{"level":"warn","ts":"2026-02-27T20:21:39.52991Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:21:39.529986Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-27T20:21:39.530045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T20:21:39.530056Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-27T20:21:39.530072Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-27T20:21:39.530638Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-27T20:21:39.530705Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-27T20:21:39.540405Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.531051ms"} {"level":"info","ts":"2026-02-27T20:21:39.549114Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-27T20:21:39.549268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T20:21:39.549337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-27T20:21:39.549357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T20:21:39.549367Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-27T20:21:39.549406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-27T20:21:39.562992Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T20:21:39.565175Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T20:21:39.568068Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-27T20:21:39.571754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T20:21:39.571909Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-27T20:21:39.571979Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-27T20:21:39.571989Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-27T20:21:39.574184Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T20:21:39.574669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-27T20:21:39.574849Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-27T20:21:39.576763Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-27T20:21:39.576971Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-27T20:21:39.577002Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-27T20:21:39.577034Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T20:21:39.577095Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T20:21:40.249597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T20:21:40.249671Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T20:21:40.249705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-27T20:21:40.249725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-27T20:21:40.249737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-27T20:21:40.249752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-27T20:21:40.249766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-27T20:21:40.315191Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T20:21:40.31525Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:21:40.315534Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:21:40.316043Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T20:21:40.316221Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T20:21:40.316532Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T20:21:40.316931Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:21:40.317056Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:21:40.317094Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T20:21:40.319506Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-27T20:21:40.320952Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T20:21:41.622427Z","caller":"traceutil/trace.go:171","msg":"trace[295895241] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"103.010662ms","start":"2026-02-27T20:21:41.519377Z","end":"2026-02-27T20:21:41.622388Z","steps":["trace[295895241] 'process raft request' (duration: 102.86018ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.731546Z","caller":"traceutil/trace.go:171","msg":"trace[232928017] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"132.943054ms","start":"2026-02-27T20:21:41.598581Z","end":"2026-02-27T20:21:41.731524Z","steps":["trace[232928017] 'process raft request' (duration: 132.696221ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.731571Z","caller":"traceutil/trace.go:171","msg":"trace[1742234920] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"132.777651ms","start":"2026-02-27T20:21:41.598767Z","end":"2026-02-27T20:21:41.731544Z","steps":["trace[1742234920] 'process raft request' (duration: 132.638679ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.731769Z","caller":"traceutil/trace.go:171","msg":"trace[72992225] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"133.019904ms","start":"2026-02-27T20:21:41.598731Z","end":"2026-02-27T20:21:41.731751Z","steps":["trace[72992225] 'process raft request' (duration: 132.643979ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.731792Z","caller":"traceutil/trace.go:171","msg":"trace[2128522773] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"109.260044ms","start":"2026-02-27T20:21:41.622377Z","end":"2026-02-27T20:21:41.731637Z","steps":["trace[2128522773] 'read index received' (duration: 108.852638ms)","trace[2128522773] 'applied index is now lower than readState.Index' (duration: 406.096µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:41.73192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.39088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:21:41.731923Z","caller":"traceutil/trace.go:171","msg":"trace[1059215308] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"124.898374ms","start":"2026-02-27T20:21:41.606974Z","end":"2026-02-27T20:21:41.731873Z","steps":["trace[1059215308] 'process raft request' (duration: 124.573749ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.731952Z","caller":"traceutil/trace.go:171","msg":"trace[20554713] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"132.936303ms","start":"2026-02-27T20:21:41.598951Z","end":"2026-02-27T20:21:41.731887Z","steps":["trace[20554713] 'process raft request' (duration: 132.530788ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.732004Z","caller":"traceutil/trace.go:171","msg":"trace[794801064] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"133.533472ms","start":"2026-02-27T20:21:41.598448Z","end":"2026-02-27T20:21:41.731982Z","steps":["trace[794801064] 'agreement among raft nodes before linearized reading' (duration: 133.361529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:41.732029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.145686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:21:41.73207Z","caller":"traceutil/trace.go:171","msg":"trace[2136309321] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:0; response_revision:11; }","duration":"135.215708ms","start":"2026-02-27T20:21:41.596841Z","end":"2026-02-27T20:21:41.732056Z","steps":["trace[2136309321] 'agreement among raft nodes before linearized reading' (duration: 135.172127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:41.73199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.103332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:21:41.732225Z","caller":"traceutil/trace.go:171","msg":"trace[59894663] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"134.365395ms","start":"2026-02-27T20:21:41.597838Z","end":"2026-02-27T20:21:41.732203Z","steps":["trace[59894663] 'agreement among raft nodes before linearized reading' (duration: 134.106581ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.732267Z","caller":"traceutil/trace.go:171","msg":"trace[642354267] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"124.798343ms","start":"2026-02-27T20:21:41.607458Z","end":"2026-02-27T20:21:41.732256Z","steps":["trace[642354267] 'process raft request' (duration: 124.135023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:41.732338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.832422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T20:21:41.732375Z","caller":"traceutil/trace.go:171","msg":"trace[433029224] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"155.895532ms","start":"2026-02-27T20:21:41.576471Z","end":"2026-02-27T20:21:41.732366Z","steps":["trace[433029224] 'agreement among raft nodes before linearized reading' (duration: 155.844422ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.732494Z","caller":"traceutil/trace.go:171","msg":"trace[1908873482] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"133.576243ms","start":"2026-02-27T20:21:41.598878Z","end":"2026-02-27T20:21:41.732454Z","steps":["trace[1908873482] 'process raft request' (duration: 132.556518ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:41.732659Z","caller":"traceutil/trace.go:171","msg":"trace[1528081645] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"133.292719ms","start":"2026-02-27T20:21:41.599354Z","end":"2026-02-27T20:21:41.732647Z","steps":["trace[1528081645] 'process raft request' (duration: 132.159972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:41.732574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.710271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-02-27T20:21:41.732876Z","caller":"traceutil/trace.go:171","msg":"trace[259225254] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"108.044556ms","start":"2026-02-27T20:21:41.624819Z","end":"2026-02-27T20:21:41.732863Z","steps":["trace[259225254] 'agreement among raft nodes before linearized reading' (duration: 107.68982ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:44.535991Z","caller":"traceutil/trace.go:171","msg":"trace[1299825613] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:258; }","duration":"136.881166ms","start":"2026-02-27T20:21:44.39907Z","end":"2026-02-27T20:21:44.535951Z","steps":["trace[1299825613] 'read index received' (duration: 58.105722ms)","trace[1299825613] 'applied index is now lower than readState.Index' (duration: 78.773474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:44.536254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.14667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:21:44.536302Z","caller":"traceutil/trace.go:171","msg":"trace[445486866] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:253; }","duration":"137.253201ms","start":"2026-02-27T20:21:44.399035Z","end":"2026-02-27T20:21:44.536288Z","steps":["trace[445486866] 'agreement among raft nodes before linearized reading' (duration: 137.110759ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:44.536531Z","caller":"traceutil/trace.go:171","msg":"trace[1981813515] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"142.803192ms","start":"2026-02-27T20:21:44.393651Z","end":"2026-02-27T20:21:44.536454Z","steps":["trace[1981813515] 'process raft request' (duration: 63.674093ms)","trace[1981813515] 'compare' (duration: 78.440089ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:44.649637Z","caller":"traceutil/trace.go:171","msg":"trace[467295649] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"105.535556ms","start":"2026-02-27T20:21:44.544046Z","end":"2026-02-27T20:21:44.649582Z","steps":["trace[467295649] 'process raft request' (duration: 104.603682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:45.957675Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675041180606343,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T20:21:46.249394Z","caller":"traceutil/trace.go:171","msg":"trace[981932146] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"792.423083ms","start":"2026-02-27T20:21:45.456943Z","end":"2026-02-27T20:21:46.249366Z","steps":["trace[981932146] 'process raft request' (duration: 792.297411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:46.249963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.45692Z","time spent":"792.622996ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:21:47.699415Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449574069s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T20:21:48.736258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036281117s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:21:48.736683Z","caller":"traceutil/trace.go:171","msg":"trace[1228984608] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"2.95502557s","start":"2026-02-27T20:21:45.78164Z","end":"2026-02-27T20:21:48.736666Z","steps":["trace[1228984608] 'process raft request' (duration: 2.954826347s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.736813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.781614Z","time spent":"2.955162692s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:48.736919Z","caller":"traceutil/trace.go:171","msg":"trace[382697341] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"2.955133541s","start":"2026-02-27T20:21:45.78177Z","end":"2026-02-27T20:21:48.736904Z","steps":["trace[382697341] 'process raft request' (duration: 2.954726136s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:48.736935Z","caller":"traceutil/trace.go:171","msg":"trace[2140754974] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"3.173999857s","start":"2026-02-27T20:21:45.562902Z","end":"2026-02-27T20:21:48.736901Z","steps":["trace[2140754974] 'process raft request' (duration: 3.17348539s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.781761Z","time spent":"2.955202052s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:48.737014Z","caller":"traceutil/trace.go:171","msg":"trace[1062235013] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"3.277023142s","start":"2026-02-27T20:21:45.459943Z","end":"2026-02-27T20:21:48.736966Z","steps":["trace[1062235013] 'process raft request' (duration: 2.239789941s)","trace[1062235013] 'compare' (duration: 1.036139425s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:48.737099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.459924Z","time spent":"3.277136345s","remote":"127.0.0.1:45680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":420,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:21:48.737103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.562867Z","time spent":"3.17416029s","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:48.737115Z","caller":"traceutil/trace.go:171","msg":"trace[1585807844] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"2.956050144s","start":"2026-02-27T20:21:45.781054Z","end":"2026-02-27T20:21:48.737104Z","steps":["trace[1585807844] 'process raft request' (duration: 2.955384745s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:48.737201Z","caller":"traceutil/trace.go:171","msg":"trace[919495420] transaction","detail":"{read_only:false; number_of_response:0; response_revision:292; }","duration":"2.955252042s","start":"2026-02-27T20:21:45.781935Z","end":"2026-02-27T20:21:48.737188Z","steps":["trace[919495420] 'process raft request' (duration: 2.954623934s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.781036Z","time spent":"2.956168626s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:21:48.73728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.781912Z","time spent":"2.955335044s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:48.736712Z","caller":"traceutil/trace.go:171","msg":"trace[1231192167] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"2.482769553s","start":"2026-02-27T20:21:46.253925Z","end":"2026-02-27T20:21:48.736695Z","steps":["trace[1231192167] 'process raft request' (duration: 2.482718283s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:46.253902Z","time spent":"2.483512304s","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:48.737451Z","caller":"traceutil/trace.go:171","msg":"trace[149787244] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"2.955368745s","start":"2026-02-27T20:21:45.782063Z","end":"2026-02-27T20:21:48.737432Z","steps":["trace[149787244] 'process raft request' (duration: 2.954529383s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.782054Z","time spent":"2.955486096s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:21:48.73761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.280532483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-27T20:21:48.737506Z","caller":"traceutil/trace.go:171","msg":"trace[1216801131] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:296; }","duration":"3.280432152s","start":"2026-02-27T20:21:45.457062Z","end":"2026-02-27T20:21:48.737494Z","steps":["trace[1216801131] 'read index received' (duration: 792.324132ms)","trace[1216801131] 'applied index is now lower than readState.Index' (duration: 2.48810642s)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:48.737655Z","caller":"traceutil/trace.go:171","msg":"trace[1930673170] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:294; }","duration":"3.280595525s","start":"2026-02-27T20:21:45.457046Z","end":"2026-02-27T20:21:48.737641Z","steps":["trace[1930673170] 'agreement among raft nodes before linearized reading' (duration: 3.280483323s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.457034Z","time spent":"3.280704056s","remote":"127.0.0.1:45708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-02-27T20:21:48.73784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.985017406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:21:48.737862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.277954265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:21:48.737895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.24677789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:21:48.737934Z","caller":"traceutil/trace.go:171","msg":"trace[1341920117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:294; }","duration":"3.246815101s","start":"2026-02-27T20:21:45.491106Z","end":"2026-02-27T20:21:48.737921Z","steps":["trace[1341920117] 'agreement among raft nodes before linearized reading' (duration: 3.24675063s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:48.737938Z","caller":"traceutil/trace.go:171","msg":"trace[229319008] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:294; }","duration":"3.278056998s","start":"2026-02-27T20:21:45.459861Z","end":"2026-02-27T20:21:48.737917Z","steps":["trace[229319008] 'agreement among raft nodes before linearized reading' (duration: 3.277911966s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.737938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.247919737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:21:48.73797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.491091Z","time spent":"3.246872871s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T20:21:48.737984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.459854Z","time spent":"3.278121398s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-02-27T20:21:48.737987Z","caller":"traceutil/trace.go:171","msg":"trace[1942951209] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:294; }","duration":"3.247984427s","start":"2026-02-27T20:21:45.489989Z","end":"2026-02-27T20:21:48.737973Z","steps":["trace[1942951209] 'agreement among raft nodes before linearized reading' (duration: 3.247901836s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.738031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.489982Z","time spent":"3.248037798s","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" "} {"level":"warn","ts":"2026-02-27T20:21:48.738051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.855198081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:21:48.738129Z","caller":"traceutil/trace.go:171","msg":"trace[1389096652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:294; }","duration":"2.855301534s","start":"2026-02-27T20:21:45.882819Z","end":"2026-02-27T20:21:48.73812Z","steps":["trace[1389096652] 'agreement among raft nodes before linearized reading' (duration: 2.855162652s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:48.737903Z","caller":"traceutil/trace.go:171","msg":"trace[41847823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:294; }","duration":"2.985121438s","start":"2026-02-27T20:21:45.752771Z","end":"2026-02-27T20:21:48.737892Z","steps":["trace[41847823] 'agreement among raft nodes before linearized reading' (duration: 2.985003907s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.738197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.752748Z","time spent":"2.985441522s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:21:48.738214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:45.882806Z","time spent":"2.855394255s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T20:21:48.738626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.197242942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:21:48.738735Z","caller":"traceutil/trace.go:171","msg":"trace[558335763] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:294; }","duration":"2.198065955s","start":"2026-02-27T20:21:46.540628Z","end":"2026-02-27T20:21:48.738693Z","steps":["trace[558335763] 'agreement among raft nodes before linearized reading' (duration: 2.197160172s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:48.738783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:46.540607Z","time spent":"2.198161946s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T20:21:49.144964Z","caller":"traceutil/trace.go:171","msg":"trace[1107007405] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"387.663703ms","start":"2026-02-27T20:21:48.757246Z","end":"2026-02-27T20:21:49.144909Z","steps":["trace[1107007405] 'process raft request' (duration: 387.48354ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:49.144959Z","caller":"traceutil/trace.go:171","msg":"trace[2103426075] linearizableReadLoop","detail":"{readStateIndex:316; appliedIndex:312; }","duration":"386.11556ms","start":"2026-02-27T20:21:48.758784Z","end":"2026-02-27T20:21:49.1449Z","steps":["trace[2103426075] 'read index received' (duration: 370.957011ms)","trace[2103426075] 'applied index is now lower than readState.Index' (duration: 15.157259ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:49.145121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.307073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:21:49.145158Z","caller":"traceutil/trace.go:171","msg":"trace[1103608606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:308; }","duration":"386.396545ms","start":"2026-02-27T20:21:48.758749Z","end":"2026-02-27T20:21:49.145146Z","steps":["trace[1103608606] 'agreement among raft nodes before linearized reading' (duration: 386.302354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:49.14519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.758729Z","time spent":"386.454176ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T20:21:49.145273Z","caller":"traceutil/trace.go:171","msg":"trace[1896998979] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"383.95565ms","start":"2026-02-27T20:21:48.761299Z","end":"2026-02-27T20:21:49.145255Z","steps":["trace[1896998979] 'process raft request' (duration: 383.499482ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:49.145302Z","caller":"traceutil/trace.go:171","msg":"trace[1091872770] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"393.976305ms","start":"2026-02-27T20:21:48.751292Z","end":"2026-02-27T20:21:49.145268Z","steps":["trace[1091872770] 'process raft request' (duration: 393.333725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:49.145452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.75128Z","time spent":"394.124157ms","remote":"127.0.0.1:45538","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":176,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:49.145495Z","caller":"traceutil/trace.go:171","msg":"trace[250532947] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"378.054504ms","start":"2026-02-27T20:21:48.767431Z","end":"2026-02-27T20:21:49.145486Z","steps":["trace[250532947] 'process raft request' (duration: 377.413374ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:49.144973Z","caller":"traceutil/trace.go:171","msg":"trace[390452469] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"393.68041ms","start":"2026-02-27T20:21:48.751245Z","end":"2026-02-27T20:21:49.144925Z","steps":["trace[390452469] 'process raft request' (duration: 378.500211ms)","trace[390452469] 'compare' (duration: 14.669752ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:49.145597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.761284Z","time spent":"384.035441ms","remote":"127.0.0.1:45524","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4182,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:21:49.145608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.751237Z","time spent":"394.34566ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T20:21:49.14559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.757211Z","time spent":"387.835817ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:21:49.145767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.767421Z","time spent":"378.102025ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:21:49.146004Z","caller":"traceutil/trace.go:171","msg":"trace[1963277949] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"393.185614ms","start":"2026-02-27T20:21:48.751694Z","end":"2026-02-27T20:21:49.14488Z","steps":["trace[1963277949] 'process raft request' (duration: 392.988791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:49.146106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:48.751689Z","time spent":"394.389391ms","remote":"127.0.0.1:45654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":435,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:21:51.568737Z","caller":"traceutil/trace.go:171","msg":"trace[1886980607] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"116.70679ms","start":"2026-02-27T20:21:51.452007Z","end":"2026-02-27T20:21:51.568714Z","steps":["trace[1886980607] 'process raft request' (duration: 116.476867ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:51.890978Z","caller":"traceutil/trace.go:171","msg":"trace[828552452] linearizableReadLoop","detail":"{readStateIndex:377; appliedIndex:374; }","duration":"139.746131ms","start":"2026-02-27T20:21:51.751209Z","end":"2026-02-27T20:21:51.890955Z","steps":["trace[828552452] 'read index received' (duration: 8.35064ms)","trace[828552452] 'applied index is now lower than readState.Index' (duration: 131.394571ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:51.891137Z","caller":"traceutil/trace.go:171","msg":"trace[1210321549] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"145.282291ms","start":"2026-02-27T20:21:51.745832Z","end":"2026-02-27T20:21:51.891115Z","steps":["trace[1210321549] 'process raft request' (duration: 145.069538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:51.891213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.979174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-02-27T20:21:51.891284Z","caller":"traceutil/trace.go:171","msg":"trace[873558039] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:367; }","duration":"140.090966ms","start":"2026-02-27T20:21:51.751177Z","end":"2026-02-27T20:21:51.891268Z","steps":["trace[873558039] 'agreement among raft nodes before linearized reading' (duration: 139.963314ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:51.89117Z","caller":"traceutil/trace.go:171","msg":"trace[2121720024] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"146.766782ms","start":"2026-02-27T20:21:51.744371Z","end":"2026-02-27T20:21:51.891138Z","steps":["trace[2121720024] 'process raft request' (duration: 80.538189ms)","trace[2121720024] 'compare' (duration: 65.869278ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:52.16409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.18628ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:21:52.164199Z","caller":"traceutil/trace.go:171","msg":"trace[2093131884] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:378; }","duration":"221.873998ms","start":"2026-02-27T20:21:51.942311Z","end":"2026-02-27T20:21:52.164185Z","steps":["trace[2093131884] 'read index received' (duration: 11.07798ms)","trace[2093131884] 'applied index is now lower than readState.Index' (duration: 210.795188ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:52.164217Z","caller":"traceutil/trace.go:171","msg":"trace[1723096852] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"259.447878ms","start":"2026-02-27T20:21:51.904744Z","end":"2026-02-27T20:21:52.164192Z","steps":["trace[1723096852] 'process raft request' (duration: 90.098716ms)","trace[1723096852] 'compare' (duration: 169.034107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:52.164276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.966449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-02-27T20:21:52.164441Z","caller":"traceutil/trace.go:171","msg":"trace[1151633342] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:371; }","duration":"222.166361ms","start":"2026-02-27T20:21:51.942267Z","end":"2026-02-27T20:21:52.164433Z","steps":["trace[1151633342] 'agreement among raft nodes before linearized reading' (duration: 221.954308ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:52.164634Z","caller":"traceutil/trace.go:171","msg":"trace[577865262] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"206.717789ms","start":"2026-02-27T20:21:51.957907Z","end":"2026-02-27T20:21:52.164624Z","steps":["trace[577865262] 'process raft request' (duration: 206.588637ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:52.164624Z","caller":"traceutil/trace.go:171","msg":"trace[1220572335] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"197.806531ms","start":"2026-02-27T20:21:51.966797Z","end":"2026-02-27T20:21:52.164604Z","steps":["trace[1220572335] 'process raft request' (duration: 197.76412ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:52.164727Z","caller":"traceutil/trace.go:171","msg":"trace[338481099] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"206.84182ms","start":"2026-02-27T20:21:51.957879Z","end":"2026-02-27T20:21:52.16472Z","steps":["trace[338481099] 'process raft request' (duration: 206.530986ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:52.164923Z","caller":"traceutil/trace.go:171","msg":"trace[2132971921] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"205.3418ms","start":"2026-02-27T20:21:51.959572Z","end":"2026-02-27T20:21:52.164914Z","steps":["trace[2132971921] 'process raft request' (duration: 204.953414ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:53.705176Z","caller":"traceutil/trace.go:171","msg":"trace[1180938862] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"175.467573ms","start":"2026-02-27T20:21:53.529681Z","end":"2026-02-27T20:21:53.705149Z","steps":["trace[1180938862] 'process raft request' (duration: 175.308341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.028433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.138555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:21:55.028622Z","caller":"traceutil/trace.go:171","msg":"trace[18619234] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"1.276270654s","start":"2026-02-27T20:21:53.752321Z","end":"2026-02-27T20:21:55.028592Z","steps":["trace[18619234] 'read index received' (duration: 483.764694ms)","trace[18619234] 'applied index is now lower than readState.Index' (duration: 792.50402ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:55.028679Z","caller":"traceutil/trace.go:171","msg":"trace[358872158] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"1.312206178s","start":"2026-02-27T20:21:53.716432Z","end":"2026-02-27T20:21:55.028638Z","steps":["trace[358872158] 'process raft request' (duration: 519.771839ms)","trace[358872158] 'compare' (duration: 792.004303ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:21:55.028862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.276547497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T20:21:55.028906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:53.716415Z","time spent":"1.31236215s","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:21:55.028936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.801936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:21:55.028976Z","caller":"traceutil/trace.go:171","msg":"trace[1540598020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"1.276674429s","start":"2026-02-27T20:21:53.752257Z","end":"2026-02-27T20:21:55.028931Z","steps":["trace[1540598020] 'agreement among raft nodes before linearized reading' (duration: 1.276483347s)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:55.02902Z","caller":"traceutil/trace.go:171","msg":"trace[1573413015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:396; }","duration":"255.912838ms","start":"2026-02-27T20:21:54.773088Z","end":"2026-02-27T20:21:55.029Z","steps":["trace[1573413015] 'agreement among raft nodes before linearized reading' (duration: 255.723366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.029096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:53.752228Z","time spent":"1.276856401s","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:21:55.029237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.083195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:21:55.029372Z","caller":"traceutil/trace.go:171","msg":"trace[1882090859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:396; }","duration":"257.356289ms","start":"2026-02-27T20:21:54.771991Z","end":"2026-02-27T20:21:55.029347Z","steps":["trace[1882090859] 'agreement among raft nodes before linearized reading' (duration: 256.788631ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.424273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.328435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:21:55.424594Z","caller":"traceutil/trace.go:171","msg":"trace[1237397103] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"390.506961ms","start":"2026-02-27T20:21:55.034053Z","end":"2026-02-27T20:21:55.42456Z","steps":["trace[1237397103] 'process raft request' (duration: 260.803041ms)","trace[1237397103] 'compare' (duration: 129.147023ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:55.424753Z","caller":"traceutil/trace.go:171","msg":"trace[1893534762] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"387.937904ms","start":"2026-02-27T20:21:55.036772Z","end":"2026-02-27T20:21:55.42471Z","steps":["trace[1893534762] 'process raft request' (duration: 387.780112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.424808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.381037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:21:55.424811Z","caller":"traceutil/trace.go:171","msg":"trace[556953355] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"389.465336ms","start":"2026-02-27T20:21:55.035303Z","end":"2026-02-27T20:21:55.424768Z","steps":["trace[556953355] 'process raft request' (duration: 389.130002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.424818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:55.034021Z","time spent":"390.696994ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:21:55.424938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:55.036748Z","time spent":"388.098436ms","remote":"127.0.0.1:45774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2903,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:21:55.424623Z","caller":"traceutil/trace.go:171","msg":"trace[515045990] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:408; }","duration":"387.180564ms","start":"2026-02-27T20:21:55.037424Z","end":"2026-02-27T20:21:55.424604Z","steps":["trace[515045990] 'read index received' (duration: 257.537235ms)","trace[515045990] 'applied index is now lower than readState.Index' (duration: 129.642299ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:55.425134Z","caller":"traceutil/trace.go:171","msg":"trace[1770206672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"387.446717ms","start":"2026-02-27T20:21:55.03739Z","end":"2026-02-27T20:21:55.424837Z","steps":["trace[1770206672] 'agreement among raft nodes before linearized reading' (duration: 387.269125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.425018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:55.035276Z","time spent":"389.641629ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:21:55.425409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:21:55.037355Z","time spent":"387.852003ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T20:21:55.425542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.732894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-27T20:21:55.426449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.512471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T20:21:55.426483Z","caller":"traceutil/trace.go:171","msg":"trace[278648148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:399; }","duration":"166.713829ms","start":"2026-02-27T20:21:55.259747Z","end":"2026-02-27T20:21:55.426461Z","steps":["trace[278648148] 'agreement among raft nodes before linearized reading' (duration: 165.730614ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:21:55.426662Z","caller":"traceutil/trace.go:171","msg":"trace[2093754653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:399; }","duration":"165.590222ms","start":"2026-02-27T20:21:55.260899Z","end":"2026-02-27T20:21:55.426489Z","steps":["trace[2093754653] 'agreement among raft nodes before linearized reading' (duration: 164.721569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:55.730467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.191644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:21:55.730574Z","caller":"traceutil/trace.go:171","msg":"trace[1087438865] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"297.125959ms","start":"2026-02-27T20:21:55.433429Z","end":"2026-02-27T20:21:55.730555Z","steps":["trace[1087438865] 'process raft request' (duration: 188.764524ms)","trace[1087438865] 'compare' (duration: 108.065411ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:59.604579Z","caller":"traceutil/trace.go:171","msg":"trace[1820492513] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"107.480482ms","start":"2026-02-27T20:21:59.497074Z","end":"2026-02-27T20:21:59.604554Z","steps":["trace[1820492513] 'read index received' (duration: 107.230298ms)","trace[1820492513] 'applied index is now lower than readState.Index' (duration: 249.344µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:21:59.604708Z","caller":"traceutil/trace.go:171","msg":"trace[809648398] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"148.016946ms","start":"2026-02-27T20:21:59.456664Z","end":"2026-02-27T20:21:59.604681Z","steps":["trace[809648398] 'process raft request' (duration: 147.722852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:59.604787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.691075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-27T20:21:59.605636Z","caller":"traceutil/trace.go:171","msg":"trace[716674465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"108.575887ms","start":"2026-02-27T20:21:59.497034Z","end":"2026-02-27T20:21:59.60561Z","steps":["trace[716674465] 'agreement among raft nodes before linearized reading' (duration: 107.666614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:21:59.605718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.509792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-27T20:21:59.605766Z","caller":"traceutil/trace.go:171","msg":"trace[1889336632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"102.581412ms","start":"2026-02-27T20:21:59.503172Z","end":"2026-02-27T20:21:59.605754Z","steps":["trace[1889336632] 'agreement among raft nodes before linearized reading' (duration: 102.497971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:14.801133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.807688ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:22:14.80143Z","caller":"traceutil/trace.go:171","msg":"trace[668061063] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"172.896239ms","start":"2026-02-27T20:22:14.628497Z","end":"2026-02-27T20:22:14.801393Z","steps":["trace[668061063] 'process raft request' (duration: 172.794938ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:14.801558Z","caller":"traceutil/trace.go:171","msg":"trace[657716161] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"172.94147ms","start":"2026-02-27T20:22:14.628491Z","end":"2026-02-27T20:22:14.801433Z","steps":["trace[657716161] 'process raft request' (duration: 60.765906ms)","trace[657716161] 'compare' (duration: 111.675806ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:17.062251Z","caller":"traceutil/trace.go:171","msg":"trace[1637952025] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"279.686486ms","start":"2026-02-27T20:22:16.782543Z","end":"2026-02-27T20:22:17.062229Z","steps":["trace[1637952025] 'process raft request' (duration: 279.549792ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:20.103778Z","caller":"traceutil/trace.go:171","msg":"trace[998188378] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:558; }","duration":"115.770223ms","start":"2026-02-27T20:22:19.987971Z","end":"2026-02-27T20:22:20.103742Z","steps":["trace[998188378] 'read index received' (duration: 80.775097ms)","trace[998188378] 'applied index is now lower than readState.Index' (duration: 34.993406ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:20.103833Z","caller":"traceutil/trace.go:171","msg":"trace[2022270850] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"117.902716ms","start":"2026-02-27T20:22:19.985909Z","end":"2026-02-27T20:22:20.103812Z","steps":["trace[2022270850] 'process raft request' (duration: 82.746036ms)","trace[2022270850] 'compare' (duration: 34.80595ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:20.103836Z","caller":"traceutil/trace.go:171","msg":"trace[1428504071] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"117.867725ms","start":"2026-02-27T20:22:19.985927Z","end":"2026-02-27T20:22:20.103794Z","steps":["trace[1428504071] 'process raft request' (duration: 117.727491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.104016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.008131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-02-27T20:22:20.104078Z","caller":"traceutil/trace.go:171","msg":"trace[857972044] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:542; }","duration":"116.124904ms","start":"2026-02-27T20:22:19.987935Z","end":"2026-02-27T20:22:20.10406Z","steps":["trace[857972044] 'agreement among raft nodes before linearized reading' (duration: 115.954099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.952628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.587587ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:22:20.952752Z","caller":"traceutil/trace.go:171","msg":"trace[659786140] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:542; }","duration":"598.740501ms","start":"2026-02-27T20:22:20.353994Z","end":"2026-02-27T20:22:20.952734Z","steps":["trace[659786140] 'range keys from in-memory index tree' (duration: 598.571296ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.953079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.775666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T20:22:20.953135Z","caller":"traceutil/trace.go:171","msg":"trace[1078467185] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"844.782403ms","start":"2026-02-27T20:22:20.108341Z","end":"2026-02-27T20:22:20.953124Z","steps":["trace[1078467185] 'process raft request' (duration: 322.943355ms)","trace[1078467185] 'compare' (duration: 521.636072ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:22:20.95318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.10831Z","time spent":"844.850615ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:22:20.954155Z","caller":"traceutil/trace.go:171","msg":"trace[846397699] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"845.442651ms","start":"2026-02-27T20:22:20.108689Z","end":"2026-02-27T20:22:20.954131Z","steps":["trace[846397699] 'process raft request' (duration: 845.216165ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:20.954164Z","caller":"traceutil/trace.go:171","msg":"trace[2030850386] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:560; }","duration":"760.908187ms","start":"2026-02-27T20:22:20.193236Z","end":"2026-02-27T20:22:20.954144Z","steps":["trace[2030850386] 'read index received' (duration: 238.185424ms)","trace[2030850386] 'applied index is now lower than readState.Index' (duration: 522.721943ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:20.954212Z","caller":"traceutil/trace.go:171","msg":"trace[867457442] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"845.357629ms","start":"2026-02-27T20:22:20.108796Z","end":"2026-02-27T20:22:20.954154Z","steps":["trace[867457442] 'process raft request' (duration: 845.220955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.954389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.85748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:22:20.954376Z","caller":"traceutil/trace.go:171","msg":"trace[479741229] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"844.814703ms","start":"2026-02-27T20:22:20.10952Z","end":"2026-02-27T20:22:20.954334Z","steps":["trace[479741229] 'process raft request' (duration: 844.545875ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:20.954441Z","caller":"traceutil/trace.go:171","msg":"trace[597902114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"201.963894ms","start":"2026-02-27T20:22:20.752465Z","end":"2026-02-27T20:22:20.954429Z","steps":["trace[597902114] 'agreement among raft nodes before linearized reading' (duration: 201.87299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.954251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.108661Z","time spent":"845.535664ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:22:20.954573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.30615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-02-27T20:22:20.954681Z","caller":"traceutil/trace.go:171","msg":"trace[2010928604] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"842.27156ms","start":"2026-02-27T20:22:20.112016Z","end":"2026-02-27T20:22:20.954288Z","steps":["trace[2010928604] 'process raft request' (duration: 842.088684ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:20.954691Z","caller":"traceutil/trace.go:171","msg":"trace[209683119] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:547; }","duration":"761.475894ms","start":"2026-02-27T20:22:20.193189Z","end":"2026-02-27T20:22:20.954664Z","steps":["trace[209683119] 'agreement among raft nodes before linearized reading' (duration: 761.205156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:20.954739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.19317Z","time spent":"761.559777ms","remote":"127.0.0.1:45442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-27T20:22:20.954779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.109499Z","time spent":"844.967369ms","remote":"127.0.0.1:45762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:22:20.954782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.112006Z","time spent":"842.705053ms","remote":"127.0.0.1:45526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T20:22:20.954911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:20.108783Z","time spent":"845.514093ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T20:22:23.867493Z","caller":"traceutil/trace.go:171","msg":"trace[155625019] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"121.919841ms","start":"2026-02-27T20:22:23.745551Z","end":"2026-02-27T20:22:23.867471Z","steps":["trace[155625019] 'read index received' (duration: 121.755586ms)","trace[155625019] 'applied index is now lower than readState.Index' (duration: 163.415µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:23.867546Z","caller":"traceutil/trace.go:171","msg":"trace[1347760655] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"127.739946ms","start":"2026-02-27T20:22:23.739772Z","end":"2026-02-27T20:22:23.867512Z","steps":["trace[1347760655] 'process raft request' (duration: 127.581781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:23.867638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.064335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-27T20:22:23.86767Z","caller":"traceutil/trace.go:171","msg":"trace[1935977425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"122.141527ms","start":"2026-02-27T20:22:23.74552Z","end":"2026-02-27T20:22:23.867661Z","steps":["trace[1935977425] 'agreement among raft nodes before linearized reading' (duration: 122.064345ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:24.082786Z","caller":"traceutil/trace.go:171","msg":"trace[1257750120] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"210.797278ms","start":"2026-02-27T20:22:23.871963Z","end":"2026-02-27T20:22:24.082761Z","steps":["trace[1257750120] 'process raft request' (duration: 210.652084ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:22:24.445629Z","caller":"traceutil/trace.go:171","msg":"trace[1708401691] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"547.083222ms","start":"2026-02-27T20:22:23.898517Z","end":"2026-02-27T20:22:24.4456Z","steps":["trace[1708401691] 'read index received' (duration: 184.303237ms)","trace[1708401691] 'applied index is now lower than readState.Index' (duration: 362.779115ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T20:22:24.445654Z","caller":"traceutil/trace.go:171","msg":"trace[469964098] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"572.399771ms","start":"2026-02-27T20:22:23.873224Z","end":"2026-02-27T20:22:24.445624Z","steps":["trace[469964098] 'process raft request' (duration: 529.925484ms)","trace[469964098] 'compare' (duration: 42.315523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T20:22:24.445797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.243467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-27T20:22:24.44583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:23.8732Z","time spent":"572.515724ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T20:22:24.44589Z","caller":"traceutil/trace.go:171","msg":"trace[1675205801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:562; }","duration":"547.396441ms","start":"2026-02-27T20:22:23.898471Z","end":"2026-02-27T20:22:24.445867Z","steps":["trace[1675205801] 'agreement among raft nodes before linearized reading' (duration: 547.218906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:24.445981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:23.898452Z","time spent":"547.516174ms","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T20:22:25.341764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.487417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T20:22:25.341856Z","caller":"traceutil/trace.go:171","msg":"trace[495896995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"589.627111ms","start":"2026-02-27T20:22:24.75221Z","end":"2026-02-27T20:22:25.341837Z","steps":["trace[495896995] 'range keys from in-memory index tree' (duration: 589.430995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T20:22:25.341865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:24.700707Z","time spent":"641.150632ms","remote":"127.0.0.1:57036","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T20:22:25.341911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T20:22:24.752194Z","time spent":"589.707593ms","remote":"127.0.0.1:45340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T20:22:27.950789Z","caller":"traceutil/trace.go:171","msg":"trace[863615394] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"206.567189ms","start":"2026-02-27T20:22:27.744192Z","end":"2026-02-27T20:22:27.950759Z","steps":["trace[863615394] 'process raft request' (duration: 204.42271ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:23:49.0154Z","caller":"traceutil/trace.go:171","msg":"trace[541385639] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"171.140526ms","start":"2026-02-27T20:23:48.844145Z","end":"2026-02-27T20:23:49.015286Z","steps":["trace[541385639] 'process raft request' (duration: 171.080955ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T20:23:49.015385Z","caller":"traceutil/trace.go:171","msg":"trace[1350463919] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"171.224258ms","start":"2026-02-27T20:23:48.844118Z","end":"2026-02-27T20:23:49.015342Z","steps":["trace[1350463919] 'process raft request' (duration: 170.940413ms)"],"step_count":1}