{"level":"warn","ts":"2026-02-27T21:53:08.487221Z","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-27T21:53:08.48731Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.204: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.204:2380","--initial-cluster=instance=https://162.253.55.204:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.204:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.204: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-27T21:53:08.487388Z","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-27T21:53:08.4874Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-27T21:53:08.487417Z","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-27T21:53:08.48811Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"]} {"level":"info","ts":"2026-02-27T21:53:08.488183Z","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.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.204: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-27T21:53:08.492358Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.957237ms"} {"level":"info","ts":"2026-02-27T21:53:08.498616Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a11c20306510c1f7","cluster-id":"97da89d0cbebd2b5"} {"level":"info","ts":"2026-02-27T21:53:08.498688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T21:53:08.498711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 0"} {"level":"info","ts":"2026-02-27T21:53:08.498725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a11c20306510c1f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T21:53:08.498738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became follower at term 1"} {"level":"info","ts":"2026-02-27T21:53:08.498778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"warn","ts":"2026-02-27T21:53:08.505528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T21:53:08.506957Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T21:53:08.508273Z","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-27T21:53:08.51049Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a11c20306510c1f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T21:53:08.510652Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a11c20306510c1f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T21:53:08.510839Z","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-27T21:53:08.510912Z","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-27T21:53:08.510923Z","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-27T21:53:08.516612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 switched to configuration voters=(11609189331680412151)"} {"level":"info","ts":"2026-02-27T21:53:08.517186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","added-peer-id":"a11c20306510c1f7","added-peer-peer-urls":["https://162.253.55.204:2380"]} {"level":"info","ts":"2026-02-27T21:53:08.519858Z","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-27T21:53:08.520139Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a11c20306510c1f7","initial-advertise-peer-urls":["https://162.253.55.204:2380"],"listen-peer-urls":["https://162.253.55.204:2380"],"advertise-client-urls":["https://162.253.55.204:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.204:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T21:53:08.52018Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T21:53:08.520153Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-27T21:53:08.520246Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.204:2380"} {"level":"info","ts":"2026-02-27T21:53:09.399909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T21:53:09.39998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T21:53:09.400012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgPreVoteResp from a11c20306510c1f7 at term 1"} {"level":"info","ts":"2026-02-27T21:53:09.400031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became candidate at term 2"} {"level":"info","ts":"2026-02-27T21:53:09.400043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 received MsgVoteResp from a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-27T21:53:09.400059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a11c20306510c1f7 became leader at term 2"} {"level":"info","ts":"2026-02-27T21:53:09.400079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a11c20306510c1f7 elected leader a11c20306510c1f7 at term 2"} {"level":"info","ts":"2026-02-27T21:53:09.401138Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:53:09.402029Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a11c20306510c1f7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.204:2379]}","request-path":"/0/members/a11c20306510c1f7/attributes","cluster-id":"97da89d0cbebd2b5","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T21:53:09.402207Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:53:09.402245Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T21:53:09.402627Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T21:53:09.402666Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T21:53:09.403308Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97da89d0cbebd2b5","local-member-id":"a11c20306510c1f7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:53:09.40345Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:53:09.403491Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T21:53:09.40549Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T21:53:09.406457Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.204:2379"} {"level":"warn","ts":"2026-02-27T21:53:33.708511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.043436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:53:33.708561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.054362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T21:53:33.708595Z","caller":"traceutil/trace.go:171","msg":"trace[1335125721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"636.165149ms","start":"2026-02-27T21:53:33.072415Z","end":"2026-02-27T21:53:33.70858Z","steps":["trace[1335125721] 'range keys from in-memory index tree' (duration: 635.961654ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:33.708629Z","caller":"traceutil/trace.go:171","msg":"trace[1717432006] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:418; }","duration":"258.175674ms","start":"2026-02-27T21:53:33.450436Z","end":"2026-02-27T21:53:33.708612Z","steps":["trace[1717432006] 'range keys from in-memory index tree' (duration: 257.942969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:33.708639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:33.0724Z","time spent":"636.22915ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:53:33.708626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.440492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-27T21:53:33.708656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.188569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:53:33.708704Z","caller":"traceutil/trace.go:171","msg":"trace[801323430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"601.558475ms","start":"2026-02-27T21:53:33.107128Z","end":"2026-02-27T21:53:33.708686Z","steps":["trace[801323430] 'range keys from in-memory index tree' (duration: 601.34941ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:33.708712Z","caller":"traceutil/trace.go:171","msg":"trace[1331857633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"479.286472ms","start":"2026-02-27T21:53:33.229407Z","end":"2026-02-27T21:53:33.708693Z","steps":["trace[1331857633] 'range keys from in-memory index tree' (duration: 479.087577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:33.708745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:33.229387Z","time spent":"479.350983ms","remote":"127.0.0.1:57170","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":"warn","ts":"2026-02-27T21:53:33.708742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:33.107107Z","time spent":"601.626426ms","remote":"127.0.0.1:57170","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":"info","ts":"2026-02-27T21:53:33.86972Z","caller":"traceutil/trace.go:171","msg":"trace[515316498] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"159.213889ms","start":"2026-02-27T21:53:33.710487Z","end":"2026-02-27T21:53:33.869701Z","steps":["trace[515316498] 'read index received' (duration: 159.138887ms)","trace[515316498] 'applied index is now lower than readState.Index' (duration: 73.942µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:53:33.869817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.314861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:53:33.869857Z","caller":"traceutil/trace.go:171","msg":"trace[278294737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"159.381492ms","start":"2026-02-27T21:53:33.710467Z","end":"2026-02-27T21:53:33.869848Z","steps":["trace[278294737] 'agreement among raft nodes before linearized reading' (duration: 159.31042ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:33.905217Z","caller":"traceutil/trace.go:171","msg":"trace[396515531] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"192.094204ms","start":"2026-02-27T21:53:33.713106Z","end":"2026-02-27T21:53:33.9052Z","steps":["trace[396515531] 'process raft request' (duration: 191.923741ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:33.905368Z","caller":"traceutil/trace.go:171","msg":"trace[1391797782] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"192.211636ms","start":"2026-02-27T21:53:33.713131Z","end":"2026-02-27T21:53:33.905343Z","steps":["trace[1391797782] 'process raft request' (duration: 192.004072ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:40.919944Z","caller":"traceutil/trace.go:171","msg":"trace[1718090171] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"142.443573ms","start":"2026-02-27T21:53:40.777478Z","end":"2026-02-27T21:53:40.919922Z","steps":["trace[1718090171] 'process raft request' (duration: 142.30467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:44.194933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.815217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:53:44.194949Z","caller":"traceutil/trace.go:171","msg":"trace[500625890] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"143.031845ms","start":"2026-02-27T21:53:44.051866Z","end":"2026-02-27T21:53:44.194898Z","steps":["trace[500625890] 'process raft request' (duration: 141.471142ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:44.194885Z","caller":"traceutil/trace.go:171","msg":"trace[702863557] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"123.732125ms","start":"2026-02-27T21:53:44.071109Z","end":"2026-02-27T21:53:44.194842Z","steps":["trace[702863557] 'read index received' (duration: 122.183532ms)","trace[702863557] 'applied index is now lower than readState.Index' (duration: 1.547753ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:53:44.194986Z","caller":"traceutil/trace.go:171","msg":"trace[113947635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"123.9183ms","start":"2026-02-27T21:53:44.071058Z","end":"2026-02-27T21:53:44.194977Z","steps":["trace[113947635] 'agreement among raft nodes before linearized reading' (duration: 123.832287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:47.532682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.702436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nckgz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-27T21:53:47.532767Z","caller":"traceutil/trace.go:171","msg":"trace[634172754] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nckgz; range_end:; response_count:1; response_revision:492; }","duration":"107.82198ms","start":"2026-02-27T21:53:47.424919Z","end":"2026-02-27T21:53:47.532741Z","steps":["trace[634172754] 'range keys from in-memory index tree' (duration: 107.548793ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:47.780043Z","caller":"traceutil/trace.go:171","msg":"trace[1844190020] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"234.044088ms","start":"2026-02-27T21:53:47.545959Z","end":"2026-02-27T21:53:47.780003Z","steps":["trace[1844190020] 'process raft request' (duration: 233.799463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:48.249974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.147833ms","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-27T21:53:48.250102Z","caller":"traceutil/trace.go:171","msg":"trace[626004314] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:493; }","duration":"224.284506ms","start":"2026-02-27T21:53:48.025799Z","end":"2026-02-27T21:53:48.250084Z","steps":["trace[626004314] 'range keys from in-memory index tree' (duration: 224.135443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:48.250167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.481523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T21:53:48.250319Z","caller":"traceutil/trace.go:171","msg":"trace[1911251825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"270.793629ms","start":"2026-02-27T21:53:47.979499Z","end":"2026-02-27T21:53:48.250293Z","steps":["trace[1911251825] 'range keys from in-memory index tree' (duration: 270.243638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:48.250479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.629047ms","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-27T21:53:48.250619Z","caller":"traceutil/trace.go:171","msg":"trace[1673451409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"265.935863ms","start":"2026-02-27T21:53:47.984665Z","end":"2026-02-27T21:53:48.250601Z","steps":["trace[1673451409] 'range keys from in-memory index tree' (duration: 265.159017ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:48.383321Z","caller":"traceutil/trace.go:171","msg":"trace[129552739] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"128.958609ms","start":"2026-02-27T21:53:48.254334Z","end":"2026-02-27T21:53:48.383293Z","steps":["trace[129552739] 'process raft request' (duration: 128.815836ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:48.39891Z","caller":"traceutil/trace.go:171","msg":"trace[783703532] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"142.483694ms","start":"2026-02-27T21:53:48.256354Z","end":"2026-02-27T21:53:48.398838Z","steps":["trace[783703532] 'process raft request' (duration: 142.315651ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:53:48.399116Z","caller":"traceutil/trace.go:171","msg":"trace[20088760] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"142.663968ms","start":"2026-02-27T21:53:48.256413Z","end":"2026-02-27T21:53:48.399077Z","steps":["trace[20088760] 'process raft request' (duration: 142.392052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:49.573394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812184263169633,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:53:49.662305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"713.590446ms","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-27T21:53:49.662466Z","caller":"traceutil/trace.go:171","msg":"trace[1187788875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"713.730549ms","start":"2026-02-27T21:53:48.948642Z","end":"2026-02-27T21:53:49.662372Z","steps":["trace[1187788875] 'range keys from in-memory index tree' (duration: 713.401392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:49.662581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:48.94862Z","time spent":"713.942424ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T21:53:49.667992Z","caller":"traceutil/trace.go:171","msg":"trace[1455845419] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"594.94032ms","start":"2026-02-27T21:53:49.073029Z","end":"2026-02-27T21:53:49.667969Z","steps":["trace[1455845419] 'read index received' (duration: 592.411085ms)","trace[1455845419] 'applied index is now lower than readState.Index' (duration: 2.527025ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:53:49.668152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.074933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:53:49.668259Z","caller":"traceutil/trace.go:171","msg":"trace[721450841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"595.275257ms","start":"2026-02-27T21:53:49.072967Z","end":"2026-02-27T21:53:49.668243Z","steps":["trace[721450841] 'agreement among raft nodes before linearized reading' (duration: 595.095853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:49.668339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:49.072944Z","time spent":"595.3832ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:53:50.413743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.1809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:53:50.413819Z","caller":"traceutil/trace.go:171","msg":"trace[1361455869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"340.312763ms","start":"2026-02-27T21:53:50.073489Z","end":"2026-02-27T21:53:50.413802Z","steps":["trace[1361455869] 'range keys from in-memory index tree' (duration: 340.040917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:53:50.413856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:53:50.073467Z","time spent":"340.380705ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T21:54:01.400979Z","caller":"traceutil/trace.go:171","msg":"trace[1391884124] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"307.353575ms","start":"2026-02-27T21:54:01.093589Z","end":"2026-02-27T21:54:01.400943Z","steps":["trace[1391884124] 'process raft request' (duration: 307.251843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:01.401565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:01.093571Z","time spent":"307.461488ms","remote":"127.0.0.1:57072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:07.993205Z","caller":"traceutil/trace.go:171","msg":"trace[1082272987] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"180.769388ms","start":"2026-02-27T21:54:07.812415Z","end":"2026-02-27T21:54:07.993185Z","steps":["trace[1082272987] 'process raft request' (duration: 180.647956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:08.564117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.922927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:54:08.564177Z","caller":"traceutil/trace.go:171","msg":"trace[1887457075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"492.015809ms","start":"2026-02-27T21:54:08.072147Z","end":"2026-02-27T21:54:08.564163Z","steps":["trace[1887457075] 'range keys from in-memory index tree' (duration: 491.864674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:08.564205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:08.072133Z","time spent":"492.0666ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:54:08.564286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.540032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T21:54:08.564368Z","caller":"traceutil/trace.go:171","msg":"trace[737678527] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:679; }","duration":"448.660095ms","start":"2026-02-27T21:54:08.115692Z","end":"2026-02-27T21:54:08.564352Z","steps":["trace[737678527] 'range keys from in-memory index tree' (duration: 448.376579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:08.564405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:08.115675Z","time spent":"448.721406ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T21:54:08.743675Z","caller":"traceutil/trace.go:171","msg":"trace[1970903762] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"175.81735ms","start":"2026-02-27T21:54:08.567836Z","end":"2026-02-27T21:54:08.743654Z","steps":["trace[1970903762] 'process raft request' (duration: 175.452641ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:08.743727Z","caller":"traceutil/trace.go:171","msg":"trace[502497977] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"175.102125ms","start":"2026-02-27T21:54:08.568594Z","end":"2026-02-27T21:54:08.743696Z","steps":["trace[502497977] 'process raft request' (duration: 174.731416ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:08.744251Z","caller":"traceutil/trace.go:171","msg":"trace[175879763] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"176.414154ms","start":"2026-02-27T21:54:08.567823Z","end":"2026-02-27T21:54:08.744237Z","steps":["trace[175879763] 'process raft request' (duration: 175.34262ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:10.796291Z","caller":"traceutil/trace.go:171","msg":"trace[1397422721] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"131.251537ms","start":"2026-02-27T21:54:10.665017Z","end":"2026-02-27T21:54:10.796269Z","steps":["trace[1397422721] 'process raft request' (duration: 131.079732ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:12.019Z","caller":"traceutil/trace.go:171","msg":"trace[1114898010] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"261.25558ms","start":"2026-02-27T21:54:11.757724Z","end":"2026-02-27T21:54:12.018979Z","steps":["trace[1114898010] 'process raft request' (duration: 169.922978ms)","trace[1114898010] 'compare' (duration: 91.127247ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:54:12.972634Z","caller":"traceutil/trace.go:171","msg":"trace[1676475589] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"119.625272ms","start":"2026-02-27T21:54:12.852992Z","end":"2026-02-27T21:54:12.972617Z","steps":["trace[1676475589] 'read index received' (duration: 119.559111ms)","trace[1676475589] 'applied index is now lower than readState.Index' (duration: 65.331µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:54:12.972725Z","caller":"traceutil/trace.go:171","msg":"trace[1195680701] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"145.436027ms","start":"2026-02-27T21:54:12.827254Z","end":"2026-02-27T21:54:12.97269Z","steps":["trace[1195680701] 'process raft request' (duration: 145.255563ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:12.972822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.773935ms","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-27T21:54:12.972851Z","caller":"traceutil/trace.go:171","msg":"trace[142980601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:693; }","duration":"119.857448ms","start":"2026-02-27T21:54:12.852982Z","end":"2026-02-27T21:54:12.97284Z","steps":["trace[142980601] 'agreement among raft nodes before linearized reading' (duration: 119.735395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:12.972917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.897539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T21:54:12.973006Z","caller":"traceutil/trace.go:171","msg":"trace[1239025839] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:693; }","duration":"120.030082ms","start":"2026-02-27T21:54:12.852957Z","end":"2026-02-27T21:54:12.972987Z","steps":["trace[1239025839] 'agreement among raft nodes before linearized reading' (duration: 119.756405ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:16.746212Z","caller":"traceutil/trace.go:171","msg":"trace[701742265] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"206.751802ms","start":"2026-02-27T21:54:16.539095Z","end":"2026-02-27T21:54:16.745847Z","steps":["trace[701742265] 'process raft request' (duration: 201.725121ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:22.404847Z","caller":"traceutil/trace.go:171","msg":"trace[375735735] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"174.439216ms","start":"2026-02-27T21:54:22.230388Z","end":"2026-02-27T21:54:22.404827Z","steps":["trace[375735735] 'read index received' (duration: 174.325383ms)","trace[375735735] 'applied index is now lower than readState.Index' (duration: 113.043µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:54:22.404926Z","caller":"traceutil/trace.go:171","msg":"trace[373663438] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"313.674692ms","start":"2026-02-27T21:54:22.091216Z","end":"2026-02-27T21:54:22.40489Z","steps":["trace[373663438] 'process raft request' (duration: 313.494507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:22.405029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.625942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:54:22.405056Z","caller":"traceutil/trace.go:171","msg":"trace[1929683949] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:724; }","duration":"174.719064ms","start":"2026-02-27T21:54:22.23033Z","end":"2026-02-27T21:54:22.405049Z","steps":["trace[1929683949] 'agreement among raft nodes before linearized reading' (duration: 174.57333ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:22.405095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:22.09119Z","time spent":"313.816886ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:31.356957Z","caller":"traceutil/trace.go:171","msg":"trace[240215453] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"274.625569ms","start":"2026-02-27T21:54:31.082307Z","end":"2026-02-27T21:54:31.356933Z","steps":["trace[240215453] 'process raft request' (duration: 274.479526ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:31.952891Z","caller":"traceutil/trace.go:171","msg":"trace[1831069487] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"865.700856ms","start":"2026-02-27T21:54:31.087171Z","end":"2026-02-27T21:54:31.952872Z","steps":["trace[1831069487] 'read index received' (duration: 269.570579ms)","trace[1831069487] 'applied index is now lower than readState.Index' (duration: 596.129467ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:54:31.952959Z","caller":"traceutil/trace.go:171","msg":"trace[1863182255] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"868.315903ms","start":"2026-02-27T21:54:31.084611Z","end":"2026-02-27T21:54:31.952927Z","steps":["trace[1863182255] 'process raft request' (duration: 832.590098ms)","trace[1863182255] 'compare' (duration: 35.350004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:31.953026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.838649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T21:54:31.953054Z","caller":"traceutil/trace.go:171","msg":"trace[2014997698] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:750; }","duration":"865.88791ms","start":"2026-02-27T21:54:31.087157Z","end":"2026-02-27T21:54:31.953045Z","steps":["trace[2014997698] 'agreement among raft nodes before linearized reading' (duration: 865.784178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:31.953085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:31.087152Z","time spent":"865.927531ms","remote":"127.0.0.1:57170","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":"warn","ts":"2026-02-27T21:54:31.953101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:31.0846Z","time spent":"868.425946ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:54:32.361392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.040875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:54:32.361516Z","caller":"traceutil/trace.go:171","msg":"trace[386618325] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"404.384917ms","start":"2026-02-27T21:54:31.957109Z","end":"2026-02-27T21:54:32.361494Z","steps":["trace[386618325] 'process raft request' (duration: 95.889ms)","trace[386618325] 'compare' (duration: 307.774138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:32.361597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:31.957093Z","time spent":"404.463998ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:33.28966Z","caller":"traceutil/trace.go:171","msg":"trace[1019740873] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"397.337123ms","start":"2026-02-27T21:54:32.892294Z","end":"2026-02-27T21:54:33.289631Z","steps":["trace[1019740873] 'read index received' (duration: 363.387714ms)","trace[1019740873] 'applied index is now lower than readState.Index' (duration: 33.947919ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:33.289896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.585279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T21:54:33.289954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.52494ms","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-27T21:54:33.289995Z","caller":"traceutil/trace.go:171","msg":"trace[1169947692] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:752; }","duration":"397.743583ms","start":"2026-02-27T21:54:32.892236Z","end":"2026-02-27T21:54:33.289979Z","steps":["trace[1169947692] 'agreement among raft nodes before linearized reading' (duration: 397.569318ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:33.290016Z","caller":"traceutil/trace.go:171","msg":"trace[908658437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"302.646833ms","start":"2026-02-27T21:54:32.987354Z","end":"2026-02-27T21:54:33.290001Z","steps":["trace[908658437] 'agreement among raft nodes before linearized reading' (duration: 302.496499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:33.290046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:32.892206Z","time spent":"397.825065ms","remote":"127.0.0.1:57096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:54:33.290056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:32.987296Z","time spent":"302.752226ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T21:54:33.290259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.514358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:54:33.290298Z","caller":"traceutil/trace.go:171","msg":"trace[1786682961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"216.58668ms","start":"2026-02-27T21:54:33.073701Z","end":"2026-02-27T21:54:33.290287Z","steps":["trace[1786682961] 'agreement among raft nodes before linearized reading' (duration: 216.534768ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:33.289743Z","caller":"traceutil/trace.go:171","msg":"trace[327280094] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"640.396466ms","start":"2026-02-27T21:54:32.649269Z","end":"2026-02-27T21:54:33.289666Z","steps":["trace[327280094] 'process raft request' (duration: 606.340514ms)","trace[327280094] 'compare' (duration: 33.62081ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:33.290609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.811758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T21:54:33.29161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:32.649241Z","time spent":"642.281455ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:33.291628Z","caller":"traceutil/trace.go:171","msg":"trace[1162156630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:751; }","duration":"816.885756ms","start":"2026-02-27T21:54:32.474719Z","end":"2026-02-27T21:54:33.291605Z","steps":["trace[1162156630] 'range keys from in-memory index tree' (duration: 814.572416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:33.291712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:32.474699Z","time spent":"816.983248ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-27T21:54:33.625806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.968754ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:54:33.625977Z","caller":"traceutil/trace.go:171","msg":"trace[1562254582] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"264.978567ms","start":"2026-02-27T21:54:33.360981Z","end":"2026-02-27T21:54:33.62596Z","steps":["trace[1562254582] 'read index received' (duration: 48.762927ms)","trace[1562254582] 'applied index is now lower than readState.Index' (duration: 216.21466ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:54:33.626067Z","caller":"traceutil/trace.go:171","msg":"trace[2084994461] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"329.394764ms","start":"2026-02-27T21:54:33.296663Z","end":"2026-02-27T21:54:33.626058Z","steps":["trace[2084994461] 'process raft request' (duration: 329.24602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:33.626129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:33.296649Z","time spent":"329.448195ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:33.626108Z","caller":"traceutil/trace.go:171","msg":"trace[4087590] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"331.405146ms","start":"2026-02-27T21:54:33.294669Z","end":"2026-02-27T21:54:33.626074Z","steps":["trace[4087590] 'process raft request' (duration: 115.083473ms)","trace[4087590] 'compare' (duration: 215.846331ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:33.62628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:33.294613Z","time spent":"331.568301ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:54:33.626361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.407258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-27T21:54:33.626439Z","caller":"traceutil/trace.go:171","msg":"trace[2068336525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:754; }","duration":"265.526531ms","start":"2026-02-27T21:54:33.360895Z","end":"2026-02-27T21:54:33.626422Z","steps":["trace[2068336525] 'agreement among raft nodes before linearized reading' (duration: 265.290495ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:33.626527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.688537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T21:54:33.626732Z","caller":"traceutil/trace.go:171","msg":"trace[671036205] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:754; }","duration":"174.036656ms","start":"2026-02-27T21:54:33.45268Z","end":"2026-02-27T21:54:33.626717Z","steps":["trace[671036205] 'agreement among raft nodes before linearized reading' (duration: 173.544793ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:34.01164Z","caller":"traceutil/trace.go:171","msg":"trace[1238018687] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"380.727486ms","start":"2026-02-27T21:54:33.630892Z","end":"2026-02-27T21:54:34.011619Z","steps":["trace[1238018687] 'process raft request' (duration: 380.587693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:34.011778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:33.630846Z","time spent":"380.858071ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:54:34.033874Z","caller":"traceutil/trace.go:171","msg":"trace[365308228] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"360.740363ms","start":"2026-02-27T21:54:33.67311Z","end":"2026-02-27T21:54:34.03385Z","steps":["trace[365308228] 'process raft request' (duration: 360.555439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:34.034009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:33.673093Z","time spent":"360.846566ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:54:34.393548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.276111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:54:34.393646Z","caller":"traceutil/trace.go:171","msg":"trace[2041694074] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:757; }","duration":"185.432345ms","start":"2026-02-27T21:54:34.208191Z","end":"2026-02-27T21:54:34.393624Z","steps":["trace[2041694074] 'count revisions from in-memory index tree' (duration: 185.199148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:34.645025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.549417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:54:34.645118Z","caller":"traceutil/trace.go:171","msg":"trace[1128782774] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:758; }","duration":"125.663841ms","start":"2026-02-27T21:54:34.519427Z","end":"2026-02-27T21:54:34.645091Z","steps":["trace[1128782774] 'count revisions from in-memory index tree' (duration: 125.512917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:34.645195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.79977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:54:34.645285Z","caller":"traceutil/trace.go:171","msg":"trace[207789239] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:758; }","duration":"126.942624ms","start":"2026-02-27T21:54:34.518323Z","end":"2026-02-27T21:54:34.645266Z","steps":["trace[207789239] 'count revisions from in-memory index tree' (duration: 126.740029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:44.750452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.326543ms","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-27T21:54:44.75053Z","caller":"traceutil/trace.go:171","msg":"trace[377799507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"271.462447ms","start":"2026-02-27T21:54:44.479054Z","end":"2026-02-27T21:54:44.750517Z","steps":["trace[377799507] 'range keys from in-memory index tree' (duration: 271.017886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:44.750795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.164513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:54:44.750819Z","caller":"traceutil/trace.go:171","msg":"trace[1497275811] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:836; }","duration":"100.216614ms","start":"2026-02-27T21:54:44.650596Z","end":"2026-02-27T21:54:44.750813Z","steps":["trace[1497275811] 'count revisions from in-memory index tree' (duration: 100.06069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:48.186523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812184263170403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:54:48.68767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812184263170403,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:54:49.013799Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.708302294s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:54:49.014376Z","caller":"traceutil/trace.go:171","msg":"trace[79064860] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"1.70890875s","start":"2026-02-27T21:54:47.305431Z","end":"2026-02-27T21:54:49.01434Z","steps":["trace[79064860] 'process raft request' (duration: 1.708559471s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:49.014276Z","caller":"traceutil/trace.go:171","msg":"trace[465827634] linearizableReadLoop","detail":"{readStateIndex:883; appliedIndex:882; }","duration":"1.328062669s","start":"2026-02-27T21:54:47.686189Z","end":"2026-02-27T21:54:49.014252Z","steps":["trace[465827634] 'read index received' (duration: 1.327780062s)","trace[465827634] 'applied index is now lower than readState.Index' (duration: 281.377µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:54:49.014509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:47.305413Z","time spent":"1.709011133s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:54:49.014607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.022795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:54:49.014638Z","caller":"traceutil/trace.go:171","msg":"trace[388471650] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:843; }","duration":"491.102106ms","start":"2026-02-27T21:54:48.523528Z","end":"2026-02-27T21:54:49.01463Z","steps":["trace[388471650] 'agreement among raft nodes before linearized reading' (duration: 491.025704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.01466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:48.52351Z","time spent":"491.144418ms","remote":"127.0.0.1:57186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:54:49.014662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323605591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-27T21:54:49.014712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.020769ms","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-27T21:54:49.01472Z","caller":"traceutil/trace.go:171","msg":"trace[1895566747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:843; }","duration":"1.323700514s","start":"2026-02-27T21:54:47.691002Z","end":"2026-02-27T21:54:49.014703Z","steps":["trace[1895566747] 'agreement among raft nodes before linearized reading' (duration: 1.32353723s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.014719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.023048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:54:49.014761Z","caller":"traceutil/trace.go:171","msg":"trace[285346055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"757.092161ms","start":"2026-02-27T21:54:48.257655Z","end":"2026-02-27T21:54:49.014747Z","steps":["trace[285346055] 'agreement among raft nodes before linearized reading' (duration: 756.950097ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:54:49.014768Z","caller":"traceutil/trace.go:171","msg":"trace[259068166] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:843; }","duration":"155.11402ms","start":"2026-02-27T21:54:48.859643Z","end":"2026-02-27T21:54:49.014757Z","steps":["trace[259068166] 'agreement among raft nodes before linearized reading' (duration: 155.021318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.014764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:47.690981Z","time spent":"1.323771857s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T21:54:49.014758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.172296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-27T21:54:49.014792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:48.257644Z","time spent":"757.141652ms","remote":"127.0.0.1:57170","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":"info","ts":"2026-02-27T21:54:49.014827Z","caller":"traceutil/trace.go:171","msg":"trace[780757027] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:843; }","duration":"882.287559ms","start":"2026-02-27T21:54:48.13252Z","end":"2026-02-27T21:54:49.014807Z","steps":["trace[780757027] 'agreement among raft nodes before linearized reading' (duration: 882.101304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.01488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.20261ms","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-27T21:54:49.014897Z","caller":"traceutil/trace.go:171","msg":"trace[2035659365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"242.250172ms","start":"2026-02-27T21:54:48.772642Z","end":"2026-02-27T21:54:49.014892Z","steps":["trace[2035659365] 'agreement among raft nodes before linearized reading' (duration: 242.1786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.014901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:48.132502Z","time spent":"882.389532ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:54:49.019617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333395939s","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-27T21:54:49.019715Z","caller":"traceutil/trace.go:171","msg":"trace[417520218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"1.333520042s","start":"2026-02-27T21:54:47.686142Z","end":"2026-02-27T21:54:49.019662Z","steps":["trace[417520218] 'agreement among raft nodes before linearized reading' (duration: 1.328336146s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:54:49.020804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:54:47.686126Z","time spent":"1.334639451s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T21:55:01.657164Z","caller":"traceutil/trace.go:171","msg":"trace[783112648] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"398.999966ms","start":"2026-02-27T21:55:01.258141Z","end":"2026-02-27T21:55:01.657141Z","steps":["trace[783112648] 'process raft request' (duration: 398.804291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:01.65731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:01.258132Z","time spent":"399.103148ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:01.659373Z","caller":"traceutil/trace.go:171","msg":"trace[1916047694] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"401.099241ms","start":"2026-02-27T21:55:01.258258Z","end":"2026-02-27T21:55:01.659358Z","steps":["trace[1916047694] 'process raft request' (duration: 401.06314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:01.659492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:01.258253Z","time spent":"401.194743ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:01.659889Z","caller":"traceutil/trace.go:171","msg":"trace[1041509288] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"401.721347ms","start":"2026-02-27T21:55:01.258156Z","end":"2026-02-27T21:55:01.659877Z","steps":["trace[1041509288] 'process raft request' (duration: 401.0658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:01.660002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:01.258135Z","time spent":"401.791889ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:01.660162Z","caller":"traceutil/trace.go:171","msg":"trace[828960927] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"401.932603ms","start":"2026-02-27T21:55:01.25822Z","end":"2026-02-27T21:55:01.660153Z","steps":["trace[828960927] 'process raft request' (duration: 401.07126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:01.660229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:01.258215Z","time spent":"401.980194ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:03.803783Z","caller":"traceutil/trace.go:171","msg":"trace[1873860110] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"130.770893ms","start":"2026-02-27T21:55:03.672985Z","end":"2026-02-27T21:55:03.803756Z","steps":["trace[1873860110] 'process raft request' (duration: 130.706701ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:03.803842Z","caller":"traceutil/trace.go:171","msg":"trace[465259135] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"130.839215ms","start":"2026-02-27T21:55:03.672967Z","end":"2026-02-27T21:55:03.803806Z","steps":["trace[465259135] 'process raft request' (duration: 130.459755ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:03.803928Z","caller":"traceutil/trace.go:171","msg":"trace[908903158] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"130.916456ms","start":"2026-02-27T21:55:03.672977Z","end":"2026-02-27T21:55:03.803893Z","steps":["trace[908903158] 'process raft request' (duration: 130.65579ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:04.048267Z","caller":"traceutil/trace.go:171","msg":"trace[1279308498] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"148.015114ms","start":"2026-02-27T21:55:03.900228Z","end":"2026-02-27T21:55:04.048243Z","steps":["trace[1279308498] 'process raft request' (duration: 113.993174ms)","trace[1279308498] 'compare' (duration: 33.914147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:06.641802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.703176ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:06.642951Z","caller":"traceutil/trace.go:171","msg":"trace[1529125739] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:955; }","duration":"596.017764ms","start":"2026-02-27T21:55:06.046921Z","end":"2026-02-27T21:55:06.642939Z","steps":["trace[1529125739] 'read index received' (duration: 104.104135ms)","trace[1529125739] 'applied index is now lower than readState.Index' (duration: 491.913039ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:06.643004Z","caller":"traceutil/trace.go:171","msg":"trace[1091477719] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"775.131513ms","start":"2026-02-27T21:55:05.867868Z","end":"2026-02-27T21:55:06.642999Z","steps":["trace[1091477719] 'process raft request' (duration: 774.962469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:06.643036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:05.867856Z","time spent":"775.160344ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:06.643108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.782733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:55:06.643103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.162867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:55:06.64315Z","caller":"traceutil/trace.go:171","msg":"trace[578337263] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"775.226535ms","start":"2026-02-27T21:55:05.867919Z","end":"2026-02-27T21:55:06.643146Z","steps":["trace[578337263] 'process raft request' (duration: 775.000789ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:06.643155Z","caller":"traceutil/trace.go:171","msg":"trace[1558495003] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:914; }","duration":"596.305621ms","start":"2026-02-27T21:55:06.046836Z","end":"2026-02-27T21:55:06.643141Z","steps":["trace[1558495003] 'agreement among raft nodes before linearized reading' (duration: 596.188178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:06.643177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:05.867913Z","time spent":"775.251756ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:06.643131Z","caller":"traceutil/trace.go:171","msg":"trace[84207795] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"775.465591ms","start":"2026-02-27T21:55:05.867608Z","end":"2026-02-27T21:55:06.643074Z","steps":["trace[84207795] 'process raft request' (duration: 283.44151ms)","trace[84207795] 'compare' (duration: 490.366377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:06.643205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:06.046816Z","time spent":"596.379403ms","remote":"127.0.0.1:57306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:55:06.643129Z","caller":"traceutil/trace.go:171","msg":"trace[764159614] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"570.841834ms","start":"2026-02-27T21:55:06.072281Z","end":"2026-02-27T21:55:06.643123Z","steps":["trace[764159614] 'agreement among raft nodes before linearized reading' (duration: 570.809534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:06.643301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:06.07226Z","time spent":"571.014418ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:55:06.643327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:05.867589Z","time spent":"775.636676ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:07.948187Z","caller":"traceutil/trace.go:171","msg":"trace[428163776] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"120.415952ms","start":"2026-02-27T21:55:07.827722Z","end":"2026-02-27T21:55:07.948138Z","steps":["trace[428163776] 'process raft request' (duration: 120.262758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.163135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.137302245s","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-27T21:55:09.163283Z","caller":"traceutil/trace.go:171","msg":"trace[2092045926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:921; }","duration":"1.137455578s","start":"2026-02-27T21:55:08.0258Z","end":"2026-02-27T21:55:09.163255Z","steps":["trace[2092045926] 'range keys from in-memory index tree' (duration: 1.137284484s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.163588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04435346s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:09.163692Z","caller":"traceutil/trace.go:171","msg":"trace[950249234] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"1.091096775s","start":"2026-02-27T21:55:08.072579Z","end":"2026-02-27T21:55:09.163676Z","steps":["trace[950249234] 'read index received' (duration: 46.547459ms)","trace[950249234] 'applied index is now lower than readState.Index' (duration: 1.044548306s)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:09.163725Z","caller":"traceutil/trace.go:171","msg":"trace[341919896] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"1.211127448s","start":"2026-02-27T21:55:07.952569Z","end":"2026-02-27T21:55:09.163697Z","steps":["trace[341919896] 'process raft request' (duration: 166.602602ms)","trace[341919896] 'compare' (duration: 1.044004931s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:09.163789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091213509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:09.163815Z","caller":"traceutil/trace.go:171","msg":"trace[1280972371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"1.09127752s","start":"2026-02-27T21:55:08.072528Z","end":"2026-02-27T21:55:09.163806Z","steps":["trace[1280972371] 'agreement among raft nodes before linearized reading' (duration: 1.091209938s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.163846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:08.07251Z","time spent":"1.091328181s","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:55:09.163853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:07.952548Z","time spent":"1.211229691s","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T21:55:09.244482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.126945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T21:55:09.244504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.341076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-27T21:55:09.244493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.119661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:55:09.244543Z","caller":"traceutil/trace.go:171","msg":"trace[1028330893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"596.221048ms","start":"2026-02-27T21:55:08.648308Z","end":"2026-02-27T21:55:09.244529Z","steps":["trace[1028330893] 'agreement among raft nodes before linearized reading' (duration: 596.054364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.244582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:08.648289Z","time spent":"596.28534ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T21:55:09.244577Z","caller":"traceutil/trace.go:171","msg":"trace[1866508328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"595.435618ms","start":"2026-02-27T21:55:08.649122Z","end":"2026-02-27T21:55:09.244558Z","steps":["trace[1866508328] 'agreement among raft nodes before linearized reading' (duration: 595.242853ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:09.244594Z","caller":"traceutil/trace.go:171","msg":"trace[1474442763] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:922; }","duration":"812.253595ms","start":"2026-02-27T21:55:08.432318Z","end":"2026-02-27T21:55:09.244571Z","steps":["trace[1474442763] 'agreement among raft nodes before linearized reading' (duration: 812.103401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.244619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:08.649116Z","time spent":"595.494039ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T21:55:09.244611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.770671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:55:09.244642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:08.432303Z","time spent":"812.324207ms","remote":"127.0.0.1:56392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-02-27T21:55:09.244659Z","caller":"traceutil/trace.go:171","msg":"trace[8257686] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:922; }","duration":"133.823912ms","start":"2026-02-27T21:55:09.110822Z","end":"2026-02-27T21:55:09.244646Z","steps":["trace[8257686] 'agreement among raft nodes before linearized reading' (duration: 133.74565ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.24513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.576085ms","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-27T21:55:09.24522Z","caller":"traceutil/trace.go:171","msg":"trace[651488620] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:922; }","duration":"597.72448ms","start":"2026-02-27T21:55:08.647478Z","end":"2026-02-27T21:55:09.245203Z","steps":["trace[651488620] 'agreement among raft nodes before linearized reading' (duration: 596.979979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:09.245257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:08.647456Z","time spent":"597.792011ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T21:55:09.884647Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812184263170581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:55:10.385259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976812184263170581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T21:55:10.583778Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.334141897s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T21:55:10.584156Z","caller":"traceutil/trace.go:171","msg":"trace[325858807] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"1.334605521s","start":"2026-02-27T21:55:09.249529Z","end":"2026-02-27T21:55:10.584135Z","steps":["trace[325858807] 'process raft request' (duration: 1.334461667s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:10.584297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.249512Z","time spent":"1.334710403s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:11.470613Z","caller":"traceutil/trace.go:171","msg":"trace[33876738] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"2.220620896s","start":"2026-02-27T21:55:09.249969Z","end":"2026-02-27T21:55:11.47059Z","steps":["trace[33876738] 'process raft request' (duration: 2.220443262s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.470761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.249945Z","time spent":"2.220737909s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:11.47096Z","caller":"traceutil/trace.go:171","msg":"trace[1432156106] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:967; }","duration":"2.086369721s","start":"2026-02-27T21:55:09.384581Z","end":"2026-02-27T21:55:11.47095Z","steps":["trace[1432156106] 'read index received' (duration: 1.199426172s)","trace[1432156106] 'applied index is now lower than readState.Index' (duration: 886.943109ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:11.470973Z","caller":"traceutil/trace.go:171","msg":"trace[1611193985] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"2.220528753s","start":"2026-02-27T21:55:09.250398Z","end":"2026-02-27T21:55:11.470927Z","steps":["trace[1611193985] 'process raft request' (duration: 2.220151953s)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:11.471024Z","caller":"traceutil/trace.go:171","msg":"trace[2057981920] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.968482664s","start":"2026-02-27T21:55:09.502535Z","end":"2026-02-27T21:55:11.471018Z","steps":["trace[2057981920] 'process raft request' (duration: 1.968276239s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.471057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.502515Z","time spent":"1.968522216s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:11.471083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.816849704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T21:55:11.471101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.250392Z","time spent":"2.220636427s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:11.47112Z","caller":"traceutil/trace.go:171","msg":"trace[561335077] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:926; }","duration":"1.816930816s","start":"2026-02-27T21:55:09.654178Z","end":"2026-02-27T21:55:11.471109Z","steps":["trace[561335077] 'agreement among raft nodes before linearized reading' (duration: 1.816868774s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.471135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.823588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:55:11.471153Z","caller":"traceutil/trace.go:171","msg":"trace[1313971178] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:926; }","duration":"252.88101ms","start":"2026-02-27T21:55:11.218265Z","end":"2026-02-27T21:55:11.471147Z","steps":["trace[1313971178] 'agreement among raft nodes before linearized reading' (duration: 252.831998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.47115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.654156Z","time spent":"1.816986488s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:55:11.471212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.955828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:11.471235Z","caller":"traceutil/trace.go:171","msg":"trace[1164695006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"398.01475ms","start":"2026-02-27T21:55:11.073214Z","end":"2026-02-27T21:55:11.471229Z","steps":["trace[1164695006] 'agreement among raft nodes before linearized reading' (duration: 397.968409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.471249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:11.073192Z","time spent":"398.05435ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:55:11.471331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.086762101s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T21:55:11.471346Z","caller":"traceutil/trace.go:171","msg":"trace[310759702] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:926; }","duration":"2.086823433s","start":"2026-02-27T21:55:09.384518Z","end":"2026-02-27T21:55:11.471341Z","steps":["trace[310759702] 'agreement among raft nodes before linearized reading' (duration: 2.086758051s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.471368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.384497Z","time spent":"2.086866883s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T21:55:11.471632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.649723569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T21:55:11.471674Z","caller":"traceutil/trace.go:171","msg":"trace[53506744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:926; }","duration":"1.649804901s","start":"2026-02-27T21:55:09.821859Z","end":"2026-02-27T21:55:11.471664Z","steps":["trace[53506744] 'agreement among raft nodes before linearized reading' (duration: 1.649434581s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:11.471708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:09.821837Z","time spent":"1.649864193s","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-27T21:55:12.275579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.052938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:12.276182Z","caller":"traceutil/trace.go:171","msg":"trace[1032126084] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:971; }","duration":"133.012704ms","start":"2026-02-27T21:55:12.143149Z","end":"2026-02-27T21:55:12.276161Z","steps":["trace[1032126084] 'read index received' (duration: 42.312µs)","trace[1032126084] 'applied index is now lower than readState.Index' (duration: 132.968972ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:12.276174Z","caller":"traceutil/trace.go:171","msg":"trace[1799882248] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"800.956668ms","start":"2026-02-27T21:55:11.475188Z","end":"2026-02-27T21:55:12.276144Z","steps":["trace[1799882248] 'process raft request' (duration: 239.184801ms)","trace[1799882248] 'compare' (duration: 560.674778ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:12.276243Z","caller":"traceutil/trace.go:171","msg":"trace[27843057] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"800.65552ms","start":"2026-02-27T21:55:11.475564Z","end":"2026-02-27T21:55:12.27622Z","steps":["trace[27843057] 'process raft request' (duration: 800.510557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:12.276333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:11.475173Z","time spent":"801.067811ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:12.276287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.133817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-27T21:55:12.276541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:11.475552Z","time spent":"800.823654ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:12.276603Z","caller":"traceutil/trace.go:171","msg":"trace[1333981253] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:928; }","duration":"133.489606ms","start":"2026-02-27T21:55:12.143096Z","end":"2026-02-27T21:55:12.276586Z","steps":["trace[1333981253] 'agreement among raft nodes before linearized reading' (duration: 133.131797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:13.138445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.780359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-27T21:55:13.138508Z","caller":"traceutil/trace.go:171","msg":"trace[771936941] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:928; }","duration":"312.908282ms","start":"2026-02-27T21:55:12.825586Z","end":"2026-02-27T21:55:13.138495Z","steps":["trace[771936941] 'range keys from in-memory index tree' (duration: 312.615235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:13.138537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:12.825563Z","time spent":"312.967103ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-27T21:55:13.138445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.716587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:55:13.138705Z","caller":"traceutil/trace.go:171","msg":"trace[1110517734] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:928; }","duration":"135.022075ms","start":"2026-02-27T21:55:13.003665Z","end":"2026-02-27T21:55:13.138687Z","steps":["trace[1110517734] 'count revisions from in-memory index tree' (duration: 134.608384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:13.138626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.959344ms","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-27T21:55:13.138804Z","caller":"traceutil/trace.go:171","msg":"trace[1134513980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"551.191491ms","start":"2026-02-27T21:55:12.587593Z","end":"2026-02-27T21:55:13.138785Z","steps":["trace[1134513980] 'range keys from in-memory index tree' (duration: 550.716898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:13.13888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:12.587573Z","time spent":"551.269323ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T21:55:13.600539Z","caller":"traceutil/trace.go:171","msg":"trace[609032627] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"458.058854ms","start":"2026-02-27T21:55:13.142456Z","end":"2026-02-27T21:55:13.600515Z","steps":["trace[609032627] 'process raft request' (duration: 457.91233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:13.600703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.142441Z","time spent":"458.169496ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:14.044581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.869898ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:14.04467Z","caller":"traceutil/trace.go:171","msg":"trace[1450204591] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:973; }","duration":"589.500701ms","start":"2026-02-27T21:55:13.455154Z","end":"2026-02-27T21:55:14.044655Z","steps":["trace[1450204591] 'read index received' (duration: 145.284398ms)","trace[1450204591] 'applied index is now lower than readState.Index' (duration: 444.214763ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:14.044725Z","caller":"traceutil/trace.go:171","msg":"trace[1243513671] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"901.961328ms","start":"2026-02-27T21:55:13.14272Z","end":"2026-02-27T21:55:14.044681Z","steps":["trace[1243513671] 'process raft request' (duration: 532.549729ms)","trace[1243513671] 'compare' (duration: 368.55547ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:14.044876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.725876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-27T21:55:14.044883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.142714Z","time spent":"902.087891ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:14.044915Z","caller":"traceutil/trace.go:171","msg":"trace[1808817032] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:930; }","duration":"589.796947ms","start":"2026-02-27T21:55:13.455108Z","end":"2026-02-27T21:55:14.044905Z","steps":["trace[1808817032] 'agreement among raft nodes before linearized reading' (duration: 589.647794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.044946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.455087Z","time spent":"589.850729ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.204\" "} {"level":"warn","ts":"2026-02-27T21:55:14.044935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.913232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:55:14.044996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.865811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-27T21:55:14.044936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.876321ms","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-27T21:55:14.045032Z","caller":"traceutil/trace.go:171","msg":"trace[597170487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"569.938403ms","start":"2026-02-27T21:55:13.475081Z","end":"2026-02-27T21:55:14.045019Z","steps":["trace[597170487] 'agreement among raft nodes before linearized reading' (duration: 569.887082ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:14.045023Z","caller":"traceutil/trace.go:171","msg":"trace[235826692] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:930; }","duration":"485.001684ms","start":"2026-02-27T21:55:13.559989Z","end":"2026-02-27T21:55:14.04499Z","steps":["trace[235826692] 'agreement among raft nodes before linearized reading' (duration: 484.872351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.04506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.475055Z","time spent":"570.000553ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-27T21:55:14.04507Z","caller":"traceutil/trace.go:171","msg":"trace[1059279703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"570.035724ms","start":"2026-02-27T21:55:13.475013Z","end":"2026-02-27T21:55:14.045049Z","steps":["trace[1059279703] 'agreement among raft nodes before linearized reading' (duration: 569.786259ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.045083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.559932Z","time spent":"485.127627ms","remote":"127.0.0.1:57150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:55:14.045129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:13.475003Z","time spent":"570.108357ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-27T21:55:14.432205Z","caller":"traceutil/trace.go:171","msg":"trace[1383928512] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"380.077087ms","start":"2026-02-27T21:55:14.052111Z","end":"2026-02-27T21:55:14.432188Z","steps":["trace[1383928512] 'process raft request' (duration: 380.007866ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:14.432238Z","caller":"traceutil/trace.go:171","msg":"trace[1780076680] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"382.384709ms","start":"2026-02-27T21:55:14.049826Z","end":"2026-02-27T21:55:14.43221Z","steps":["trace[1780076680] 'process raft request' (duration: 371.337282ms)","trace[1780076680] 'compare' (duration: 10.713389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:14.432314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.052101Z","time spent":"380.156119ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:14.432528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.049815Z","time spent":"382.481062ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:14.445564Z","caller":"traceutil/trace.go:171","msg":"trace[902007892] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"378.636265ms","start":"2026-02-27T21:55:14.066914Z","end":"2026-02-27T21:55:14.445551Z","steps":["trace[902007892] 'process raft request' (duration: 378.474942ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:14.445549Z","caller":"traceutil/trace.go:171","msg":"trace[574962021] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:976; }","duration":"373.554832ms","start":"2026-02-27T21:55:14.071971Z","end":"2026-02-27T21:55:14.445526Z","steps":["trace[574962021] 'read index received' (duration: 349.202307ms)","trace[574962021] 'applied index is now lower than readState.Index' (duration: 24.351455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:14.445626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.0669Z","time spent":"378.697567ms","remote":"127.0.0.1:56916","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:14.44591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.927141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:55:14.445986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.862577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-27T21:55:14.445982Z","caller":"traceutil/trace.go:171","msg":"trace[1069319378] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"374.019212ms","start":"2026-02-27T21:55:14.071929Z","end":"2026-02-27T21:55:14.445948Z","steps":["trace[1069319378] 'agreement among raft nodes before linearized reading' (duration: 373.875489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.446085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.147948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:14.446016Z","caller":"traceutil/trace.go:171","msg":"trace[1821243429] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"164.930367ms","start":"2026-02-27T21:55:14.281078Z","end":"2026-02-27T21:55:14.446009Z","steps":["trace[1821243429] 'agreement among raft nodes before linearized reading' (duration: 164.746683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.446007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.351985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T21:55:14.446234Z","caller":"traceutil/trace.go:171","msg":"trace[1994306872] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:933; }","duration":"164.60368ms","start":"2026-02-27T21:55:14.281622Z","end":"2026-02-27T21:55:14.446225Z","steps":["trace[1994306872] 'agreement among raft nodes before linearized reading' (duration: 164.250212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.446231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.071919Z","time spent":"374.298559ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T21:55:14.446508Z","caller":"traceutil/trace.go:171","msg":"trace[848201248] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:933; }","duration":"108.26524ms","start":"2026-02-27T21:55:14.337889Z","end":"2026-02-27T21:55:14.446154Z","steps":["trace[848201248] 'agreement among raft nodes before linearized reading' (duration: 108.168457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.860804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.643883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:14.861217Z","caller":"traceutil/trace.go:171","msg":"trace[750155154] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:980; }","duration":"381.766296ms","start":"2026-02-27T21:55:14.479434Z","end":"2026-02-27T21:55:14.8612Z","steps":["trace[750155154] 'read index received' (duration: 181.56723ms)","trace[750155154] 'applied index is now lower than readState.Index' (duration: 200.198196ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:14.861237Z","caller":"traceutil/trace.go:171","msg":"trace[791798805] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"412.042523ms","start":"2026-02-27T21:55:14.449161Z","end":"2026-02-27T21:55:14.861203Z","steps":["trace[791798805] 'process raft request' (duration: 211.886478ms)","trace[791798805] 'compare' (duration: 199.214124ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:14.861297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.861228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-27T21:55:14.861328Z","caller":"traceutil/trace.go:171","msg":"trace[657769083] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:936; }","duration":"381.919609ms","start":"2026-02-27T21:55:14.479397Z","end":"2026-02-27T21:55:14.861317Z","steps":["trace[657769083] 'agreement among raft nodes before linearized reading' (duration: 381.856808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.861359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.479385Z","time spent":"381.96558ms","remote":"127.0.0.1:57186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-27T21:55:14.861332Z","caller":"traceutil/trace.go:171","msg":"trace[970954416] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"394.463759ms","start":"2026-02-27T21:55:14.466838Z","end":"2026-02-27T21:55:14.861301Z","steps":["trace[970954416] 'process raft request' (duration: 394.290475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:14.861396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.44915Z","time spent":"412.151305ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:14.861521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:14.466823Z","time spent":"394.579442ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:16.501559Z","caller":"traceutil/trace.go:171","msg":"trace[883433855] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"428.691075ms","start":"2026-02-27T21:55:16.072844Z","end":"2026-02-27T21:55:16.501535Z","steps":["trace[883433855] 'read index received' (duration: 428.455749ms)","trace[883433855] 'applied index is now lower than readState.Index' (duration: 234.076µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:16.501639Z","caller":"traceutil/trace.go:171","msg":"trace[386699663] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"449.06332ms","start":"2026-02-27T21:55:16.052541Z","end":"2026-02-27T21:55:16.501604Z","steps":["trace[386699663] 'process raft request' (duration: 448.854526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:16.501708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.845099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:16.501743Z","caller":"traceutil/trace.go:171","msg":"trace[934986559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"428.92696ms","start":"2026-02-27T21:55:16.072806Z","end":"2026-02-27T21:55:16.501733Z","steps":["trace[934986559] 'agreement among raft nodes before linearized reading' (duration: 428.854788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:16.501782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.072781Z","time spent":"428.993722ms","remote":"127.0.0.1:56870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T21:55:16.502442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.052522Z","time spent":"449.232454ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:17.123668Z","caller":"traceutil/trace.go:171","msg":"trace[1122369214] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"617.367764ms","start":"2026-02-27T21:55:16.506277Z","end":"2026-02-27T21:55:17.123645Z","steps":["trace[1122369214] 'process raft request' (duration: 617.229141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:17.123821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.506258Z","time spent":"617.483306ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:17.173529Z","caller":"traceutil/trace.go:171","msg":"trace[1312710104] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:984; }","duration":"307.180119ms","start":"2026-02-27T21:55:16.866324Z","end":"2026-02-27T21:55:17.173505Z","steps":["trace[1312710104] 'read index received' (duration: 257.437626ms)","trace[1312710104] 'applied index is now lower than readState.Index' (duration: 49.741443ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:17.173544Z","caller":"traceutil/trace.go:171","msg":"trace[1321741040] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"667.033564ms","start":"2026-02-27T21:55:16.506491Z","end":"2026-02-27T21:55:17.173524Z","steps":["trace[1321741040] 'process raft request' (duration: 666.86392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:17.173681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.506453Z","time spent":"667.153407ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:17.173791Z","caller":"traceutil/trace.go:171","msg":"trace[1940285098] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"667.066015ms","start":"2026-02-27T21:55:16.506715Z","end":"2026-02-27T21:55:17.173781Z","steps":["trace[1940285098] 'process raft request' (duration: 666.753968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:17.173847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.506687Z","time spent":"667.131846ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:17.174057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.717431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T21:55:17.174114Z","caller":"traceutil/trace.go:171","msg":"trace[1414774352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:940; }","duration":"307.793363ms","start":"2026-02-27T21:55:16.866307Z","end":"2026-02-27T21:55:17.1741Z","steps":["trace[1414774352] 'agreement among raft nodes before linearized reading' (duration: 307.602578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:17.174144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:16.866257Z","time spent":"307.880644ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-27T21:55:18.767011Z","caller":"traceutil/trace.go:171","msg":"trace[260816299] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"161.206645ms","start":"2026-02-27T21:55:18.605779Z","end":"2026-02-27T21:55:18.766986Z","steps":["trace[260816299] 'read index received' (duration: 160.961889ms)","trace[260816299] 'applied index is now lower than readState.Index' (duration: 243.926µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:18.767067Z","caller":"traceutil/trace.go:171","msg":"trace[1718930874] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"257.366015ms","start":"2026-02-27T21:55:18.509676Z","end":"2026-02-27T21:55:18.767042Z","steps":["trace[1718930874] 'process raft request' (duration: 257.074248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:18.767262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.471901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-27T21:55:18.767303Z","caller":"traceutil/trace.go:171","msg":"trace[158176926] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:950; }","duration":"161.565813ms","start":"2026-02-27T21:55:18.605725Z","end":"2026-02-27T21:55:18.76729Z","steps":["trace[158176926] 'agreement among raft nodes before linearized reading' (duration: 161.39579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:19.06011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.684164ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-27T21:55:19.060392Z","caller":"traceutil/trace.go:171","msg":"trace[170119892] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"288.884269ms","start":"2026-02-27T21:55:18.771483Z","end":"2026-02-27T21:55:19.060367Z","steps":["trace[170119892] 'process raft request' (duration: 288.727676ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:19.060329Z","caller":"traceutil/trace.go:171","msg":"trace[106798679] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"292.153172ms","start":"2026-02-27T21:55:18.768144Z","end":"2026-02-27T21:55:19.060297Z","steps":["trace[106798679] 'read index received' (duration: 58.300494ms)","trace[106798679] 'applied index is now lower than readState.Index' (duration: 233.850938ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:19.196329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.519017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:19.19661Z","caller":"traceutil/trace.go:171","msg":"trace[706204406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"125.723001ms","start":"2026-02-27T21:55:19.070708Z","end":"2026-02-27T21:55:19.196431Z","steps":["trace[706204406] 'range keys from in-memory index tree' (duration: 125.369054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:19.197532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.36012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:55:19.197635Z","caller":"traceutil/trace.go:171","msg":"trace[2038012104] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:951; }","duration":"429.483783ms","start":"2026-02-27T21:55:18.768117Z","end":"2026-02-27T21:55:19.197601Z","steps":["trace[2038012104] 'agreement among raft nodes before linearized reading' (duration: 292.341586ms)","trace[2038012104] 'count revisions from in-memory index tree' (duration: 137.018084ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:19.197694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:18.768101Z","time spent":"429.572915ms","remote":"127.0.0.1:57082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:55:19.535849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.246994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-27T21:55:19.535959Z","caller":"traceutil/trace.go:171","msg":"trace[1235719585] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:998; }","duration":"280.696006ms","start":"2026-02-27T21:55:19.255251Z","end":"2026-02-27T21:55:19.535948Z","steps":["trace[1235719585] 'read index received' (duration: 14.593147ms)","trace[1235719585] 'applied index is now lower than readState.Index' (duration: 266.102229ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:19.53602Z","caller":"traceutil/trace.go:171","msg":"trace[792407266] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"332.590577ms","start":"2026-02-27T21:55:19.203422Z","end":"2026-02-27T21:55:19.536013Z","steps":["trace[792407266] 'process raft request' (duration: 205.112797ms)","trace[792407266] 'compare' (duration: 127.070591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:19.536056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:19.203406Z","time spent":"332.632977ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T21:55:19.536213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.943361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:19.536291Z","caller":"traceutil/trace.go:171","msg":"trace[345054656] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:954; }","duration":"281.051293ms","start":"2026-02-27T21:55:19.25522Z","end":"2026-02-27T21:55:19.536272Z","steps":["trace[345054656] 'agreement among raft nodes before linearized reading' (duration: 280.924111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:19.536338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.172277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-27T21:55:19.536363Z","caller":"traceutil/trace.go:171","msg":"trace[319034683] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:954; }","duration":"281.244248ms","start":"2026-02-27T21:55:19.255113Z","end":"2026-02-27T21:55:19.536357Z","steps":["trace[319034683] 'agreement among raft nodes before linearized reading' (duration: 281.155246ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:19.536248Z","caller":"traceutil/trace.go:171","msg":"trace[1481348437] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"331.172515ms","start":"2026-02-27T21:55:19.205064Z","end":"2026-02-27T21:55:19.536236Z","steps":["trace[1481348437] 'process raft request' (duration: 330.851567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:19.536533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:55:19.205054Z","time spent":"331.40684ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T21:55:21.565565Z","caller":"traceutil/trace.go:171","msg":"trace[902386793] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"287.508089ms","start":"2026-02-27T21:55:21.278039Z","end":"2026-02-27T21:55:21.565547Z","steps":["trace[902386793] 'process raft request' (duration: 287.381616ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:21.712959Z","caller":"traceutil/trace.go:171","msg":"trace[29826459] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"143.642382ms","start":"2026-02-27T21:55:21.569303Z","end":"2026-02-27T21:55:21.712945Z","steps":["trace[29826459] 'process raft request' (duration: 139.548231ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:21.714898Z","caller":"traceutil/trace.go:171","msg":"trace[846818392] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"145.062244ms","start":"2026-02-27T21:55:21.569777Z","end":"2026-02-27T21:55:21.71484Z","steps":["trace[846818392] 'process raft request' (duration: 144.89908ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:21.715086Z","caller":"traceutil/trace.go:171","msg":"trace[1163839141] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"144.267507ms","start":"2026-02-27T21:55:21.570791Z","end":"2026-02-27T21:55:21.715058Z","steps":["trace[1163839141] 'process raft request' (duration: 144.012561ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:21.934145Z","caller":"traceutil/trace.go:171","msg":"trace[431622198] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"208.246406ms","start":"2026-02-27T21:55:21.725869Z","end":"2026-02-27T21:55:21.934116Z","steps":["trace[431622198] 'process raft request' (duration: 205.312371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:55:31.18338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.162233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:31.183467Z","caller":"traceutil/trace.go:171","msg":"trace[1901499435] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"110.304796ms","start":"2026-02-27T21:55:31.07314Z","end":"2026-02-27T21:55:31.183444Z","steps":["trace[1901499435] 'range keys from in-memory index tree' (duration: 110.06042ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:32.640258Z","caller":"traceutil/trace.go:171","msg":"trace[2035854446] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"109.5745ms","start":"2026-02-27T21:55:32.530649Z","end":"2026-02-27T21:55:32.640224Z","steps":["trace[2035854446] 'process raft request' (duration: 43.86314ms)","trace[2035854446] 'compare' (duration: 65.520506ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:32.803348Z","caller":"traceutil/trace.go:171","msg":"trace[1839428181] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"118.652102ms","start":"2026-02-27T21:55:32.684671Z","end":"2026-02-27T21:55:32.803323Z","steps":["trace[1839428181] 'process raft request' (duration: 100.680151ms)","trace[1839428181] 'compare' (duration: 17.824908ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:55:33.785515Z","caller":"traceutil/trace.go:171","msg":"trace[1773623886] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"130.57184ms","start":"2026-02-27T21:55:33.65492Z","end":"2026-02-27T21:55:33.785492Z","steps":["trace[1773623886] 'process raft request' (duration: 130.432477ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:55:33.949597Z","caller":"traceutil/trace.go:171","msg":"trace[1176398828] transaction","detail":"{read_only:false; response_revision:1033; number_of_response:1; }","duration":"128.79399ms","start":"2026-02-27T21:55:33.820752Z","end":"2026-02-27T21:55:33.949546Z","steps":["trace[1176398828] 'process raft request' (duration: 56.275428ms)","trace[1176398828] 'compare' (duration: 71.889678ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:55:34.222444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.549871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:55:34.222537Z","caller":"traceutil/trace.go:171","msg":"trace[923814780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1035; }","duration":"148.659123ms","start":"2026-02-27T21:55:34.073858Z","end":"2026-02-27T21:55:34.222517Z","steps":["trace[923814780] 'range keys from in-memory index tree' (duration: 148.393267ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:56:56.717967Z","caller":"traceutil/trace.go:171","msg":"trace[542510671] linearizableReadLoop","detail":"{readStateIndex:1437; appliedIndex:1436; }","duration":"116.217003ms","start":"2026-02-27T21:56:56.601725Z","end":"2026-02-27T21:56:56.717942Z","steps":["trace[542510671] 'read index received' (duration: 108.811046ms)","trace[542510671] 'applied index is now lower than readState.Index' (duration: 7.405157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T21:56:56.718176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.428667ms","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-27T21:56:56.718212Z","caller":"traceutil/trace.go:171","msg":"trace[1756820486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"116.50155ms","start":"2026-02-27T21:56:56.601698Z","end":"2026-02-27T21:56:56.7182Z","steps":["trace[1756820486] 'agreement among raft nodes before linearized reading' (duration: 116.337946ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:56:56.718336Z","caller":"traceutil/trace.go:171","msg":"trace[1214726047] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"135.549997ms","start":"2026-02-27T21:56:56.582756Z","end":"2026-02-27T21:56:56.718306Z","steps":["trace[1214726047] 'process raft request' (duration: 127.833873ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:57:47.943459Z","caller":"traceutil/trace.go:171","msg":"trace[1377247764] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"108.313859ms","start":"2026-02-27T21:57:47.835115Z","end":"2026-02-27T21:57:47.943429Z","steps":["trace[1377247764] 'process raft request' (duration: 58.635428ms)","trace[1377247764] 'compare' (duration: 49.560678ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:57:52.299337Z","caller":"traceutil/trace.go:171","msg":"trace[1397309146] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"109.243729ms","start":"2026-02-27T21:57:52.190067Z","end":"2026-02-27T21:57:52.299311Z","steps":["trace[1397309146] 'process raft request' (duration: 109.112736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:57:53.520016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.533749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-27T21:57:53.520088Z","caller":"traceutil/trace.go:171","msg":"trace[602939663] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1601; }","duration":"190.641041ms","start":"2026-02-27T21:57:53.329428Z","end":"2026-02-27T21:57:53.520069Z","steps":["trace[602939663] 'range keys from in-memory index tree' (duration: 190.359505ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:58:24.282128Z","caller":"traceutil/trace.go:171","msg":"trace[798727948] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"161.320843ms","start":"2026-02-27T21:58:24.120776Z","end":"2026-02-27T21:58:24.282096Z","steps":["trace[798727948] 'process raft request' (duration: 161.142448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:32.923306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.18969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-27T21:58:32.923521Z","caller":"traceutil/trace.go:171","msg":"trace[1097375228] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1790; }","duration":"179.461776ms","start":"2026-02-27T21:58:32.744035Z","end":"2026-02-27T21:58:32.923497Z","steps":["trace[1097375228] 'count revisions from in-memory index tree' (duration: 178.978544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:32.923306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.638952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T21:58:32.923817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.450353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-27T21:58:32.923893Z","caller":"traceutil/trace.go:171","msg":"trace[1329561209] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1790; }","duration":"511.247175ms","start":"2026-02-27T21:58:32.41263Z","end":"2026-02-27T21:58:32.923877Z","steps":["trace[1329561209] 'count revisions from in-memory index tree' (duration: 510.550839ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:58:32.923922Z","caller":"traceutil/trace.go:171","msg":"trace[586731405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1790; }","duration":"436.78635ms","start":"2026-02-27T21:58:32.487113Z","end":"2026-02-27T21:58:32.923899Z","steps":["trace[586731405] 'range keys from in-memory index tree' (duration: 436.249647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:32.923939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:58:32.412569Z","time spent":"511.345637ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-27T21:58:32.923977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T21:58:32.487093Z","time spent":"436.871382ms","remote":"127.0.0.1:57170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T21:58:43.658924Z","caller":"traceutil/trace.go:171","msg":"trace[1704684854] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"280.734251ms","start":"2026-02-27T21:58:43.378166Z","end":"2026-02-27T21:58:43.6589Z","steps":["trace[1704684854] 'read index received' (duration: 280.579218ms)","trace[1704684854] 'applied index is now lower than readState.Index' (duration: 153.793µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T21:58:43.659033Z","caller":"traceutil/trace.go:171","msg":"trace[1041499196] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"288.672668ms","start":"2026-02-27T21:58:43.370341Z","end":"2026-02-27T21:58:43.659013Z","steps":["trace[1041499196] 'process raft request' (duration: 288.396212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:43.65915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.979318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-dns\" ","response":"range_response_count:1 size:576"} {"level":"info","ts":"2026-02-27T21:58:43.659197Z","caller":"traceutil/trace.go:171","msg":"trace[37237856] range","detail":"{range_begin:/registry/clusterroles/system:kube-dns; range_end:; response_count:1; response_revision:1842; }","duration":"281.06342ms","start":"2026-02-27T21:58:43.378123Z","end":"2026-02-27T21:58:43.659186Z","steps":["trace[37237856] 'agreement among raft nodes before linearized reading' (duration: 280.947267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:43.659206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.933891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-27T21:58:43.659615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.246377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-02-27T21:58:43.659577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.332384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-controller-manager\" ","response":"range_response_count:1 size:1039"} {"level":"info","ts":"2026-02-27T21:58:43.659713Z","caller":"traceutil/trace.go:171","msg":"trace[232933096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1842; }","duration":"120.35806ms","start":"2026-02-27T21:58:43.53933Z","end":"2026-02-27T21:58:43.659688Z","steps":["trace[232933096] 'agreement among raft nodes before linearized reading' (duration: 119.91663ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:58:43.659655Z","caller":"traceutil/trace.go:171","msg":"trace[1664679480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1842; }","duration":"221.394221ms","start":"2026-02-27T21:58:43.438223Z","end":"2026-02-27T21:58:43.659617Z","steps":["trace[1664679480] 'agreement among raft nodes before linearized reading' (duration: 220.89034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:43.659326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.87963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.204\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-27T21:58:43.659831Z","caller":"traceutil/trace.go:171","msg":"trace[1382601293] range","detail":"{range_begin:/registry/masterleases/162.253.55.204; range_end:; response_count:1; response_revision:1842; }","duration":"192.410532ms","start":"2026-02-27T21:58:43.467412Z","end":"2026-02-27T21:58:43.659823Z","steps":["trace[1382601293] 'agreement among raft nodes before linearized reading' (duration: 191.86181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T21:58:43.659255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.291045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T21:58:43.659891Z","caller":"traceutil/trace.go:171","msg":"trace[41802143] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1842; }","duration":"171.97378ms","start":"2026-02-27T21:58:43.487901Z","end":"2026-02-27T21:58:43.659875Z","steps":["trace[41802143] 'agreement among raft nodes before linearized reading' (duration: 171.327296ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T21:58:43.659797Z","caller":"traceutil/trace.go:171","msg":"trace[882582931] range","detail":"{range_begin:/registry/clusterroles/system:kube-controller-manager; range_end:; response_count:1; response_revision:1842; }","duration":"218.562559ms","start":"2026-02-27T21:58:43.441178Z","end":"2026-02-27T21:58:43.65974Z","steps":["trace[882582931] 'agreement among raft nodes before linearized reading' (duration: 218.14354ms)"],"step_count":1}