{"level":"warn","ts":"2026-02-08T21:41:13.99254Z","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-08T21:41:13.995158Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.110: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.110:2380","--initial-cluster=instance=https://162.253.55.110:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.110:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.110: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-08T21:41:13.995243Z","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-08T21:41:13.995256Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-08T21:41:13.995286Z","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-08T21:41:14.000968Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"]} {"level":"info","ts":"2026-02-08T21:41:14.001045Z","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":2,"max-cpu-available":2,"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.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.110: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-08T21:41:14.008735Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.51388ms"} {"level":"info","ts":"2026-02-08T21:41:14.018136Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f52f30d5714009d5","cluster-id":"650981a7a383c714"} {"level":"info","ts":"2026-02-08T21:41:14.018198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:41:14.018217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 0"} {"level":"info","ts":"2026-02-08T21:41:14.018226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f52f30d5714009d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:41:14.018235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became follower at term 1"} {"level":"info","ts":"2026-02-08T21:41:14.018264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"warn","ts":"2026-02-08T21:41:14.023878Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:41:14.032481Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:41:14.045366Z","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-08T21:41:14.052446Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f52f30d5714009d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:41:14.05464Z","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-08T21:41:14.054869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 switched to configuration voters=(17667393556483934677)"} {"level":"info","ts":"2026-02-08T21:41:14.058114Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","added-peer-id":"f52f30d5714009d5","added-peer-peer-urls":["https://162.253.55.110:2380"]} {"level":"info","ts":"2026-02-08T21:41:14.054897Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f52f30d5714009d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:41:14.057651Z","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-08T21:41:14.058341Z","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-08T21:41:14.058401Z","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-08T21:41:14.057867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-08T21:41:14.05857Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.110:2380"} {"level":"info","ts":"2026-02-08T21:41:14.058789Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f52f30d5714009d5","initial-advertise-peer-urls":["https://162.253.55.110:2380"],"listen-peer-urls":["https://162.253.55.110:2380"],"advertise-client-urls":["https://162.253.55.110:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.110:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:41:14.059725Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:41:14.318637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:41:14.318735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:41:14.31876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgPreVoteResp from f52f30d5714009d5 at term 1"} {"level":"info","ts":"2026-02-08T21:41:14.318783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:41:14.318816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 received MsgVoteResp from f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-08T21:41:14.318835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f52f30d5714009d5 became leader at term 2"} {"level":"info","ts":"2026-02-08T21:41:14.318854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f52f30d5714009d5 elected leader f52f30d5714009d5 at term 2"} {"level":"info","ts":"2026-02-08T21:41:14.320001Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:14.320807Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f52f30d5714009d5","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.110:2379]}","request-path":"/0/members/f52f30d5714009d5/attributes","cluster-id":"650981a7a383c714","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:41:14.320868Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:14.321276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:41:14.321509Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"650981a7a383c714","local-member-id":"f52f30d5714009d5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:14.321591Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:14.321618Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:41:14.322647Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:41:14.322662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:41:14.326697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.110:2379"} {"level":"info","ts":"2026-02-08T21:41:14.334861Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-08T21:41:30.501174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.793545ms","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-08T21:41:30.501262Z","caller":"traceutil/trace.go:171","msg":"trace[1534025811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:398; }","duration":"745.94181ms","start":"2026-02-08T21:41:29.755302Z","end":"2026-02-08T21:41:30.501243Z","steps":["trace[1534025811] 'range keys from in-memory index tree' (duration: 745.686722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.501315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:29.755286Z","time spent":"746.019262ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:41:30.502153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.667434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:41:30.502216Z","caller":"traceutil/trace.go:171","msg":"trace[298327320] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"315.703019ms","start":"2026-02-08T21:41:30.186501Z","end":"2026-02-08T21:41:30.502204Z","steps":["trace[298327320] 'read index received' (duration: 31.771µs)","trace[298327320] 'applied index is now lower than readState.Index' (duration: 315.670208ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:30.502294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.793252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-08T21:41:30.50232Z","caller":"traceutil/trace.go:171","msg":"trace[1755346771] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:399; }","duration":"315.844104ms","start":"2026-02-08T21:41:30.186466Z","end":"2026-02-08T21:41:30.50231Z","steps":["trace[1755346771] 'agreement among raft nodes before linearized reading' (duration: 315.773392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.502347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:30.186441Z","time spent":"315.898775ms","remote":"127.0.0.1:37868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-08T21:41:30.502627Z","caller":"traceutil/trace.go:171","msg":"trace[1201642312] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"694.754854ms","start":"2026-02-08T21:41:29.807859Z","end":"2026-02-08T21:41:30.502614Z","steps":["trace[1201642312] 'process raft request' (duration: 79.064837ms)","trace[1201642312] 'compare' (duration: 614.426926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:30.502686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:29.807844Z","time spent":"694.810566ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:30.683146Z","caller":"traceutil/trace.go:171","msg":"trace[604713279] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"170.325733ms","start":"2026-02-08T21:41:30.512801Z","end":"2026-02-08T21:41:30.683127Z","steps":["trace[604713279] 'read index received' (duration: 170.161968ms)","trace[604713279] 'applied index is now lower than readState.Index' (duration: 162.795µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:30.683225Z","caller":"traceutil/trace.go:171","msg":"trace[340513881] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"175.468798ms","start":"2026-02-08T21:41:30.507745Z","end":"2026-02-08T21:41:30.683214Z","steps":["trace[340513881] 'process raft request' (duration: 175.257762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.683274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.455708ms","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-08T21:41:30.683304Z","caller":"traceutil/trace.go:171","msg":"trace[712221672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"170.51619ms","start":"2026-02-08T21:41:30.512779Z","end":"2026-02-08T21:41:30.683295Z","steps":["trace[712221672] 'agreement among raft nodes before linearized reading' (duration: 170.449318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:30.683379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.850838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:30.683395Z","caller":"traceutil/trace.go:171","msg":"trace[398126478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"169.868949ms","start":"2026-02-08T21:41:30.513521Z","end":"2026-02-08T21:41:30.68339Z","steps":["trace[398126478] 'agreement among raft nodes before linearized reading' (duration: 169.838298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:44.917282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.19882ms","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-08T21:41:44.917348Z","caller":"traceutil/trace.go:171","msg":"trace[1306199584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"122.298053ms","start":"2026-02-08T21:41:44.795037Z","end":"2026-02-08T21:41:44.917335Z","steps":["trace[1306199584] 'range keys from in-memory index tree' (duration: 122.137538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:44.917425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.004424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:44.917445Z","caller":"traceutil/trace.go:171","msg":"trace[833055506] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:499; }","duration":"122.033124ms","start":"2026-02-08T21:41:44.795407Z","end":"2026-02-08T21:41:44.917441Z","steps":["trace[833055506] 'range keys from in-memory index tree' (duration: 121.776506ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:47.151282Z","caller":"traceutil/trace.go:171","msg":"trace[481146683] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:519; }","duration":"217.361059ms","start":"2026-02-08T21:41:46.933903Z","end":"2026-02-08T21:41:47.151265Z","steps":["trace[481146683] 'read index received' (duration: 217.231335ms)","trace[481146683] 'applied index is now lower than readState.Index' (duration: 129.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:41:47.151493Z","caller":"traceutil/trace.go:171","msg":"trace[1274927296] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"268.742344ms","start":"2026-02-08T21:41:46.882745Z","end":"2026-02-08T21:41:47.151487Z","steps":["trace[1274927296] 'process raft request' (duration: 268.434844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.151612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.648798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:47.151659Z","caller":"traceutil/trace.go:171","msg":"trace[1123973473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"217.737851ms","start":"2026-02-08T21:41:46.93391Z","end":"2026-02-08T21:41:47.151648Z","steps":["trace[1123973473] 'agreement among raft nodes before linearized reading' (duration: 217.619657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:47.356596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.660894ms","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-08T21:41:47.35672Z","caller":"traceutil/trace.go:171","msg":"trace[698342085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:504; }","duration":"422.806428ms","start":"2026-02-08T21:41:46.933876Z","end":"2026-02-08T21:41:47.356683Z","steps":["trace[698342085] 'agreement among raft nodes before linearized reading' (duration: 217.865745ms)","trace[698342085] 'range keys from in-memory index tree' (duration: 204.757558ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:47.356772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:46.933864Z","time spent":"422.898731ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T21:41:47.361808Z","caller":"traceutil/trace.go:171","msg":"trace[1519463841] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"205.252793ms","start":"2026-02-08T21:41:47.156544Z","end":"2026-02-08T21:41:47.361797Z","steps":["trace[1519463841] 'process raft request' (duration: 205.018976ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.653504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.16528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:51.653614Z","caller":"traceutil/trace.go:171","msg":"trace[567033875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"609.283604ms","start":"2026-02-08T21:41:51.044278Z","end":"2026-02-08T21:41:51.653562Z","steps":["trace[567033875] 'range keys from in-memory index tree' (duration: 609.103338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.653651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.04426Z","time spent":"609.380767ms","remote":"127.0.0.1:37742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T21:41:51.65393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.123374Z","time spent":"530.553787ms","remote":"127.0.0.1:51922","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T21:41:51.654367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.059668ms","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-08T21:41:51.65436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.434834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:41:51.654404Z","caller":"traceutil/trace.go:171","msg":"trace[459888674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"244.11397ms","start":"2026-02-08T21:41:51.410278Z","end":"2026-02-08T21:41:51.654392Z","steps":["trace[459888674] 'range keys from in-memory index tree' (duration: 244.006857ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:51.654429Z","caller":"traceutil/trace.go:171","msg":"trace[177649852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"279.564709ms","start":"2026-02-08T21:41:51.374847Z","end":"2026-02-08T21:41:51.654412Z","steps":["trace[177649852] 'range keys from in-memory index tree' (duration: 279.339342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.654538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.957847ms","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-08T21:41:51.654565Z","caller":"traceutil/trace.go:171","msg":"trace[312158883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"250.025528ms","start":"2026-02-08T21:41:51.404531Z","end":"2026-02-08T21:41:51.654557Z","steps":["trace[312158883] 'range keys from in-memory index tree' (duration: 249.846252ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:51.96169Z","caller":"traceutil/trace.go:171","msg":"trace[512487465] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"300.555177ms","start":"2026-02-08T21:41:51.661091Z","end":"2026-02-08T21:41:51.961646Z","steps":["trace[512487465] 'process raft request' (duration: 300.305929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:51.961872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.661071Z","time spent":"300.705281ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:52.232811Z","caller":"traceutil/trace.go:171","msg":"trace[1247104254] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"190.954809ms","start":"2026-02-08T21:41:52.041835Z","end":"2026-02-08T21:41:52.23279Z","steps":["trace[1247104254] 'read index received' (duration: 190.579007ms)","trace[1247104254] 'applied index is now lower than readState.Index' (duration: 374.942µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:41:52.232934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.083303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:41:52.232964Z","caller":"traceutil/trace.go:171","msg":"trace[1778516071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"191.129884ms","start":"2026-02-08T21:41:52.041825Z","end":"2026-02-08T21:41:52.232955Z","steps":["trace[1778516071] 'agreement among raft nodes before linearized reading' (duration: 191.047991ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:52.23463Z","caller":"traceutil/trace.go:171","msg":"trace[1222473046] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"564.646023ms","start":"2026-02-08T21:41:51.669969Z","end":"2026-02-08T21:41:52.234615Z","steps":["trace[1222473046] 'process raft request' (duration: 562.761493ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:41:52.236265Z","caller":"traceutil/trace.go:171","msg":"trace[900105722] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"573.714161ms","start":"2026-02-08T21:41:51.662536Z","end":"2026-02-08T21:41:52.23625Z","steps":["trace[900105722] 'process raft request' (duration: 569.936161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:52.23682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.662524Z","time spent":"574.250969ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:41:52.236321Z","caller":"traceutil/trace.go:171","msg":"trace[1167951183] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"572.194973ms","start":"2026-02-08T21:41:51.664118Z","end":"2026-02-08T21:41:52.236313Z","steps":["trace[1167951183] 'process raft request' (duration: 568.537907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:41:52.236987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.664103Z","time spent":"572.856484ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:41:52.23761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:41:51.669963Z","time spent":"566.76068ms","remote":"127.0.0.1:37870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:06.751018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.116406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:06.751086Z","caller":"traceutil/trace.go:171","msg":"trace[481604993] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"310.205624ms","start":"2026-02-08T21:42:06.44087Z","end":"2026-02-08T21:42:06.751076Z","steps":["trace[481604993] 'read index received' (duration: 46.921183ms)","trace[481604993] 'applied index is now lower than readState.Index' (duration: 263.283801ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:06.751184Z","caller":"traceutil/trace.go:171","msg":"trace[318671492] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"525.187426ms","start":"2026-02-08T21:42:06.225991Z","end":"2026-02-08T21:42:06.751178Z","steps":["trace[318671492] 'process raft request' (duration: 261.839333ms)","trace[318671492] 'compare' (duration: 263.023913ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:06.751227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.225978Z","time spent":"525.218518ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:06.751352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.481033ms","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-08T21:42:06.751367Z","caller":"traceutil/trace.go:171","msg":"trace[2038800260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:684; }","duration":"310.517304ms","start":"2026-02-08T21:42:06.440844Z","end":"2026-02-08T21:42:06.751361Z","steps":["trace[2038800260] 'agreement among raft nodes before linearized reading' (duration: 310.465052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:06.751417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.440831Z","time spent":"310.582607ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:06.76534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.308559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:06.765393Z","caller":"traceutil/trace.go:171","msg":"trace[465594116] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"322.380811ms","start":"2026-02-08T21:42:06.443001Z","end":"2026-02-08T21:42:06.765382Z","steps":["trace[465594116] 'agreement among raft nodes before linearized reading' (duration: 322.229196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:06.765417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.442997Z","time spent":"322.416533ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:07.311687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.889205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:07.311837Z","caller":"traceutil/trace.go:171","msg":"trace[1611978334] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:706; }","duration":"428.897672ms","start":"2026-02-08T21:42:06.882923Z","end":"2026-02-08T21:42:07.311821Z","steps":["trace[1611978334] 'read index received' (duration: 101.761339ms)","trace[1611978334] 'applied index is now lower than readState.Index' (duration: 327.135473ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:07.312052Z","caller":"traceutil/trace.go:171","msg":"trace[2070236843] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"557.571228ms","start":"2026-02-08T21:42:06.754469Z","end":"2026-02-08T21:42:07.31204Z","steps":["trace[2070236843] 'process raft request' (duration: 230.202468ms)","trace[2070236843] 'compare' (duration: 326.578885ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:07.312112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.754461Z","time spent":"557.617159ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:07.312234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.477089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:07.312309Z","caller":"traceutil/trace.go:171","msg":"trace[1426104334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"270.589363ms","start":"2026-02-08T21:42:07.041702Z","end":"2026-02-08T21:42:07.312291Z","steps":["trace[1426104334] 'agreement among raft nodes before linearized reading' (duration: 270.430868ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:07.312468Z","caller":"traceutil/trace.go:171","msg":"trace[1200717231] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"544.063678ms","start":"2026-02-08T21:42:06.768393Z","end":"2026-02-08T21:42:07.312457Z","steps":["trace[1200717231] 'process raft request' (duration: 543.379697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:07.312527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.768371Z","time spent":"544.125731ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:07.312755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.824812ms","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-08T21:42:07.312793Z","caller":"traceutil/trace.go:171","msg":"trace[1327139694] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"429.906545ms","start":"2026-02-08T21:42:06.882876Z","end":"2026-02-08T21:42:07.312782Z","steps":["trace[1327139694] 'agreement among raft nodes before linearized reading' (duration: 429.725779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:07.312822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:06.882858Z","time spent":"429.956776ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-08T21:42:07.469358Z","caller":"traceutil/trace.go:171","msg":"trace[810176213] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"151.264755ms","start":"2026-02-08T21:42:07.318067Z","end":"2026-02-08T21:42:07.469332Z","steps":["trace[810176213] 'process raft request' (duration: 138.931462ms)","trace[810176213] 'compare' (duration: 12.112546ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:07.534484Z","caller":"traceutil/trace.go:171","msg":"trace[1485281253] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"193.762548ms","start":"2026-02-08T21:42:07.340706Z","end":"2026-02-08T21:42:07.534468Z","steps":["trace[1485281253] 'process raft request' (duration: 193.639365ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:12.590536Z","caller":"traceutil/trace.go:171","msg":"trace[1902617039] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:724; }","duration":"353.701969ms","start":"2026-02-08T21:42:12.236817Z","end":"2026-02-08T21:42:12.590519Z","steps":["trace[1902617039] 'read index received' (duration: 353.528653ms)","trace[1902617039] 'applied index is now lower than readState.Index' (duration: 172.666µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:12.590701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.868904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-08T21:42:12.590722Z","caller":"traceutil/trace.go:171","msg":"trace[698220509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"353.932556ms","start":"2026-02-08T21:42:12.236783Z","end":"2026-02-08T21:42:12.590716Z","steps":["trace[698220509] 'agreement among raft nodes before linearized reading' (duration: 353.804452ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.590745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.236754Z","time spent":"353.984668ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T21:42:12.590955Z","caller":"traceutil/trace.go:171","msg":"trace[681002016] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"517.487653ms","start":"2026-02-08T21:42:12.07346Z","end":"2026-02-08T21:42:12.590948Z","steps":["trace[681002016] 'process raft request' (duration: 516.953236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:12.591032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:12.073441Z","time spent":"517.529723ms","remote":"127.0.0.1:37870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18047,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:28.989037Z","caller":"traceutil/trace.go:171","msg":"trace[1288570434] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"136.928749ms","start":"2026-02-08T21:42:28.852088Z","end":"2026-02-08T21:42:28.989017Z","steps":["trace[1288570434] 'read index received' (duration: 136.775404ms)","trace[1288570434] 'applied index is now lower than readState.Index' (duration: 152.485µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:28.989297Z","caller":"traceutil/trace.go:171","msg":"trace[352269479] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"320.183401ms","start":"2026-02-08T21:42:28.669103Z","end":"2026-02-08T21:42:28.989286Z","steps":["trace[352269479] 'process raft request' (duration: 319.809909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:28.989396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:28.669088Z","time spent":"320.244594ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:42:28.989732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.621741ms","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-08T21:42:28.989814Z","caller":"traceutil/trace.go:171","msg":"trace[1207115708] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"137.734724ms","start":"2026-02-08T21:42:28.852045Z","end":"2026-02-08T21:42:28.98978Z","steps":["trace[1207115708] 'agreement among raft nodes before linearized reading' (duration: 137.564669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:29.581186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.913474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:42:29.581252Z","caller":"traceutil/trace.go:171","msg":"trace[1976574093] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"264.266992ms","start":"2026-02-08T21:42:29.316974Z","end":"2026-02-08T21:42:29.581241Z","steps":["trace[1976574093] 'read index received' (duration: 25.221563ms)","trace[1976574093] 'applied index is now lower than readState.Index' (duration: 239.044719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:29.581414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.406546ms","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":"warn","ts":"2026-02-08T21:42:29.581466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.481864ms","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-08T21:42:29.581478Z","caller":"traceutil/trace.go:171","msg":"trace[1980354310] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:759; }","duration":"264.51757ms","start":"2026-02-08T21:42:29.316944Z","end":"2026-02-08T21:42:29.581461Z","steps":["trace[1980354310] 'agreement among raft nodes before linearized reading' (duration: 264.387516ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:29.58149Z","caller":"traceutil/trace.go:171","msg":"trace[1241984654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"141.505305ms","start":"2026-02-08T21:42:29.439977Z","end":"2026-02-08T21:42:29.581483Z","steps":["trace[1241984654] 'agreement among raft nodes before linearized reading' (duration: 141.405201ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:29.581689Z","caller":"traceutil/trace.go:171","msg":"trace[1570593646] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"583.739571ms","start":"2026-02-08T21:42:28.99794Z","end":"2026-02-08T21:42:29.581679Z","steps":["trace[1570593646] 'process raft request' (duration: 344.276139ms)","trace[1570593646] 'compare' (duration: 238.842902ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:29.58172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.027412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:29.58174Z","caller":"traceutil/trace.go:171","msg":"trace[1169108727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"137.120635ms","start":"2026-02-08T21:42:29.444614Z","end":"2026-02-08T21:42:29.581734Z","steps":["trace[1169108727] 'agreement among raft nodes before linearized reading' (duration: 137.066983ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:29.581756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:28.997926Z","time spent":"583.780813ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:42:29.720709Z","caller":"traceutil/trace.go:171","msg":"trace[976321142] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"133.555291ms","start":"2026-02-08T21:42:29.587137Z","end":"2026-02-08T21:42:29.720692Z","steps":["trace[976321142] 'process raft request' (duration: 100.405496ms)","trace[976321142] 'compare' (duration: 32.834095ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:29.720805Z","caller":"traceutil/trace.go:171","msg":"trace[1777582013] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"133.157678ms","start":"2026-02-08T21:42:29.587643Z","end":"2026-02-08T21:42:29.720801Z","steps":["trace[1777582013] 'process raft request' (duration: 132.805787ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:29.72086Z","caller":"traceutil/trace.go:171","msg":"trace[1601024705] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"131.874958ms","start":"2026-02-08T21:42:29.588979Z","end":"2026-02-08T21:42:29.720854Z","steps":["trace[1601024705] 'process raft request' (duration: 131.489506ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:33.457188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.021023ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-08T21:42:33.727545Z","caller":"traceutil/trace.go:171","msg":"trace[40616614] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"132.362703ms","start":"2026-02-08T21:42:33.595161Z","end":"2026-02-08T21:42:33.727523Z","steps":["trace[40616614] 'process raft request' (duration: 132.220179ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:33.873849Z","caller":"traceutil/trace.go:171","msg":"trace[316845746] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"125.966349ms","start":"2026-02-08T21:42:33.747861Z","end":"2026-02-08T21:42:33.873827Z","steps":["trace[316845746] 'process raft request' (duration: 125.781114ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:33.876245Z","caller":"traceutil/trace.go:171","msg":"trace[688279553] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"126.557808ms","start":"2026-02-08T21:42:33.749669Z","end":"2026-02-08T21:42:33.876227Z","steps":["trace[688279553] 'process raft request' (duration: 126.521257ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:33.876744Z","caller":"traceutil/trace.go:171","msg":"trace[210952641] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"128.702886ms","start":"2026-02-08T21:42:33.748028Z","end":"2026-02-08T21:42:33.876731Z","steps":["trace[210952641] 'process raft request' (duration: 128.053645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.242472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:47.806493Z","time spent":"435.97636ms","remote":"127.0.0.1:37750","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-08T21:42:48.24273Z","caller":"traceutil/trace.go:171","msg":"trace[553723826] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:896; }","duration":"391.978629ms","start":"2026-02-08T21:42:47.85074Z","end":"2026-02-08T21:42:48.242719Z","steps":["trace[553723826] 'read index received' (duration: 391.975499ms)","trace[553723826] 'applied index is now lower than readState.Index' (duration: 2.48µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:42:48.242837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.085424ms","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-08T21:42:48.242852Z","caller":"traceutil/trace.go:171","msg":"trace[45301638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"392.137665ms","start":"2026-02-08T21:42:47.85071Z","end":"2026-02-08T21:42:48.242847Z","steps":["trace[45301638] 'agreement among raft nodes before linearized reading' (duration: 392.055692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.242865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:47.850694Z","time spent":"392.168416ms","remote":"127.0.0.1:37968","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-08T21:42:48.299677Z","caller":"traceutil/trace.go:171","msg":"trace[485084577] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"219.243195ms","start":"2026-02-08T21:42:48.080419Z","end":"2026-02-08T21:42:48.299662Z","steps":["trace[485084577] 'process raft request' (duration: 219.141422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.300015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.997287ms","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-08T21:42:48.300042Z","caller":"traceutil/trace.go:171","msg":"trace[243176686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:865; }","duration":"340.048018ms","start":"2026-02-08T21:42:47.959989Z","end":"2026-02-08T21:42:48.300037Z","steps":["trace[243176686] 'agreement among raft nodes before linearized reading' (duration: 339.961815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.300057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:47.959978Z","time spent":"340.075259ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:42:48.30014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.833371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:42:48.300152Z","caller":"traceutil/trace.go:171","msg":"trace[1249223589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:865; }","duration":"328.857743ms","start":"2026-02-08T21:42:47.97129Z","end":"2026-02-08T21:42:48.300148Z","steps":["trace[1249223589] 'agreement among raft nodes before linearized reading' (duration: 328.821381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.300162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:47.971283Z","time spent":"328.877193ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-08T21:42:48.300233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.937135ms","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-08T21:42:48.300244Z","caller":"traceutil/trace.go:171","msg":"trace[1782374916] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:865; }","duration":"334.956287ms","start":"2026-02-08T21:42:47.965285Z","end":"2026-02-08T21:42:48.300241Z","steps":["trace[1782374916] 'agreement among raft nodes before linearized reading' (duration: 334.921545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:48.300255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:42:47.96528Z","time spent":"334.972287ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-08T21:42:48.388875Z","caller":"traceutil/trace.go:171","msg":"trace[1221650414] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"143.476375ms","start":"2026-02-08T21:42:48.245385Z","end":"2026-02-08T21:42:48.388861Z","steps":["trace[1221650414] 'process raft request' (duration: 143.446564ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:48.389022Z","caller":"traceutil/trace.go:171","msg":"trace[811971502] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"145.882051ms","start":"2026-02-08T21:42:48.243134Z","end":"2026-02-08T21:42:48.389017Z","steps":["trace[811971502] 'process raft request' (duration: 145.617912ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:54.646943Z","caller":"traceutil/trace.go:171","msg":"trace[627007430] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"222.611072ms","start":"2026-02-08T21:42:54.424317Z","end":"2026-02-08T21:42:54.646928Z","steps":["trace[627007430] 'read index received' (duration: 222.519219ms)","trace[627007430] 'applied index is now lower than readState.Index' (duration: 91.203µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:42:54.647034Z","caller":"traceutil/trace.go:171","msg":"trace[238137281] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"228.876581ms","start":"2026-02-08T21:42:54.418149Z","end":"2026-02-08T21:42:54.647026Z","steps":["trace[238137281] 'process raft request' (duration: 228.669644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:54.647194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.8641ms","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-08T21:42:54.647211Z","caller":"traceutil/trace.go:171","msg":"trace[2113423372] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:883; }","duration":"222.904412ms","start":"2026-02-08T21:42:54.424301Z","end":"2026-02-08T21:42:54.647206Z","steps":["trace[2113423372] 'agreement among raft nodes before linearized reading' (duration: 222.815879ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:54.647364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.392929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-08T21:42:54.647404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.020788ms","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-08T21:42:54.647422Z","caller":"traceutil/trace.go:171","msg":"trace[1471610182] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"219.045818ms","start":"2026-02-08T21:42:54.428371Z","end":"2026-02-08T21:42:54.647417Z","steps":["trace[1471610182] 'agreement among raft nodes before linearized reading' (duration: 219.004157ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:54.647422Z","caller":"traceutil/trace.go:171","msg":"trace[73993267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:883; }","duration":"219.466532ms","start":"2026-02-08T21:42:54.427944Z","end":"2026-02-08T21:42:54.64741Z","steps":["trace[73993267] 'agreement among raft nodes before linearized reading' (duration: 219.326958ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:58.895534Z","caller":"traceutil/trace.go:171","msg":"trace[822852341] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"218.16733ms","start":"2026-02-08T21:42:58.677351Z","end":"2026-02-08T21:42:58.895518Z","steps":["trace[822852341] 'process raft request' (duration: 218.075737ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:42:58.921051Z","caller":"traceutil/trace.go:171","msg":"trace[73978936] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"213.163071ms","start":"2026-02-08T21:42:58.707874Z","end":"2026-02-08T21:42:58.921037Z","steps":["trace[73978936] 'process raft request' (duration: 213.095299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:42:59.145528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.219981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:42:59.145575Z","caller":"traceutil/trace.go:171","msg":"trace[1808824893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"101.313024ms","start":"2026-02-08T21:42:59.044251Z","end":"2026-02-08T21:42:59.145564Z","steps":["trace[1808824893] 'range keys from in-memory index tree' (duration: 101.125847ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:00.858024Z","caller":"traceutil/trace.go:171","msg":"trace[1704631856] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:933; }","duration":"150.070744ms","start":"2026-02-08T21:43:00.707937Z","end":"2026-02-08T21:43:00.858008Z","steps":["trace[1704631856] 'read index received' (duration: 149.974391ms)","trace[1704631856] 'applied index is now lower than readState.Index' (duration: 95.683µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:00.858135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.190118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:00.858151Z","caller":"traceutil/trace.go:171","msg":"trace[1232596592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"150.232579ms","start":"2026-02-08T21:43:00.707913Z","end":"2026-02-08T21:43:00.858146Z","steps":["trace[1232596592] 'agreement among raft nodes before linearized reading' (duration: 150.149767ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:00.858217Z","caller":"traceutil/trace.go:171","msg":"trace[1564856211] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"176.408692ms","start":"2026-02-08T21:43:00.68179Z","end":"2026-02-08T21:43:00.858199Z","steps":["trace[1564856211] 'process raft request' (duration: 176.130373ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:01.594708Z","caller":"traceutil/trace.go:171","msg":"trace[622848512] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"338.965283ms","start":"2026-02-08T21:43:01.255726Z","end":"2026-02-08T21:43:01.594692Z","steps":["trace[622848512] 'process raft request' (duration: 338.88123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:01.594897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:01.255718Z","time spent":"339.134779ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:07.024091Z","caller":"traceutil/trace.go:171","msg":"trace[1801568970] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"150.328652ms","start":"2026-02-08T21:43:06.873748Z","end":"2026-02-08T21:43:07.024077Z","steps":["trace[1801568970] 'process raft request' (duration: 149.920009ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:07.02441Z","caller":"traceutil/trace.go:171","msg":"trace[1188967591] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"141.058817ms","start":"2026-02-08T21:43:06.882772Z","end":"2026-02-08T21:43:07.023831Z","steps":["trace[1188967591] 'read index received' (duration: 140.948084ms)","trace[1188967591] 'applied index is now lower than readState.Index' (duration: 109.753µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:07.024579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.78578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-08T21:43:07.024613Z","caller":"traceutil/trace.go:171","msg":"trace[1338725170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:936; }","duration":"141.844482ms","start":"2026-02-08T21:43:06.882757Z","end":"2026-02-08T21:43:07.024601Z","steps":["trace[1338725170] 'agreement among raft nodes before linearized reading' (duration: 141.724308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:07.024831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.794982ms","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-08T21:43:07.02486Z","caller":"traceutil/trace.go:171","msg":"trace[624617201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:936; }","duration":"103.849024ms","start":"2026-02-08T21:43:06.921003Z","end":"2026-02-08T21:43:07.024852Z","steps":["trace[624617201] 'agreement among raft nodes before linearized reading' (duration: 103.766591ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:16.262646Z","caller":"traceutil/trace.go:171","msg":"trace[1114657151] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"248.230977ms","start":"2026-02-08T21:43:16.014395Z","end":"2026-02-08T21:43:16.262625Z","steps":["trace[1114657151] 'read index received' (duration: 165.813435ms)","trace[1114657151] 'applied index is now lower than readState.Index' (duration: 82.416392ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:16.262886Z","caller":"traceutil/trace.go:171","msg":"trace[583543083] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"260.202918ms","start":"2026-02-08T21:43:16.002671Z","end":"2026-02-08T21:43:16.262874Z","steps":["trace[583543083] 'process raft request' (duration: 177.5968ms)","trace[583543083] 'compare' (duration: 81.73497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:16.263076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.666261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:16.263115Z","caller":"traceutil/trace.go:171","msg":"trace[55581686] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:994; }","duration":"248.740573ms","start":"2026-02-08T21:43:16.014366Z","end":"2026-02-08T21:43:16.263106Z","steps":["trace[55581686] 'agreement among raft nodes before linearized reading' (duration: 248.672761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:16.26327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.761569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:16.263314Z","caller":"traceutil/trace.go:171","msg":"trace[2131370620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:994; }","duration":"218.823721ms","start":"2026-02-08T21:43:16.044479Z","end":"2026-02-08T21:43:16.263303Z","steps":["trace[2131370620] 'agreement among raft nodes before linearized reading' (duration: 218.76935ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:18.687312Z","caller":"traceutil/trace.go:171","msg":"trace[199952199] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"366.755628ms","start":"2026-02-08T21:43:18.320532Z","end":"2026-02-08T21:43:18.687287Z","steps":["trace[199952199] 'process raft request' (duration: 366.633414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:18.687486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:43:18.320442Z","time spent":"366.957234ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:43:19.191433Z","caller":"traceutil/trace.go:171","msg":"trace[1337700344] linearizableReadLoop","detail":"{readStateIndex:1049; appliedIndex:1048; }","duration":"146.718108ms","start":"2026-02-08T21:43:19.044688Z","end":"2026-02-08T21:43:19.191406Z","steps":["trace[1337700344] 'read index received' (duration: 122.655572ms)","trace[1337700344] 'applied index is now lower than readState.Index' (duration: 24.061326ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:43:19.191691Z","caller":"traceutil/trace.go:171","msg":"trace[1714128051] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"293.003961ms","start":"2026-02-08T21:43:18.898673Z","end":"2026-02-08T21:43:19.191677Z","steps":["trace[1714128051] 'process raft request' (duration: 268.706418ms)","trace[1714128051] 'compare' (duration: 23.918231ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:43:19.191899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.196423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:19.19194Z","caller":"traceutil/trace.go:171","msg":"trace[859526145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"147.311417ms","start":"2026-02-08T21:43:19.044618Z","end":"2026-02-08T21:43:19.191929Z","steps":["trace[859526145] 'agreement among raft nodes before linearized reading' (duration: 147.242035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:19.192088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.550796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:43:19.192122Z","caller":"traceutil/trace.go:171","msg":"trace[1898175354] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1011; }","duration":"137.631779ms","start":"2026-02-08T21:43:19.054483Z","end":"2026-02-08T21:43:19.192114Z","steps":["trace[1898175354] 'agreement among raft nodes before linearized reading' (duration: 137.577687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:19.19421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.793078ms","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-08T21:43:19.194368Z","caller":"traceutil/trace.go:171","msg":"trace[1947755780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"113.028546ms","start":"2026-02-08T21:43:19.081299Z","end":"2026-02-08T21:43:19.194328Z","steps":["trace[1947755780] 'agreement among raft nodes before linearized reading' (duration: 112.574612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:19.194681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.927426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-08T21:43:19.194823Z","caller":"traceutil/trace.go:171","msg":"trace[2022087707] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1011; }","duration":"115.0492ms","start":"2026-02-08T21:43:19.079725Z","end":"2026-02-08T21:43:19.194774Z","steps":["trace[2022087707] 'agreement among raft nodes before linearized reading' (duration: 114.884855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:43:19.195161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.711716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T21:43:19.195781Z","caller":"traceutil/trace.go:171","msg":"trace[2115792891] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"130.353177ms","start":"2026-02-08T21:43:19.065418Z","end":"2026-02-08T21:43:19.195771Z","steps":["trace[2115792891] 'agreement among raft nodes before linearized reading' (duration: 129.585012ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.3149Z","caller":"traceutil/trace.go:171","msg":"trace[116647403] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"113.982896ms","start":"2026-02-08T21:43:19.200896Z","end":"2026-02-08T21:43:19.314878Z","steps":["trace[116647403] 'process raft request' (duration: 107.014784ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.329844Z","caller":"traceutil/trace.go:171","msg":"trace[1272141261] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"125.202833ms","start":"2026-02-08T21:43:19.204617Z","end":"2026-02-08T21:43:19.32982Z","steps":["trace[1272141261] 'process raft request' (duration: 125.150712ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.330576Z","caller":"traceutil/trace.go:171","msg":"trace[133143847] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"126.228565ms","start":"2026-02-08T21:43:19.204319Z","end":"2026-02-08T21:43:19.330547Z","steps":["trace[133143847] 'process raft request' (duration: 125.230814ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.330915Z","caller":"traceutil/trace.go:171","msg":"trace[1673848260] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"125.922076ms","start":"2026-02-08T21:43:19.204665Z","end":"2026-02-08T21:43:19.330587Z","steps":["trace[1673848260] 'process raft request' (duration: 125.075089ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:43:19.33123Z","caller":"traceutil/trace.go:171","msg":"trace[1556725569] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"126.708051ms","start":"2026-02-08T21:43:19.204512Z","end":"2026-02-08T21:43:19.33122Z","steps":["trace[1556725569] 'process raft request' (duration: 125.148021ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:45:46.027416Z","caller":"traceutil/trace.go:171","msg":"trace[1113598702] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"122.207997ms","start":"2026-02-08T21:45:45.905187Z","end":"2026-02-08T21:45:46.027395Z","steps":["trace[1113598702] 'process raft request' (duration: 121.799714ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:22.895653Z","caller":"traceutil/trace.go:171","msg":"trace[633414873] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"358.826046ms","start":"2026-02-08T21:46:22.536807Z","end":"2026-02-08T21:46:22.895633Z","steps":["trace[633414873] 'process raft request' (duration: 358.601788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:22.895777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:22.536792Z","time spent":"358.915308ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:46:22.895834Z","caller":"traceutil/trace.go:171","msg":"trace[646969055] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"357.820824ms","start":"2026-02-08T21:46:22.538002Z","end":"2026-02-08T21:46:22.895823Z","steps":["trace[646969055] 'read index received' (duration: 357.357059ms)","trace[646969055] 'applied index is now lower than readState.Index' (duration: 462.745µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:22.896339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.336461ms","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":"info","ts":"2026-02-08T21:46:22.897742Z","caller":"traceutil/trace.go:171","msg":"trace[1235920296] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1789; }","duration":"359.750085ms","start":"2026-02-08T21:46:22.537972Z","end":"2026-02-08T21:46:22.897722Z","steps":["trace[1235920296] 'agreement among raft nodes before linearized reading' (duration: 357.957858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:22.898105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:22.537963Z","time spent":"360.123216ms","remote":"127.0.0.1:37968","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-08T21:46:22.900931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.790957ms","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-08T21:46:22.901113Z","caller":"traceutil/trace.go:171","msg":"trace[920183945] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1789; }","duration":"235.009754ms","start":"2026-02-08T21:46:22.666086Z","end":"2026-02-08T21:46:22.901095Z","steps":["trace[920183945] 'agreement among raft nodes before linearized reading' (duration: 234.631632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:22.901775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.949415ms","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-08T21:46:22.901835Z","caller":"traceutil/trace.go:171","msg":"trace[1105626284] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1789; }","duration":"320.046318ms","start":"2026-02-08T21:46:22.581774Z","end":"2026-02-08T21:46:22.90182Z","steps":["trace[1105626284] 'agreement among raft nodes before linearized reading' (duration: 318.762347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:46:22.902064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:46:22.581757Z","time spent":"320.289256ms","remote":"127.0.0.1:37968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-08T21:46:25.84395Z","caller":"traceutil/trace.go:171","msg":"trace[1816420333] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"219.10592ms","start":"2026-02-08T21:46:25.624817Z","end":"2026-02-08T21:46:25.843922Z","steps":["trace[1816420333] 'process raft request' (duration: 218.992886ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:26.162934Z","caller":"traceutil/trace.go:171","msg":"trace[1124478601] linearizableReadLoop","detail":"{readStateIndex:1879; appliedIndex:1878; }","duration":"117.518682ms","start":"2026-02-08T21:46:26.045392Z","end":"2026-02-08T21:46:26.16291Z","steps":["trace[1124478601] 'read index received' (duration: 117.299625ms)","trace[1124478601] 'applied index is now lower than readState.Index' (duration: 218.267µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:46:26.16311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.700858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:46:26.163139Z","caller":"traceutil/trace.go:171","msg":"trace[1910467230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1803; }","duration":"117.798071ms","start":"2026-02-08T21:46:26.045332Z","end":"2026-02-08T21:46:26.16313Z","steps":["trace[1910467230] 'agreement among raft nodes before linearized reading' (duration: 117.670066ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:46:26.163764Z","caller":"traceutil/trace.go:171","msg":"trace[1932153336] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"278.691777ms","start":"2026-02-08T21:46:25.885059Z","end":"2026-02-08T21:46:26.163751Z","steps":["trace[1932153336] 'process raft request' (duration: 277.726697ms)"],"step_count":1}