{"level":"warn","ts":"2026-02-21T02:35:53.411329Z","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-21T02:35:53.411426Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.99: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://199.19.213.99:2380","--initial-cluster=instance=https://199.19.213.99:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.99:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.99: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-21T02:35:53.411505Z","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-21T02:35:53.411516Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-02-21T02:35:53.411541Z","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-21T02:35:53.412531Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"]} {"level":"info","ts":"2026-02-21T02:35:53.412618Z","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://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.99: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-21T02:35:53.421042Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.180768ms"} {"level":"info","ts":"2026-02-21T02:35:53.432351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4da37caf00b87dfe","cluster-id":"221bed930aa36d2e"} {"level":"info","ts":"2026-02-21T02:35:53.432422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=()"} {"level":"info","ts":"2026-02-21T02:35:53.432442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 0"} {"level":"info","ts":"2026-02-21T02:35:53.43245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4da37caf00b87dfe [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-21T02:35:53.432463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became follower at term 1"} {"level":"info","ts":"2026-02-21T02:35:53.432495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"warn","ts":"2026-02-21T02:35:53.442483Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-21T02:35:53.44471Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-21T02:35:53.446815Z","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-21T02:35:53.459489Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4da37caf00b87dfe","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-21T02:35:53.459621Z","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-21T02:35:53.459695Z","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-21T02:35:53.459712Z","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-21T02:35:53.459994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4da37caf00b87dfe","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-21T02:35:53.461968Z","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-21T02:35:53.462171Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4da37caf00b87dfe","initial-advertise-peer-urls":["https://199.19.213.99:2380"],"listen-peer-urls":["https://199.19.213.99:2380"],"advertise-client-urls":["https://199.19.213.99:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.99:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-21T02:35:53.462205Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-21T02:35:53.462311Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-02-21T02:35:53.462328Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.99:2380"} {"level":"info","ts":"2026-02-21T02:35:53.463733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe switched to configuration voters=(5594452253197499902)"} {"level":"info","ts":"2026-02-21T02:35:53.463832Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","added-peer-id":"4da37caf00b87dfe","added-peer-peer-urls":["https://199.19.213.99:2380"]} {"level":"info","ts":"2026-02-21T02:35:54.033627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe is starting a new election at term 1"} {"level":"info","ts":"2026-02-21T02:35:54.033728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became pre-candidate at term 1"} {"level":"info","ts":"2026-02-21T02:35:54.033762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgPreVoteResp from 4da37caf00b87dfe at term 1"} {"level":"info","ts":"2026-02-21T02:35:54.03379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became candidate at term 2"} {"level":"info","ts":"2026-02-21T02:35:54.033826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe received MsgVoteResp from 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-02-21T02:35:54.033847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4da37caf00b87dfe became leader at term 2"} {"level":"info","ts":"2026-02-21T02:35:54.033872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4da37caf00b87dfe elected leader 4da37caf00b87dfe at term 2"} {"level":"info","ts":"2026-02-21T02:35:54.091799Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4da37caf00b87dfe","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.99:2379]}","request-path":"/0/members/4da37caf00b87dfe/attributes","cluster-id":"221bed930aa36d2e","publish-timeout":"7s"} {"level":"info","ts":"2026-02-21T02:35:54.099439Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T02:35:54.099542Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-21T02:35:54.099539Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:35:54.099578Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-21T02:35:54.099457Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-21T02:35:54.102325Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"221bed930aa36d2e","local-member-id":"4da37caf00b87dfe","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:35:54.102541Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:35:54.102582Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-21T02:35:54.104745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.99:2379"} {"level":"info","ts":"2026-02-21T02:35:54.106176Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-21T02:36:15.643695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.697487ms","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-21T02:36:15.643934Z","caller":"traceutil/trace.go:171","msg":"trace[835171972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:451; }","duration":"725.992165ms","start":"2026-02-21T02:36:14.917914Z","end":"2026-02-21T02:36:15.643906Z","steps":["trace[835171972] 'range keys from in-memory index tree' (duration: 725.571265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:15.643998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:14.917899Z","time spent":"726.086778ms","remote":"127.0.0.1:55698","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-21T02:36:15.644365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.541401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:15.644467Z","caller":"traceutil/trace.go:171","msg":"trace[164045603] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:451; }","duration":"253.722856ms","start":"2026-02-21T02:36:15.390717Z","end":"2026-02-21T02:36:15.64444Z","steps":["trace[164045603] 'range keys from in-memory index tree' (duration: 253.45497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:20.822349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.400101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-21T02:36:20.82246Z","caller":"traceutil/trace.go:171","msg":"trace[1605824894] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:466; }","duration":"190.551305ms","start":"2026-02-21T02:36:20.631881Z","end":"2026-02-21T02:36:20.822432Z","steps":["trace[1605824894] 'range keys from in-memory index tree' (duration: 190.260527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:22.180555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035267606,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:36:22.378605Z","caller":"traceutil/trace.go:171","msg":"trace[1585694864] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"703.185569ms","start":"2026-02-21T02:36:21.67539Z","end":"2026-02-21T02:36:22.378576Z","steps":["trace[1585694864] 'process raft request' (duration: 702.961453ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:22.379709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:21.675382Z","time spent":"703.336192ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:22.499244Z","caller":"traceutil/trace.go:171","msg":"trace[1816724953] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:483; }","duration":"819.691947ms","start":"2026-02-21T02:36:21.679529Z","end":"2026-02-21T02:36:22.499221Z","steps":["trace[1816724953] 'read index received' (duration: 698.784528ms)","trace[1816724953] 'applied index is now lower than readState.Index' (duration: 120.905389ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:22.499259Z","caller":"traceutil/trace.go:171","msg":"trace[1409401068] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"821.967734ms","start":"2026-02-21T02:36:21.677261Z","end":"2026-02-21T02:36:22.499229Z","steps":["trace[1409401068] 'process raft request' (duration: 808.849543ms)","trace[1409401068] 'compare' (duration: 12.903056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:22.499513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:21.677253Z","time spent":"822.083317ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:22.504361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.366985ms","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-21T02:36:22.504448Z","caller":"traceutil/trace.go:171","msg":"trace[990177174] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"519.480978ms","start":"2026-02-21T02:36:21.984923Z","end":"2026-02-21T02:36:22.504404Z","steps":["trace[990177174] 'agreement among raft nodes before linearized reading' (duration: 519.375305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:22.504476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:21.984898Z","time spent":"519.57168ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T02:36:22.504599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.42092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:22.504624Z","caller":"traceutil/trace.go:171","msg":"trace[1949719925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:468; }","duration":"115.444471ms","start":"2026-02-21T02:36:22.389174Z","end":"2026-02-21T02:36:22.504618Z","steps":["trace[1949719925] 'agreement among raft nodes before linearized reading' (duration: 115.41254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:22.504747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.199776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:22.504849Z","caller":"traceutil/trace.go:171","msg":"trace[48893771] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:468; }","duration":"825.272237ms","start":"2026-02-21T02:36:21.679517Z","end":"2026-02-21T02:36:22.50479Z","steps":["trace[48893771] 'agreement among raft nodes before linearized reading' (duration: 819.82818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:22.504929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:21.679512Z","time spent":"825.3692ms","remote":"127.0.0.1:55648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-02-21T02:36:24.51281Z","caller":"traceutil/trace.go:171","msg":"trace[693006189] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"121.015402ms","start":"2026-02-21T02:36:24.391765Z","end":"2026-02-21T02:36:24.51278Z","steps":["trace[693006189] 'read index received' (duration: 120.753436ms)","trace[693006189] 'applied index is now lower than readState.Index' (duration: 260.826µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:24.512867Z","caller":"traceutil/trace.go:171","msg":"trace[928206817] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"121.149835ms","start":"2026-02-21T02:36:24.391669Z","end":"2026-02-21T02:36:24.512818Z","steps":["trace[928206817] 'process raft request' (duration: 120.94472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:24.512991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.204097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:24.51303Z","caller":"traceutil/trace.go:171","msg":"trace[1389929643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"121.285139ms","start":"2026-02-21T02:36:24.391733Z","end":"2026-02-21T02:36:24.513018Z","steps":["trace[1389929643] 'agreement among raft nodes before linearized reading' (duration: 121.208187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:25.370344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.79066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:36:25.370479Z","caller":"traceutil/trace.go:171","msg":"trace[1023776772] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"854.157836ms","start":"2026-02-21T02:36:24.516295Z","end":"2026-02-21T02:36:25.370453Z","steps":["trace[1023776772] 'process raft request' (duration: 479.034538ms)","trace[1023776772] 'compare' (duration: 374.664327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:25.370573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:24.516275Z","time spent":"854.250809ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:25.382232Z","caller":"traceutil/trace.go:171","msg":"trace[557782416] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"863.965483ms","start":"2026-02-21T02:36:24.518249Z","end":"2026-02-21T02:36:25.382215Z","steps":["trace[557782416] 'process raft request' (duration: 863.929522ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:25.382361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:24.518236Z","time spent":"864.057845ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:25.382472Z","caller":"traceutil/trace.go:171","msg":"trace[102875224] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"865.488203ms","start":"2026-02-21T02:36:24.516977Z","end":"2026-02-21T02:36:25.382465Z","steps":["trace[102875224] 'process raft request' (duration: 865.103843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:25.382558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:24.516953Z","time spent":"865.539823ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:31.724894Z","caller":"traceutil/trace.go:171","msg":"trace[3923680] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"329.028096ms","start":"2026-02-21T02:36:31.395833Z","end":"2026-02-21T02:36:31.724861Z","steps":["trace[3923680] 'process raft request' (duration: 328.882772ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:31.725031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.996272ms","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-21T02:36:31.725059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:31.39581Z","time spent":"329.164189ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:31.725098Z","caller":"traceutil/trace.go:171","msg":"trace[65114261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"321.112295ms","start":"2026-02-21T02:36:31.403974Z","end":"2026-02-21T02:36:31.725086Z","steps":["trace[65114261] 'agreement among raft nodes before linearized reading' (duration: 320.965902ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:31.725142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:31.403961Z","time spent":"321.172576ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-21T02:36:31.724888Z","caller":"traceutil/trace.go:171","msg":"trace[372531960] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"320.827848ms","start":"2026-02-21T02:36:31.404011Z","end":"2026-02-21T02:36:31.724839Z","steps":["trace[372531960] 'read index received' (duration: 320.679014ms)","trace[372531960] 'applied index is now lower than readState.Index' (duration: 147.884µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:31.725549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.745806ms","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-21T02:36:31.725575Z","caller":"traceutil/trace.go:171","msg":"trace[1221096299] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"316.791197ms","start":"2026-02-21T02:36:31.408777Z","end":"2026-02-21T02:36:31.725569Z","steps":["trace[1221096299] 'agreement among raft nodes before linearized reading' (duration: 316.743996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:31.725593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:31.408767Z","time spent":"316.821948ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T02:36:31.850281Z","caller":"traceutil/trace.go:171","msg":"trace[819401400] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"121.77631ms","start":"2026-02-21T02:36:31.728477Z","end":"2026-02-21T02:36:31.850253Z","steps":["trace[819401400] 'process raft request' (duration: 100.324089ms)","trace[819401400] 'compare' (duration: 21.315508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:32.344098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.099819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:36:32.344254Z","caller":"traceutil/trace.go:171","msg":"trace[1014617794] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"613.59817ms","start":"2026-02-21T02:36:31.730616Z","end":"2026-02-21T02:36:32.344214Z","steps":["trace[1014617794] 'process raft request' (duration: 439.299526ms)","trace[1014617794] 'compare' (duration: 173.908615ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:32.344431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:31.730591Z","time spent":"613.746724ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:32.344202Z","caller":"traceutil/trace.go:171","msg":"trace[934841652] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"470.489433ms","start":"2026-02-21T02:36:31.873695Z","end":"2026-02-21T02:36:32.344185Z","steps":["trace[934841652] 'read index received' (duration: 296.155267ms)","trace[934841652] 'applied index is now lower than readState.Index' (duration: 174.332556ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:32.345325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.617731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lzpzp\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-21T02:36:32.34538Z","caller":"traceutil/trace.go:171","msg":"trace[160548346] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lzpzp; range_end:; response_count:1; response_revision:528; }","duration":"471.716544ms","start":"2026-02-21T02:36:31.873651Z","end":"2026-02-21T02:36:32.345367Z","steps":["trace[160548346] 'agreement among raft nodes before linearized reading' (duration: 470.678027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:32.345417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:31.873634Z","time spent":"471.772216ms","remote":"127.0.0.1:55634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-lzpzp\" "} {"level":"info","ts":"2026-02-21T02:36:32.517703Z","caller":"traceutil/trace.go:171","msg":"trace[2143189809] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"126.680194ms","start":"2026-02-21T02:36:32.390996Z","end":"2026-02-21T02:36:32.517676Z","steps":["trace[2143189809] 'read index received' (duration: 126.413207ms)","trace[2143189809] 'applied index is now lower than readState.Index' (duration: 266.067µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:32.517844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.805977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:32.517846Z","caller":"traceutil/trace.go:171","msg":"trace[1571530046] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"159.503442ms","start":"2026-02-21T02:36:32.358324Z","end":"2026-02-21T02:36:32.517827Z","steps":["trace[1571530046] 'process raft request' (duration: 159.173674ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:32.51788Z","caller":"traceutil/trace.go:171","msg":"trace[1183031962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"126.90886ms","start":"2026-02-21T02:36:32.390958Z","end":"2026-02-21T02:36:32.517867Z","steps":["trace[1183031962] 'agreement among raft nodes before linearized reading' (duration: 126.810987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:33.020083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035267730,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:36:33.336072Z","caller":"traceutil/trace.go:171","msg":"trace[1394354987] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"816.904686ms","start":"2026-02-21T02:36:32.519146Z","end":"2026-02-21T02:36:33.336051Z","steps":["trace[1394354987] 'read index received' (duration: 734.332594ms)","trace[1394354987] 'applied index is now lower than readState.Index' (duration: 82.570982ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:33.336221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.04759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:33.336262Z","caller":"traceutil/trace.go:171","msg":"trace[941495222] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"817.140192ms","start":"2026-02-21T02:36:32.51911Z","end":"2026-02-21T02:36:33.33625Z","steps":["trace[941495222] 'agreement among raft nodes before linearized reading' (duration: 817.050619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:33.336296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.63364ms","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-21T02:36:33.336354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:32.519094Z","time spent":"817.194003ms","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T02:36:33.336379Z","caller":"traceutil/trace.go:171","msg":"trace[1379634879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"795.766723ms","start":"2026-02-21T02:36:32.540592Z","end":"2026-02-21T02:36:33.336359Z","steps":["trace[1379634879] 'agreement among raft nodes before linearized reading' (duration: 795.599019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:33.336422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:32.540569Z","time spent":"795.843366ms","remote":"127.0.0.1:55698","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-21T02:36:34.354777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035267734,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-21T02:36:34.743385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.01019418s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T02:36:34.743941Z","caller":"traceutil/trace.go:171","msg":"trace[2041147015] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:550; }","duration":"889.238081ms","start":"2026-02-21T02:36:33.854677Z","end":"2026-02-21T02:36:34.743915Z","steps":["trace[2041147015] 'read index received' (duration: 888.937263ms)","trace[2041147015] 'applied index is now lower than readState.Index' (duration: 299.608µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:34.743996Z","caller":"traceutil/trace.go:171","msg":"trace[963386628] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.010884178s","start":"2026-02-21T02:36:33.733082Z","end":"2026-02-21T02:36:34.743966Z","steps":["trace[963386628] 'process raft request' (duration: 1.01056658s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:34.744174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.248455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-21T02:36:34.744187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.490747ms","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-21T02:36:34.744207Z","caller":"traceutil/trace.go:171","msg":"trace[1632457404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:531; }","duration":"395.341308ms","start":"2026-02-21T02:36:34.348856Z","end":"2026-02-21T02:36:34.744198Z","steps":["trace[1632457404] 'agreement among raft nodes before linearized reading' (duration: 395.196524ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:34.744228Z","caller":"traceutil/trace.go:171","msg":"trace[2087851035] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:531; }","duration":"889.60035ms","start":"2026-02-21T02:36:33.854618Z","end":"2026-02-21T02:36:34.744219Z","steps":["trace[2087851035] 'agreement among raft nodes before linearized reading' (duration: 889.403615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:34.744244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:34.348833Z","time spent":"395.40372ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T02:36:34.744256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:33.854596Z","time spent":"889.654711ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T02:36:34.744255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.409326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:34.744305Z","caller":"traceutil/trace.go:171","msg":"trace[2077033035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"354.486958ms","start":"2026-02-21T02:36:34.389799Z","end":"2026-02-21T02:36:34.744286Z","steps":["trace[2077033035] 'agreement among raft nodes before linearized reading' (duration: 354.406026ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:34.744357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:34.38979Z","time spent":"354.55936ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T02:36:34.744432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:33.73306Z","time spent":"1.011118513s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:35.816804Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068228093s","expected-duration":"1s"} {"level":"info","ts":"2026-02-21T02:36:35.81716Z","caller":"traceutil/trace.go:171","msg":"trace[1958422806] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.068677865s","start":"2026-02-21T02:36:34.748458Z","end":"2026-02-21T02:36:35.817136Z","steps":["trace[1958422806] 'process raft request' (duration: 1.068555722s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:35.817347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:34.748427Z","time spent":"1.068847869s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:35.819835Z","caller":"traceutil/trace.go:171","msg":"trace[1753467877] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"427.510069ms","start":"2026-02-21T02:36:35.39229Z","end":"2026-02-21T02:36:35.8198Z","steps":["trace[1753467877] 'read index received' (duration: 424.931324ms)","trace[1753467877] 'applied index is now lower than readState.Index' (duration: 2.577765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:35.820029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.715684ms","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-21T02:36:35.820099Z","caller":"traceutil/trace.go:171","msg":"trace[1773456444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:533; }","duration":"427.835458ms","start":"2026-02-21T02:36:35.392243Z","end":"2026-02-21T02:36:35.820078Z","steps":["trace[1773456444] 'agreement among raft nodes before linearized reading' (duration: 427.687574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:35.820153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:35.392222Z","time spent":"427.917709ms","remote":"127.0.0.1:55698","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":"info","ts":"2026-02-21T02:36:35.820245Z","caller":"traceutil/trace.go:171","msg":"trace[1813267270] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.071270159s","start":"2026-02-21T02:36:34.748947Z","end":"2026-02-21T02:36:35.820217Z","steps":["trace[1813267270] 'process raft request' (duration: 1.070725895s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:35.82046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:34.74893Z","time spent":"1.071457004s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:38.391183Z","caller":"traceutil/trace.go:171","msg":"trace[527632156] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"171.190206ms","start":"2026-02-21T02:36:38.219966Z","end":"2026-02-21T02:36:38.391157Z","steps":["trace[527632156] 'process raft request' (duration: 170.980111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:54.453913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035268100,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:36:54.467394Z","caller":"traceutil/trace.go:171","msg":"trace[1229081540] linearizableReadLoop","detail":"{readStateIndex:752; appliedIndex:751; }","duration":"514.471111ms","start":"2026-02-21T02:36:53.952903Z","end":"2026-02-21T02:36:54.467374Z","steps":["trace[1229081540] 'read index received' (duration: 514.324597ms)","trace[1229081540] 'applied index is now lower than readState.Index' (duration: 145.934µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:54.467583Z","caller":"traceutil/trace.go:171","msg":"trace[2122586652] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"515.546129ms","start":"2026-02-21T02:36:53.952028Z","end":"2026-02-21T02:36:54.467574Z","steps":["trace[2122586652] 'process raft request' (duration: 515.24701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:54.467663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:53.952011Z","time spent":"515.595019ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:54.467944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.814295ms","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-21T02:36:54.468003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.415719ms","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-21T02:36:54.46796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.319413ms","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-21T02:36:54.468019Z","caller":"traceutil/trace.go:171","msg":"trace[1259077593] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:727; }","duration":"112.922297ms","start":"2026-02-21T02:36:54.355074Z","end":"2026-02-21T02:36:54.467996Z","steps":["trace[1259077593] 'agreement among raft nodes before linearized reading' (duration: 112.800364ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:54.468038Z","caller":"traceutil/trace.go:171","msg":"trace[1343568390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"508.485661ms","start":"2026-02-21T02:36:53.959541Z","end":"2026-02-21T02:36:54.468026Z","steps":["trace[1343568390] 'agreement among raft nodes before linearized reading' (duration: 508.360088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:54.468067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:53.959521Z","time spent":"508.539732ms","remote":"127.0.0.1:55698","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":"info","ts":"2026-02-21T02:36:54.468086Z","caller":"traceutil/trace.go:171","msg":"trace[1692045959] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"513.485797ms","start":"2026-02-21T02:36:53.954564Z","end":"2026-02-21T02:36:54.468049Z","steps":["trace[1692045959] 'agreement among raft nodes before linearized reading' (duration: 513.105568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:54.468146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:53.954553Z","time spent":"513.58151ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T02:36:54.468223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.305632ms","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-21T02:36:54.468321Z","caller":"traceutil/trace.go:171","msg":"trace[1333060079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:727; }","duration":"515.430366ms","start":"2026-02-21T02:36:53.952868Z","end":"2026-02-21T02:36:54.468299Z","steps":["trace[1333060079] 'agreement among raft nodes before linearized reading' (duration: 515.235421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:54.468359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:53.952857Z","time spent":"515.493157ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T02:36:55.671508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.239108ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:36:55.671619Z","caller":"traceutil/trace.go:171","msg":"trace[1555395853] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"1.199733939s","start":"2026-02-21T02:36:54.471867Z","end":"2026-02-21T02:36:55.671601Z","steps":["trace[1555395853] 'process raft request' (duration: 790.282746ms)","trace[1555395853] 'compare' (duration: 409.148686ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:55.671681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:54.471855Z","time spent":"1.19979682s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:56.032797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.573272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:36:56.032993Z","caller":"traceutil/trace.go:171","msg":"trace[1083658701] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:752; }","duration":"1.200355865s","start":"2026-02-21T02:36:54.83262Z","end":"2026-02-21T02:36:56.032975Z","steps":["trace[1083658701] 'read index received' (duration: 429.54119ms)","trace[1083658701] 'applied index is now lower than readState.Index' (duration: 770.813635ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:56.033009Z","caller":"traceutil/trace.go:171","msg":"trace[286518167] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"1.560423064s","start":"2026-02-21T02:36:54.472552Z","end":"2026-02-21T02:36:56.032975Z","steps":["trace[286518167] 'process raft request' (duration: 1.339413271s)","trace[286518167] 'compare' (duration: 220.445489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:56.033143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.200527869s","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-21T02:36:56.033131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:54.472536Z","time spent":"1.560524156s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:56.033174Z","caller":"traceutil/trace.go:171","msg":"trace[791920580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"1.20059344s","start":"2026-02-21T02:36:54.83257Z","end":"2026-02-21T02:36:56.033163Z","steps":["trace[791920580] 'agreement among raft nodes before linearized reading' (duration: 1.200454757s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:56.033208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:54.832551Z","time spent":"1.200649131s","remote":"127.0.0.1:55698","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-21T02:36:56.033252Z","caller":"traceutil/trace.go:171","msg":"trace[213460409] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.559348045s","start":"2026-02-21T02:36:54.473897Z","end":"2026-02-21T02:36:56.033245Z","steps":["trace[213460409] 'process raft request' (duration: 1.559002317s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:56.033286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:54.47389Z","time spent":"1.559376966s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:36:56.204419Z","caller":"traceutil/trace.go:171","msg":"trace[272714198] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"167.065192ms","start":"2026-02-21T02:36:56.037328Z","end":"2026-02-21T02:36:56.204393Z","steps":["trace[272714198] 'process raft request' (duration: 130.812858ms)","trace[272714198] 'compare' (duration: 36.149092ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:57.107998Z","caller":"traceutil/trace.go:171","msg":"trace[1504801014] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"632.487898ms","start":"2026-02-21T02:36:56.475472Z","end":"2026-02-21T02:36:57.10796Z","steps":["trace[1504801014] 'process raft request' (duration: 632.355924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:57.108136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:56.475459Z","time spent":"632.61013ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:58.310524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165525036s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:36:58.310684Z","caller":"traceutil/trace.go:171","msg":"trace[1477616040] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"1.18193785s","start":"2026-02-21T02:36:57.12872Z","end":"2026-02-21T02:36:58.310658Z","steps":["trace[1477616040] 'read index received' (duration: 15.358777ms)","trace[1477616040] 'applied index is now lower than readState.Index' (duration: 1.166576773s)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:36:58.310856Z","caller":"traceutil/trace.go:171","msg":"trace[905023550] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.69717045s","start":"2026-02-21T02:36:56.61367Z","end":"2026-02-21T02:36:58.31084Z","steps":["trace[905023550] 'process raft request' (duration: 530.455784ms)","trace[905023550] 'compare' (duration: 1.165180568s)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:36:58.31094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:56.613626Z","time spent":"1.697273395s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:36:58.310948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.713115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:36:58.311012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.630463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:36:58.31103Z","caller":"traceutil/trace.go:171","msg":"trace[2042609896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"920.843528ms","start":"2026-02-21T02:36:57.390164Z","end":"2026-02-21T02:36:58.311007Z","steps":["trace[2042609896] 'agreement among raft nodes before linearized reading' (duration: 920.713294ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:58.311065Z","caller":"traceutil/trace.go:171","msg":"trace[854982692] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:733; }","duration":"147.743005ms","start":"2026-02-21T02:36:58.163307Z","end":"2026-02-21T02:36:58.31105Z","steps":["trace[854982692] 'agreement among raft nodes before linearized reading' (duration: 147.626842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:58.311099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:57.390143Z","time spent":"920.92698ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T02:36:58.311102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182371521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-21T02:36:58.311102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.179122ms","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-21T02:36:58.311169Z","caller":"traceutil/trace.go:171","msg":"trace[1439865676] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:733; }","duration":"1.182484715s","start":"2026-02-21T02:36:57.128667Z","end":"2026-02-21T02:36:58.311151Z","steps":["trace[1439865676] 'agreement among raft nodes before linearized reading' (duration: 1.18228253s)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:58.311171Z","caller":"traceutil/trace.go:171","msg":"trace[1885372053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"101.300925ms","start":"2026-02-21T02:36:58.209855Z","end":"2026-02-21T02:36:58.311156Z","steps":["trace[1885372053] 'agreement among raft nodes before linearized reading' (duration: 101.09545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:58.311145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.030439ms","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-21T02:36:58.311208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:57.128639Z","time spent":"1.182561157s","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.99\" "} {"level":"info","ts":"2026-02-21T02:36:58.311243Z","caller":"traceutil/trace.go:171","msg":"trace[1543043155] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"274.181074ms","start":"2026-02-21T02:36:58.037036Z","end":"2026-02-21T02:36:58.311217Z","steps":["trace[1543043155] 'agreement among raft nodes before linearized reading' (duration: 273.919577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:58.311318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.394873ms","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-21T02:36:58.311423Z","caller":"traceutil/trace.go:171","msg":"trace[989321591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"273.549937ms","start":"2026-02-21T02:36:58.037844Z","end":"2026-02-21T02:36:58.311394Z","steps":["trace[989321591] 'agreement among raft nodes before linearized reading' (duration: 273.154157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:58.311433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.292738ms","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-21T02:36:58.311489Z","caller":"traceutil/trace.go:171","msg":"trace[1899847631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:733; }","duration":"633.359579ms","start":"2026-02-21T02:36:57.678101Z","end":"2026-02-21T02:36:58.311461Z","steps":["trace[1899847631] 'agreement among raft nodes before linearized reading' (duration: 633.171764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:36:58.311521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:36:57.678084Z","time spent":"633.428231ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T02:36:58.459701Z","caller":"traceutil/trace.go:171","msg":"trace[238901143] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"143.927119ms","start":"2026-02-21T02:36:58.315735Z","end":"2026-02-21T02:36:58.459662Z","steps":["trace[238901143] 'process raft request' (duration: 143.719144ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:58.459967Z","caller":"traceutil/trace.go:171","msg":"trace[501061329] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"142.434682ms","start":"2026-02-21T02:36:58.317519Z","end":"2026-02-21T02:36:58.459953Z","steps":["trace[501061329] 'process raft request' (duration: 142.269477ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:58.459708Z","caller":"traceutil/trace.go:171","msg":"trace[2089079570] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"143.835987ms","start":"2026-02-21T02:36:58.315844Z","end":"2026-02-21T02:36:58.45968Z","steps":["trace[2089079570] 'process raft request' (duration: 143.761585ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:36:58.460002Z","caller":"traceutil/trace.go:171","msg":"trace[208580745] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"143.588471ms","start":"2026-02-21T02:36:58.316383Z","end":"2026-02-21T02:36:58.459972Z","steps":["trace[208580745] 'process raft request' (duration: 143.263353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:18.835382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.030953ms","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-21T02:37:18.835503Z","caller":"traceutil/trace.go:171","msg":"trace[210772370] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:797; }","duration":"200.167296ms","start":"2026-02-21T02:37:18.635318Z","end":"2026-02-21T02:37:18.835486Z","steps":["trace[210772370] 'range keys from in-memory index tree' (duration: 200.016392ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:18.835657Z","caller":"traceutil/trace.go:171","msg":"trace[1798912652] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"232.972174ms","start":"2026-02-21T02:37:18.602639Z","end":"2026-02-21T02:37:18.835611Z","steps":["trace[1798912652] 'read index received' (duration: 10.850124ms)","trace[1798912652] 'applied index is now lower than readState.Index' (duration: 222.12016ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:18.835944Z","caller":"traceutil/trace.go:171","msg":"trace[1636706130] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"235.525289ms","start":"2026-02-21T02:37:18.600385Z","end":"2026-02-21T02:37:18.835911Z","steps":["trace[1636706130] 'process raft request' (duration: 222.078219ms)","trace[1636706130] 'compare' (duration: 12.64014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:18.835987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.324233ms","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-21T02:37:18.836051Z","caller":"traceutil/trace.go:171","msg":"trace[2068522181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"233.435876ms","start":"2026-02-21T02:37:18.6026Z","end":"2026-02-21T02:37:18.836036Z","steps":["trace[2068522181] 'agreement among raft nodes before linearized reading' (duration: 233.142628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:19.719343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035268309,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:37:19.77898Z","caller":"traceutil/trace.go:171","msg":"trace[1596409406] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"1.124748506s","start":"2026-02-21T02:37:18.654207Z","end":"2026-02-21T02:37:19.778955Z","steps":["trace[1596409406] 'process raft request' (duration: 1.124619103s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:19.779126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:18.654176Z","time spent":"1.124877s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:37:19.798298Z","caller":"traceutil/trace.go:171","msg":"trace[216013698] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"578.980957ms","start":"2026-02-21T02:37:19.219288Z","end":"2026-02-21T02:37:19.798269Z","steps":["trace[216013698] 'read index received' (duration: 559.584057ms)","trace[216013698] 'applied index is now lower than readState.Index' (duration: 19.39567ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:19.798397Z","caller":"traceutil/trace.go:171","msg":"trace[1628971993] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"957.242213ms","start":"2026-02-21T02:37:18.841135Z","end":"2026-02-21T02:37:19.798377Z","steps":["trace[1628971993] 'process raft request' (duration: 956.990967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:19.798542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:18.841093Z","time spent":"957.348046ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:37:19.798579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.163886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:37:19.798592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.296394ms","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-21T02:37:19.79864Z","caller":"traceutil/trace.go:171","msg":"trace[865562804] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:800; }","duration":"561.258429ms","start":"2026-02-21T02:37:19.237367Z","end":"2026-02-21T02:37:19.798625Z","steps":["trace[865562804] 'agreement among raft nodes before linearized reading' (duration: 561.168236ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:19.798645Z","caller":"traceutil/trace.go:171","msg":"trace[1697151861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"579.438998ms","start":"2026-02-21T02:37:19.219192Z","end":"2026-02-21T02:37:19.798631Z","steps":["trace[1697151861] 'agreement among raft nodes before linearized reading' (duration: 579.240363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:19.798683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:19.23735Z","time spent":"561.32346ms","remote":"127.0.0.1:55834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:37:19.798747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.039647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:37:19.798823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:19.219165Z","time spent":"579.51739ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-21T02:37:19.798834Z","caller":"traceutil/trace.go:171","msg":"trace[1882742142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"408.181281ms","start":"2026-02-21T02:37:19.390623Z","end":"2026-02-21T02:37:19.798805Z","steps":["trace[1882742142] 'agreement among raft nodes before linearized reading' (duration: 408.011557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:19.798885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:19.390603Z","time spent":"408.274014ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T02:37:21.34163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035268319,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:37:21.479703Z","caller":"traceutil/trace.go:171","msg":"trace[1273221711] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"638.441985ms","start":"2026-02-21T02:37:20.841223Z","end":"2026-02-21T02:37:21.479665Z","steps":["trace[1273221711] 'read index received' (duration: 638.060066ms)","trace[1273221711] 'applied index is now lower than readState.Index' (duration: 380.299µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:21.479957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.721712ms","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-21T02:37:21.480012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.05471ms","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-21T02:37:21.480051Z","caller":"traceutil/trace.go:171","msg":"trace[1960235559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"638.871285ms","start":"2026-02-21T02:37:20.841167Z","end":"2026-02-21T02:37:21.480038Z","steps":["trace[1960235559] 'agreement among raft nodes before linearized reading' (duration: 638.64329ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:21.480078Z","caller":"traceutil/trace.go:171","msg":"trace[684389333] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:804; }","duration":"347.201264ms","start":"2026-02-21T02:37:21.132857Z","end":"2026-02-21T02:37:21.480058Z","steps":["trace[684389333] 'agreement among raft nodes before linearized reading' (duration: 347.04014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:21.480099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:20.841144Z","time spent":"638.945878ms","remote":"127.0.0.1:55698","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":"info","ts":"2026-02-21T02:37:21.480035Z","caller":"traceutil/trace.go:171","msg":"trace[2039617719] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"858.222587ms","start":"2026-02-21T02:37:20.62158Z","end":"2026-02-21T02:37:21.479802Z","steps":["trace[2039617719] 'process raft request' (duration: 857.819387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:21.480404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:20.621557Z","time spent":"858.74292ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:37:21.480142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:21.132835Z","time spent":"347.295206ms","remote":"127.0.0.1:55974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:37:22.640943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.940282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:37:22.64106Z","caller":"traceutil/trace.go:171","msg":"trace[686981305] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"838.242747ms","start":"2026-02-21T02:37:21.802787Z","end":"2026-02-21T02:37:22.641029Z","steps":["trace[686981305] 'read index received' (duration: 147.028998ms)","trace[686981305] 'applied index is now lower than readState.Index' (duration: 691.212329ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:22.641153Z","caller":"traceutil/trace.go:171","msg":"trace[1647584859] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.156322587s","start":"2026-02-21T02:37:21.484805Z","end":"2026-02-21T02:37:22.641128Z","steps":["trace[1647584859] 'process raft request' (duration: 465.093348ms)","trace[1647584859] 'compare' (duration: 690.584292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:22.641268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:21.484784Z","time spent":"1.156439521s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:37:22.642008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.224461ms","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-21T02:37:22.642058Z","caller":"traceutil/trace.go:171","msg":"trace[51719435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"839.371645ms","start":"2026-02-21T02:37:21.802675Z","end":"2026-02-21T02:37:22.642046Z","steps":["trace[51719435] 'agreement among raft nodes before linearized reading' (duration: 838.446572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:22.642092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:21.802647Z","time spent":"839.437176ms","remote":"127.0.0.1:55698","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-21T02:37:22.87024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.49968ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-21T02:37:22.870458Z","caller":"traceutil/trace.go:171","msg":"trace[285819635] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"224.26006ms","start":"2026-02-21T02:37:22.646182Z","end":"2026-02-21T02:37:22.870442Z","steps":["trace[285819635] 'process raft request' (duration: 224.152817ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:22.870489Z","caller":"traceutil/trace.go:171","msg":"trace[252913268] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:835; }","duration":"229.356867ms","start":"2026-02-21T02:37:22.641091Z","end":"2026-02-21T02:37:22.870448Z","steps":["trace[252913268] 'read index received' (duration: 20.714644ms)","trace[252913268] 'applied index is now lower than readState.Index' (duration: 208.637863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:37:22.87079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023896481s","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-21T02:37:22.870833Z","caller":"traceutil/trace.go:171","msg":"trace[819519024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.024009684s","start":"2026-02-21T02:37:21.846813Z","end":"2026-02-21T02:37:22.870822Z","steps":["trace[819519024] 'agreement among raft nodes before linearized reading' (duration: 1.023749956s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:22.870873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:21.846786Z","time spent":"1.024078146s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-21T02:37:22.870929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.134254ms","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-21T02:37:22.871045Z","caller":"traceutil/trace.go:171","msg":"trace[40416049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"271.270147ms","start":"2026-02-21T02:37:22.599751Z","end":"2026-02-21T02:37:22.871021Z","steps":["trace[40416049] 'agreement among raft nodes before linearized reading' (duration: 270.943659ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:46.49138Z","caller":"traceutil/trace.go:171","msg":"trace[1324104056] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"100.783059ms","start":"2026-02-21T02:37:46.390571Z","end":"2026-02-21T02:37:46.491354Z","steps":["trace[1324104056] 'read index received' (duration: 100.593624ms)","trace[1324104056] 'applied index is now lower than readState.Index' (duration: 188.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:37:46.491649Z","caller":"traceutil/trace.go:171","msg":"trace[239506218] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"827.825882ms","start":"2026-02-21T02:37:45.663811Z","end":"2026-02-21T02:37:46.491637Z","steps":["trace[239506218] 'process raft request' (duration: 827.414271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:46.491758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:45.663788Z","time spent":"827.899433ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:37:46.491967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.390695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:37:46.492015Z","caller":"traceutil/trace.go:171","msg":"trace[731084960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"101.457576ms","start":"2026-02-21T02:37:46.390545Z","end":"2026-02-21T02:37:46.492002Z","steps":["trace[731084960] 'agreement among raft nodes before linearized reading' (duration: 101.389854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.830332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.024585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.99\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-21T02:37:47.830503Z","caller":"traceutil/trace.go:171","msg":"trace[3423501] range","detail":"{range_begin:/registry/masterleases/199.19.213.99; range_end:; response_count:1; response_revision:921; }","duration":"697.271111ms","start":"2026-02-21T02:37:47.133201Z","end":"2026-02-21T02:37:47.830472Z","steps":["trace[3423501] 'range keys from in-memory index tree' (duration: 696.79727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.830556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:47.133165Z","time spent":"697.379854ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.99\" "} {"level":"warn","ts":"2026-02-21T02:37:47.830786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.238449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:37:47.830883Z","caller":"traceutil/trace.go:171","msg":"trace[816406186] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:921; }","duration":"695.448005ms","start":"2026-02-21T02:37:47.135416Z","end":"2026-02-21T02:37:47.830864Z","steps":["trace[816406186] 'count revisions from in-memory index tree' (duration: 695.138567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.830929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:47.135394Z","time spent":"695.523728ms","remote":"127.0.0.1:55616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:37:47.831081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055476739s","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-21T02:37:47.831142Z","caller":"traceutil/trace.go:171","msg":"trace[1423923193] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"1.055606092s","start":"2026-02-21T02:37:46.775516Z","end":"2026-02-21T02:37:47.831122Z","steps":["trace[1423923193] 'range keys from in-memory index tree' (duration: 1.055277723s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.831184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:46.775464Z","time spent":"1.055710634s","remote":"127.0.0.1:55698","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-21T02:37:47.831445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.709098ms","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-21T02:37:47.831761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.123257ms","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-21T02:37:47.831983Z","caller":"traceutil/trace.go:171","msg":"trace[1263606609] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"816.543537ms","start":"2026-02-21T02:37:47.015418Z","end":"2026-02-21T02:37:47.831962Z","steps":["trace[1263606609] 'range keys from in-memory index tree' (duration: 816.030234ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:37:47.831821Z","caller":"traceutil/trace.go:171","msg":"trace[556827349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"856.135479ms","start":"2026-02-21T02:37:46.975653Z","end":"2026-02-21T02:37:47.831788Z","steps":["trace[556827349] 'range keys from in-memory index tree' (duration: 855.426621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.831459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.636273ms","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-21T02:37:47.832135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:46.975629Z","time spent":"856.456656ms","remote":"127.0.0.1:55698","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-21T02:37:47.832212Z","caller":"traceutil/trace.go:171","msg":"trace[1100466934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"759.494354ms","start":"2026-02-21T02:37:47.0727Z","end":"2026-02-21T02:37:47.832194Z","steps":["trace[1100466934] 'range keys from in-memory index tree' (duration: 758.363216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:37:47.832274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:47.072674Z","time spent":"759.583666ms","remote":"127.0.0.1:55698","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":"warn","ts":"2026-02-21T02:37:47.83229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:37:47.015399Z","time spent":"816.625849ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-21T02:38:06.495378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035268784,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:38:06.569696Z","caller":"traceutil/trace.go:171","msg":"trace[1530161994] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"588.642155ms","start":"2026-02-21T02:38:05.981037Z","end":"2026-02-21T02:38:06.569679Z","steps":["trace[1530161994] 'process raft request' (duration: 588.417809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.569859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:05.981011Z","time spent":"588.767998ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:06.569938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.528153ms","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-21T02:38:06.569995Z","caller":"traceutil/trace.go:171","msg":"trace[1951962123] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"575.229596ms","start":"2026-02-21T02:38:05.994385Z","end":"2026-02-21T02:38:06.569615Z","steps":["trace[1951962123] 'read index received' (duration: 574.9962ms)","trace[1951962123] 'applied index is now lower than readState.Index' (duration: 232.506µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:06.570004Z","caller":"traceutil/trace.go:171","msg":"trace[1969032755] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:993; }","duration":"575.624236ms","start":"2026-02-21T02:38:05.994361Z","end":"2026-02-21T02:38:06.569985Z","steps":["trace[1969032755] 'agreement among raft nodes before linearized reading' (duration: 575.423501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.570064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:05.99435Z","time spent":"575.704658ms","remote":"127.0.0.1:55698","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-21T02:38:06.570221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.236956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:38:06.570262Z","caller":"traceutil/trace.go:171","msg":"trace[800787118] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:993; }","duration":"155.363979ms","start":"2026-02-21T02:38:06.414885Z","end":"2026-02-21T02:38:06.570249Z","steps":["trace[800787118] 'agreement among raft nodes before linearized reading' (duration: 155.262546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.570269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.176441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:06.570318Z","caller":"traceutil/trace.go:171","msg":"trace[1580782484] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:993; }","duration":"179.253303ms","start":"2026-02-21T02:38:06.391054Z","end":"2026-02-21T02:38:06.570308Z","steps":["trace[1580782484] 'agreement among raft nodes before linearized reading' (duration: 179.186132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.570673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.901842ms","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-21T02:38:06.570695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.914113ms","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-21T02:38:06.570783Z","caller":"traceutil/trace.go:171","msg":"trace[456997442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:993; }","duration":"508.045177ms","start":"2026-02-21T02:38:06.062718Z","end":"2026-02-21T02:38:06.570763Z","steps":["trace[456997442] 'agreement among raft nodes before linearized reading' (duration: 507.853112ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:06.570811Z","caller":"traceutil/trace.go:171","msg":"trace[1664875694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:993; }","duration":"507.983185ms","start":"2026-02-21T02:38:06.062715Z","end":"2026-02-21T02:38:06.570698Z","steps":["trace[1664875694] 'agreement among raft nodes before linearized reading' (duration: 507.39335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.570822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:06.062707Z","time spent":"508.107068ms","remote":"127.0.0.1:55698","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-21T02:38:06.570857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:06.062697Z","time spent":"508.150309ms","remote":"127.0.0.1:55698","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":"info","ts":"2026-02-21T02:38:06.756596Z","caller":"traceutil/trace.go:171","msg":"trace[1807972197] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"180.664067ms","start":"2026-02-21T02:38:06.5759Z","end":"2026-02-21T02:38:06.756564Z","steps":["trace[1807972197] 'process raft request' (duration: 164.318785ms)","trace[1807972197] 'compare' (duration: 16.095306ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:06.759496Z","caller":"traceutil/trace.go:171","msg":"trace[1156346141] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"183.53368ms","start":"2026-02-21T02:38:06.575949Z","end":"2026-02-21T02:38:06.759483Z","steps":["trace[1156346141] 'process raft request' (duration: 183.378116ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:06.759677Z","caller":"traceutil/trace.go:171","msg":"trace[1148097391] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"183.386866ms","start":"2026-02-21T02:38:06.57628Z","end":"2026-02-21T02:38:06.759667Z","steps":["trace[1148097391] 'process raft request' (duration: 183.163271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:06.759975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.59613ms","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-21T02:38:06.760086Z","caller":"traceutil/trace.go:171","msg":"trace[13073472] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"137.776434ms","start":"2026-02-21T02:38:06.62229Z","end":"2026-02-21T02:38:06.760067Z","steps":["trace[13073472] 'agreement among raft nodes before linearized reading' (duration: 137.470876ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:06.760487Z","caller":"traceutil/trace.go:171","msg":"trace[287572982] linearizableReadLoop","detail":"{readStateIndex:1037; appliedIndex:1034; }","duration":"137.282782ms","start":"2026-02-21T02:38:06.622353Z","end":"2026-02-21T02:38:06.759636Z","steps":["trace[287572982] 'read index received' (duration: 117.872812ms)","trace[287572982] 'applied index is now lower than readState.Index' (duration: 19.40668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:09.726345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.090719499s","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-21T02:38:09.727642Z","caller":"traceutil/trace.go:171","msg":"trace[2009008097] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1002; }","duration":"1.092030412s","start":"2026-02-21T02:38:08.635563Z","end":"2026-02-21T02:38:09.727593Z","steps":["trace[2009008097] 'range keys from in-memory index tree' (duration: 1.090687748s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.726648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.705586106s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:38:09.727916Z","caller":"traceutil/trace.go:171","msg":"trace[1250634615] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1044; }","duration":"1.927450607s","start":"2026-02-21T02:38:07.800431Z","end":"2026-02-21T02:38:09.727882Z","steps":["trace[1250634615] 'read index received' (duration: 220.495956ms)","trace[1250634615] 'applied index is now lower than readState.Index' (duration: 1.706951231s)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:09.727962Z","caller":"traceutil/trace.go:171","msg":"trace[1673921713] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"2.091477335s","start":"2026-02-21T02:38:07.636442Z","end":"2026-02-21T02:38:09.727919Z","steps":["trace[1673921713] 'process raft request' (duration: 384.549935ms)","trace[1673921713] 'compare' (duration: 1.705471762s)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:09.728097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:07.636421Z","time spent":"2.091611037s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-21T02:38:09.728194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.339862968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:38:09.728214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927759794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:09.728258Z","caller":"traceutil/trace.go:171","msg":"trace[1438062075] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"1.3399452s","start":"2026-02-21T02:38:08.388291Z","end":"2026-02-21T02:38:09.728236Z","steps":["trace[1438062075] 'agreement among raft nodes before linearized reading' (duration: 1.339777776s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.715138ms","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-21T02:38:09.728322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.388196Z","time spent":"1.340111664s","remote":"127.0.0.1:55458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T02:38:09.728351Z","caller":"traceutil/trace.go:171","msg":"trace[2131220153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1003; }","duration":"963.80069ms","start":"2026-02-21T02:38:08.76453Z","end":"2026-02-21T02:38:09.728331Z","steps":["trace[2131220153] 'agreement among raft nodes before linearized reading' (duration: 963.542083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.764525Z","time spent":"963.862581ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-21T02:38:09.72843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.279039ms","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-21T02:38:09.728471Z","caller":"traceutil/trace.go:171","msg":"trace[1685229257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1003; }","duration":"903.353091ms","start":"2026-02-21T02:38:08.825105Z","end":"2026-02-21T02:38:09.728458Z","steps":["trace[1685229257] 'agreement among raft nodes before linearized reading' (duration: 903.200967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.95133ms","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-21T02:38:09.728507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.825083Z","time spent":"903.416743ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-21T02:38:09.728514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152714986s","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-21T02:38:09.72852Z","caller":"traceutil/trace.go:171","msg":"trace[245722886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1003; }","duration":"967.052222ms","start":"2026-02-21T02:38:08.761453Z","end":"2026-02-21T02:38:09.728505Z","steps":["trace[245722886] 'agreement among raft nodes before linearized reading' (duration: 966.883288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.015776ms","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-21T02:38:09.728563Z","caller":"traceutil/trace.go:171","msg":"trace[1418042334] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"1.152788518s","start":"2026-02-21T02:38:08.575752Z","end":"2026-02-21T02:38:09.72854Z","steps":["trace[1418042334] 'agreement among raft nodes before linearized reading' (duration: 1.152685655s)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:09.728264Z","caller":"traceutil/trace.go:171","msg":"trace[904379567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"1.927888947s","start":"2026-02-21T02:38:07.800363Z","end":"2026-02-21T02:38:09.728252Z","steps":["trace[904379567] 'agreement among raft nodes before linearized reading' (duration: 1.927660571s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.761431Z","time spent":"967.126103ms","remote":"127.0.0.1:55698","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-21T02:38:09.728597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.57573Z","time spent":"1.1528597s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-21T02:38:09.728604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:07.800333Z","time spent":"1.928262786s","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T02:38:09.728604Z","caller":"traceutil/trace.go:171","msg":"trace[41642924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1003; }","duration":"964.108918ms","start":"2026-02-21T02:38:08.76447Z","end":"2026-02-21T02:38:09.728579Z","steps":["trace[41642924] 'agreement among raft nodes before linearized reading' (duration: 963.881052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:09.728665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:08.764449Z","time spent":"964.19813ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-21T02:38:10.075037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.394572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:38:10.07518Z","caller":"traceutil/trace.go:171","msg":"trace[1266525784] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"338.970851ms","start":"2026-02-21T02:38:09.736188Z","end":"2026-02-21T02:38:10.075159Z","steps":["trace[1266525784] 'process raft request' (duration: 173.359244ms)","trace[1266525784] 'compare' (duration: 164.953791ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:10.075249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.736176Z","time spent":"339.043392ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:10.548048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.539329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:38:10.548434Z","caller":"traceutil/trace.go:171","msg":"trace[320449788] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1045; }","duration":"801.035685ms","start":"2026-02-21T02:38:09.747378Z","end":"2026-02-21T02:38:10.548414Z","steps":["trace[320449788] 'read index received' (duration: 162.178181ms)","trace[320449788] 'applied index is now lower than readState.Index' (duration: 638.856593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:10.548582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.185148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:10.548629Z","caller":"traceutil/trace.go:171","msg":"trace[2069780866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"801.266971ms","start":"2026-02-21T02:38:09.747349Z","end":"2026-02-21T02:38:10.548616Z","steps":["trace[2069780866] 'agreement among raft nodes before linearized reading' (duration: 801.140237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:10.548674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.74734Z","time spent":"801.322662ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-21T02:38:10.548793Z","caller":"traceutil/trace.go:171","msg":"trace[1084035761] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"809.291304ms","start":"2026-02-21T02:38:09.739445Z","end":"2026-02-21T02:38:10.548736Z","steps":["trace[1084035761] 'process raft request' (duration: 808.832212ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:10.548908Z","caller":"traceutil/trace.go:171","msg":"trace[660371946] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"797.153125ms","start":"2026-02-21T02:38:09.751742Z","end":"2026-02-21T02:38:10.548895Z","steps":["trace[660371946] 'process raft request' (duration: 796.634453ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:10.548957Z","caller":"traceutil/trace.go:171","msg":"trace[405998756] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"809.491559ms","start":"2026-02-21T02:38:09.739444Z","end":"2026-02-21T02:38:10.548936Z","steps":["trace[405998756] 'process raft request' (duration: 808.867623ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:10.548952Z","caller":"traceutil/trace.go:171","msg":"trace[282485150] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"809.786755ms","start":"2026-02-21T02:38:09.739142Z","end":"2026-02-21T02:38:10.548929Z","steps":["trace[282485150] 'process raft request' (duration: 457.287802ms)","trace[282485150] 'compare' (duration: 351.231632ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:10.548935Z","caller":"traceutil/trace.go:171","msg":"trace[1214443476] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"809.385556ms","start":"2026-02-21T02:38:09.739511Z","end":"2026-02-21T02:38:10.548896Z","steps":["trace[1214443476] 'process raft request' (duration: 808.835302ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:10.54907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.739437Z","time spent":"809.467648ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:10.549104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.739435Z","time spent":"809.55647ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:38:10.549106Z","caller":"traceutil/trace.go:171","msg":"trace[529043925] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"809.765855ms","start":"2026-02-21T02:38:09.739327Z","end":"2026-02-21T02:38:10.549092Z","steps":["trace[529043925] 'process raft request' (duration: 808.825562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:10.549355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.739302Z","time spent":"810.024031ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:10.549039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.739125Z","time spent":"809.871298ms","remote":"127.0.0.1:55544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-21T02:38:10.548993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.751732Z","time spent":"797.208028ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:10.549382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:09.739505Z","time spent":"809.601671ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:38:12.031575Z","caller":"traceutil/trace.go:171","msg":"trace[1234309315] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"402.055466ms","start":"2026-02-21T02:38:11.629496Z","end":"2026-02-21T02:38:12.031551Z","steps":["trace[1234309315] 'process raft request' (duration: 401.911072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:12.03171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:11.629459Z","time spent":"402.180629ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:12.206661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.630807ms","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-21T02:38:12.206766Z","caller":"traceutil/trace.go:171","msg":"trace[1379225065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1012; }","duration":"127.796991ms","start":"2026-02-21T02:38:12.078951Z","end":"2026-02-21T02:38:12.206748Z","steps":["trace[1379225065] 'range keys from in-memory index tree' (duration: 127.452083ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:12.884689Z","caller":"traceutil/trace.go:171","msg":"trace[1735196829] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"324.081665ms","start":"2026-02-21T02:38:12.560467Z","end":"2026-02-21T02:38:12.884548Z","steps":["trace[1735196829] 'process raft request' (duration: 323.850359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:12.884937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:12.560453Z","time spent":"324.359362ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:38:12.931547Z","caller":"traceutil/trace.go:171","msg":"trace[2078413833] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"369.888683ms","start":"2026-02-21T02:38:12.561635Z","end":"2026-02-21T02:38:12.931524Z","steps":["trace[2078413833] 'process raft request' (duration: 369.827141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:12.931723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:12.561609Z","time spent":"370.014277ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:38:12.931727Z","caller":"traceutil/trace.go:171","msg":"trace[96578037] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"371.069024ms","start":"2026-02-21T02:38:12.560599Z","end":"2026-02-21T02:38:12.931668Z","steps":["trace[96578037] 'process raft request' (duration: 370.829198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:12.931999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:12.560578Z","time spent":"371.286239ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:38:12.932834Z","caller":"traceutil/trace.go:171","msg":"trace[743290492] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"370.979911ms","start":"2026-02-21T02:38:12.560577Z","end":"2026-02-21T02:38:12.931557Z","steps":["trace[743290492] 'process raft request' (duration: 370.719874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:12.932994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:12.560536Z","time spent":"372.352905ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:13.063915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.379757ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-21T02:38:13.064088Z","caller":"traceutil/trace.go:171","msg":"trace[1285494026] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"110.458934ms","start":"2026-02-21T02:38:12.953602Z","end":"2026-02-21T02:38:13.064061Z","steps":["trace[1285494026] 'read index received' (duration: 72.223µs)","trace[1285494026] 'applied index is now lower than readState.Index' (duration: 110.383941ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:13.064198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.574467ms","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":"info","ts":"2026-02-21T02:38:13.064229Z","caller":"traceutil/trace.go:171","msg":"trace[435098582] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1017; }","duration":"110.674379ms","start":"2026-02-21T02:38:12.953545Z","end":"2026-02-21T02:38:13.064219Z","steps":["trace[435098582] 'agreement among raft nodes before linearized reading' (duration: 110.578407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:14.889983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035268843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:38:15.351953Z","caller":"traceutil/trace.go:171","msg":"trace[1214284284] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"962.148889ms","start":"2026-02-21T02:38:14.389758Z","end":"2026-02-21T02:38:15.351907Z","steps":["trace[1214284284] 'read index received' (duration: 961.83386ms)","trace[1214284284] 'applied index is now lower than readState.Index' (duration: 313.859µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:15.352096Z","caller":"traceutil/trace.go:171","msg":"trace[233725186] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.109547566s","start":"2026-02-21T02:38:14.242535Z","end":"2026-02-21T02:38:15.352083Z","steps":["trace[233725186] 'process raft request' (duration: 1.109159257s)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.352233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.24251Z","time spent":"1.109619577s","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:38:15.352481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.284884ms","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-21T02:38:15.352554Z","caller":"traceutil/trace.go:171","msg":"trace[1046984242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"461.425448ms","start":"2026-02-21T02:38:14.891111Z","end":"2026-02-21T02:38:15.352537Z","steps":["trace[1046984242] 'agreement among raft nodes before linearized reading' (duration: 461.230183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.352592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.891084Z","time spent":"461.50189ms","remote":"127.0.0.1:55698","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-21T02:38:15.352989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.954643ms","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-21T02:38:15.353018Z","caller":"traceutil/trace.go:171","msg":"trace[270345045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"414.984013ms","start":"2026-02-21T02:38:14.938027Z","end":"2026-02-21T02:38:15.353011Z","steps":["trace[270345045] 'agreement among raft nodes before linearized reading' (duration: 414.912172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.353028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.271697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:15.353074Z","caller":"traceutil/trace.go:171","msg":"trace[712844122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"963.35992ms","start":"2026-02-21T02:38:14.389702Z","end":"2026-02-21T02:38:15.353062Z","steps":["trace[712844122] 'agreement among raft nodes before linearized reading' (duration: 963.304698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.353112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.389677Z","time spent":"963.429471ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T02:38:15.35333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.895445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-21T02:38:15.353042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.937981Z","time spent":"415.053885ms","remote":"127.0.0.1:55698","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-21T02:38:15.35298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.524875ms","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-21T02:38:15.353512Z","caller":"traceutil/trace.go:171","msg":"trace[1370856685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"410.067618ms","start":"2026-02-21T02:38:14.943432Z","end":"2026-02-21T02:38:15.353499Z","steps":["trace[1370856685] 'agreement among raft nodes before linearized reading' (duration: 409.406932ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:15.353371Z","caller":"traceutil/trace.go:171","msg":"trace[341409170] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1018; }","duration":"289.013478ms","start":"2026-02-21T02:38:15.064347Z","end":"2026-02-21T02:38:15.353361Z","steps":["trace[341409170] 'agreement among raft nodes before linearized reading' (duration: 288.941456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.353093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.127247ms","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-21T02:38:15.35459Z","caller":"traceutil/trace.go:171","msg":"trace[451504602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"416.634996ms","start":"2026-02-21T02:38:14.937913Z","end":"2026-02-21T02:38:15.354548Z","steps":["trace[451504602] 'agreement among raft nodes before linearized reading' (duration: 415.143337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.354709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.937891Z","time spent":"416.77712ms","remote":"127.0.0.1:55698","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-21T02:38:15.355193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:14.943425Z","time spent":"410.11025ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-21T02:38:15.526419Z","caller":"traceutil/trace.go:171","msg":"trace[1246110870] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"166.291905ms","start":"2026-02-21T02:38:15.36008Z","end":"2026-02-21T02:38:15.526372Z","steps":["trace[1246110870] 'process raft request' (duration: 162.92857ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:15.586794Z","caller":"traceutil/trace.go:171","msg":"trace[1469216125] linearizableReadLoop","detail":"{readStateIndex:1065; appliedIndex:1061; }","duration":"194.99118ms","start":"2026-02-21T02:38:15.391775Z","end":"2026-02-21T02:38:15.586766Z","steps":["trace[1469216125] 'read index received' (duration: 131.2974ms)","trace[1469216125] 'applied index is now lower than readState.Index' (duration: 63.692149ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:38:15.587159Z","caller":"traceutil/trace.go:171","msg":"trace[24955349] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"227.02194ms","start":"2026-02-21T02:38:15.360123Z","end":"2026-02-21T02:38:15.587145Z","steps":["trace[24955349] 'process raft request' (duration: 226.362163ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:15.587486Z","caller":"traceutil/trace.go:171","msg":"trace[305926342] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"226.797495ms","start":"2026-02-21T02:38:15.360677Z","end":"2026-02-21T02:38:15.587475Z","steps":["trace[305926342] 'process raft request' (duration: 225.952623ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:15.587618Z","caller":"traceutil/trace.go:171","msg":"trace[117467756] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"226.146139ms","start":"2026-02-21T02:38:15.361463Z","end":"2026-02-21T02:38:15.58761Z","steps":["trace[117467756] 'process raft request' (duration: 225.228415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:15.587791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.999756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:15.587835Z","caller":"traceutil/trace.go:171","msg":"trace[1617585746] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"196.111899ms","start":"2026-02-21T02:38:15.391712Z","end":"2026-02-21T02:38:15.587824Z","steps":["trace[1617585746] 'agreement among raft nodes before linearized reading' (duration: 196.013466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:21.555529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.795548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:21.555632Z","caller":"traceutil/trace.go:171","msg":"trace[466224754] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"147.921251ms","start":"2026-02-21T02:38:21.407688Z","end":"2026-02-21T02:38:21.555609Z","steps":["trace[466224754] 'range keys from in-memory index tree' (duration: 147.732576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:21.555657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.218266ms","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-21T02:38:21.555753Z","caller":"traceutil/trace.go:171","msg":"trace[1704879354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1044; }","duration":"178.34638ms","start":"2026-02-21T02:38:21.37739Z","end":"2026-02-21T02:38:21.555737Z","steps":["trace[1704879354] 'range keys from in-memory index tree' (duration: 177.885127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:21.556167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.183484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:38:21.556266Z","caller":"traceutil/trace.go:171","msg":"trace[976743489] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1044; }","duration":"454.325908ms","start":"2026-02-21T02:38:21.101919Z","end":"2026-02-21T02:38:21.556245Z","steps":["trace[976743489] 'range keys from in-memory index tree' (duration: 454.080332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:21.556347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:21.10189Z","time spent":"454.441331ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-21T02:38:21.55675Z","caller":"traceutil/trace.go:171","msg":"trace[430848163] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"148.617909ms","start":"2026-02-21T02:38:21.408113Z","end":"2026-02-21T02:38:21.556731Z","steps":["trace[430848163] 'process raft request' (duration: 141.994811ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:38:32.898955Z","caller":"traceutil/trace.go:171","msg":"trace[422029194] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"449.560687ms","start":"2026-02-21T02:38:32.449364Z","end":"2026-02-21T02:38:32.898924Z","steps":["trace[422029194] 'read index received' (duration: 449.438104ms)","trace[422029194] 'applied index is now lower than readState.Index' (duration: 121.603µs)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:38:32.899131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.747132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:38:32.89916Z","caller":"traceutil/trace.go:171","msg":"trace[1338575451] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1113; }","duration":"449.828144ms","start":"2026-02-21T02:38:32.449323Z","end":"2026-02-21T02:38:32.899151Z","steps":["trace[1338575451] 'agreement among raft nodes before linearized reading' (duration: 449.692411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:32.899186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:32.449302Z","time spent":"449.878966ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-21T02:38:32.899359Z","caller":"traceutil/trace.go:171","msg":"trace[239670434] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"532.265274ms","start":"2026-02-21T02:38:32.367056Z","end":"2026-02-21T02:38:32.899321Z","steps":["trace[239670434] 'process raft request' (duration: 531.747181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:38:32.899617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:38:32.367031Z","time spent":"532.402308ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:39:24.260609Z","caller":"traceutil/trace.go:171","msg":"trace[1759853927] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1378; }","duration":"139.679501ms","start":"2026-02-21T02:39:24.120884Z","end":"2026-02-21T02:39:24.260564Z","steps":["trace[1759853927] 'read index received' (duration: 114.263417ms)","trace[1759853927] 'applied index is now lower than readState.Index' (duration: 25.414724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:39:24.260968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.055181ms","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-21T02:39:24.261011Z","caller":"traceutil/trace.go:171","msg":"trace[1953153417] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"196.242086ms","start":"2026-02-21T02:39:24.064747Z","end":"2026-02-21T02:39:24.260989Z","steps":["trace[1953153417] 'process raft request' (duration: 170.477533ms)","trace[1953153417] 'compare' (duration: 25.044675ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:39:24.261076Z","caller":"traceutil/trace.go:171","msg":"trace[1530856998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"140.177894ms","start":"2026-02-21T02:39:24.120873Z","end":"2026-02-21T02:39:24.261051Z","steps":["trace[1530856998] 'agreement among raft nodes before linearized reading' (duration: 139.890177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:39:24.261074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.01106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-21T02:39:24.261209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.784158ms","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-21T02:39:24.261251Z","caller":"traceutil/trace.go:171","msg":"trace[1923824034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"136.84492ms","start":"2026-02-21T02:39:24.124395Z","end":"2026-02-21T02:39:24.26124Z","steps":["trace[1923824034] 'agreement among raft nodes before linearized reading' (duration: 136.742787ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:24.261263Z","caller":"traceutil/trace.go:171","msg":"trace[1917023009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"140.224485ms","start":"2026-02-21T02:39:24.121015Z","end":"2026-02-21T02:39:24.26124Z","steps":["trace[1917023009] 'agreement among raft nodes before linearized reading' (duration: 139.750023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:39:24.603365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.500207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:39:24.60364Z","caller":"traceutil/trace.go:171","msg":"trace[1842934001] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1380; }","duration":"213.534204ms","start":"2026-02-21T02:39:24.390091Z","end":"2026-02-21T02:39:24.603625Z","steps":["trace[1842934001] 'read index received' (duration: 70.944578ms)","trace[1842934001] 'applied index is now lower than readState.Index' (duration: 142.588415ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:39:24.603766Z","caller":"traceutil/trace.go:171","msg":"trace[1846952841] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"265.282485ms","start":"2026-02-21T02:39:24.338475Z","end":"2026-02-21T02:39:24.603757Z","steps":["trace[1846952841] 'process raft request' (duration: 265.09545ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:24.603692Z","caller":"traceutil/trace.go:171","msg":"trace[2059454991] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"336.576643ms","start":"2026-02-21T02:39:24.267086Z","end":"2026-02-21T02:39:24.603662Z","steps":["trace[2059454991] 'process raft request' (duration: 336.408189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:39:24.603943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:39:24.267069Z","time spent":"336.805039ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:39:24.603992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.313051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-21T02:39:24.604046Z","caller":"traceutil/trace.go:171","msg":"trace[993964543] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"176.399433ms","start":"2026-02-21T02:39:24.42763Z","end":"2026-02-21T02:39:24.604029Z","steps":["trace[993964543] 'agreement among raft nodes before linearized reading' (duration: 176.268779ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:24.603705Z","caller":"traceutil/trace.go:171","msg":"trace[1765950333] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"337.395123ms","start":"2026-02-21T02:39:24.266267Z","end":"2026-02-21T02:39:24.603662Z","steps":["trace[1765950333] 'process raft request' (duration: 111.531558ms)","trace[1765950333] 'compare' (duration: 225.391284ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:39:24.604242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:39:24.266242Z","time spent":"337.940697ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:39:24.603821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.724489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:39:24.604331Z","caller":"traceutil/trace.go:171","msg":"trace[1393277899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"214.294743ms","start":"2026-02-21T02:39:24.390027Z","end":"2026-02-21T02:39:24.604322Z","steps":["trace[1393277899] 'agreement among raft nodes before linearized reading' (duration: 213.731829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:39:24.604407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.651868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-21T02:39:24.604488Z","caller":"traceutil/trace.go:171","msg":"trace[1179335632] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1326; }","duration":"174.899204ms","start":"2026-02-21T02:39:24.429569Z","end":"2026-02-21T02:39:24.604469Z","steps":["trace[1179335632] 'agreement among raft nodes before linearized reading' (duration: 174.565675ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:25.479371Z","caller":"traceutil/trace.go:171","msg":"trace[1882582913] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"140.868812ms","start":"2026-02-21T02:39:25.338475Z","end":"2026-02-21T02:39:25.479343Z","steps":["trace[1882582913] 'process raft request' (duration: 140.669727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:39:25.856879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.675704ms","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-21T02:39:25.856936Z","caller":"traceutil/trace.go:171","msg":"trace[90720272] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1328; }","duration":"115.813347ms","start":"2026-02-21T02:39:25.741111Z","end":"2026-02-21T02:39:25.856924Z","steps":["trace[90720272] 'range keys from in-memory index tree' (duration: 115.512159ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:26.046241Z","caller":"traceutil/trace.go:171","msg":"trace[1716680985] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"185.077143ms","start":"2026-02-21T02:39:25.861107Z","end":"2026-02-21T02:39:26.046184Z","steps":["trace[1716680985] 'process raft request' (duration: 184.933809ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:26.282877Z","caller":"traceutil/trace.go:171","msg":"trace[2046128260] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"117.258983ms","start":"2026-02-21T02:39:26.165575Z","end":"2026-02-21T02:39:26.282833Z","steps":["trace[2046128260] 'process raft request' (duration: 116.889793ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:39:26.482334Z","caller":"traceutil/trace.go:171","msg":"trace[681930431] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"139.60896ms","start":"2026-02-21T02:39:26.342686Z","end":"2026-02-21T02:39:26.482295Z","steps":["trace[681930431] 'process raft request' (duration: 56.866482ms)","trace[681930431] 'compare' (duration: 82.523932ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:39:26.822986Z","caller":"traceutil/trace.go:171","msg":"trace[974934624] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"201.326223ms","start":"2026-02-21T02:39:26.621621Z","end":"2026-02-21T02:39:26.822947Z","steps":["trace[974934624] 'process raft request' (duration: 132.342355ms)","trace[974934624] 'compare' (duration: 68.837374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:40:11.059352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.785037ms","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-21T02:40:11.059435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.64006ms","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-21T02:40:11.059481Z","caller":"traceutil/trace.go:171","msg":"trace[747997316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1511; }","duration":"183.975102ms","start":"2026-02-21T02:40:10.87548Z","end":"2026-02-21T02:40:11.059455Z","steps":["trace[747997316] 'range keys from in-memory index tree' (duration: 183.402998ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:11.05953Z","caller":"traceutil/trace.go:171","msg":"trace[912519334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1511; }","duration":"362.769753ms","start":"2026-02-21T02:40:10.696736Z","end":"2026-02-21T02:40:11.059505Z","steps":["trace[912519334] 'range keys from in-memory index tree' (duration: 362.173308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:11.059571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:40:10.696718Z","time spent":"362.844936ms","remote":"127.0.0.1:55698","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-21T02:40:11.308496Z","caller":"traceutil/trace.go:171","msg":"trace[1278353042] transaction","detail":"{read_only:false; response_revision:1512; number_of_response:1; }","duration":"242.926593ms","start":"2026-02-21T02:40:11.065539Z","end":"2026-02-21T02:40:11.308466Z","steps":["trace[1278353042] 'process raft request' (duration: 242.755689ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:11.315934Z","caller":"traceutil/trace.go:171","msg":"trace[1232482464] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"250.372562ms","start":"2026-02-21T02:40:11.065539Z","end":"2026-02-21T02:40:11.315912Z","steps":["trace[1232482464] 'process raft request' (duration: 250.222148ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:11.316082Z","caller":"traceutil/trace.go:171","msg":"trace[2004957420] linearizableReadLoop","detail":"{readStateIndex:1580; appliedIndex:1577; }","duration":"182.470764ms","start":"2026-02-21T02:40:11.13359Z","end":"2026-02-21T02:40:11.316061Z","steps":["trace[2004957420] 'read index received' (duration: 174.689747ms)","trace[2004957420] 'applied index is now lower than readState.Index' (duration: 7.779647ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:11.316153Z","caller":"traceutil/trace.go:171","msg":"trace[1775935203] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"249.073019ms","start":"2026-02-21T02:40:11.067055Z","end":"2026-02-21T02:40:11.316128Z","steps":["trace[1775935203] 'process raft request' (duration: 248.814733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:11.316596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.004828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-21T02:40:11.316711Z","caller":"traceutil/trace.go:171","msg":"trace[1840431497] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1514; }","duration":"183.176732ms","start":"2026-02-21T02:40:11.133503Z","end":"2026-02-21T02:40:11.31668Z","steps":["trace[1840431497] 'agreement among raft nodes before linearized reading' (duration: 182.624768ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:11.456391Z","caller":"traceutil/trace.go:171","msg":"trace[2034361363] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"134.430399ms","start":"2026-02-21T02:40:11.321931Z","end":"2026-02-21T02:40:11.456362Z","steps":["trace[2034361363] 'process raft request' (duration: 51.988445ms)","trace[2034361363] 'compare' (duration: 82.26803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:40:11.936606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.595498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:40:11.936674Z","caller":"traceutil/trace.go:171","msg":"trace[338039525] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1515; }","duration":"182.70518ms","start":"2026-02-21T02:40:11.75395Z","end":"2026-02-21T02:40:11.936656Z","steps":["trace[338039525] 'range keys from in-memory index tree' (duration: 182.384073ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:12.318278Z","caller":"traceutil/trace.go:171","msg":"trace[1767502322] linearizableReadLoop","detail":"{readStateIndex:1582; appliedIndex:1581; }","duration":"326.941578ms","start":"2026-02-21T02:40:11.991303Z","end":"2026-02-21T02:40:12.318244Z","steps":["trace[1767502322] 'read index received' (duration: 326.778583ms)","trace[1767502322] 'applied index is now lower than readState.Index' (duration: 161.414µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:12.318349Z","caller":"traceutil/trace.go:171","msg":"trace[1979930709] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"376.416518ms","start":"2026-02-21T02:40:11.941902Z","end":"2026-02-21T02:40:12.318319Z","steps":["trace[1979930709] 'process raft request' (duration: 376.141502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:12.318496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:40:11.94188Z","time spent":"376.537602ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:40:12.318554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.238024ms","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:535"} {"level":"info","ts":"2026-02-21T02:40:12.3186Z","caller":"traceutil/trace.go:171","msg":"trace[375598248] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1516; }","duration":"327.301576ms","start":"2026-02-21T02:40:11.991284Z","end":"2026-02-21T02:40:12.318586Z","steps":["trace[375598248] 'agreement among raft nodes before linearized reading' (duration: 327.115141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:12.318643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:40:11.991209Z","time spent":"327.425489ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-21T02:40:12.581291Z","caller":"traceutil/trace.go:171","msg":"trace[632145383] linearizableReadLoop","detail":"{readStateIndex:1583; appliedIndex:1582; }","duration":"262.904018ms","start":"2026-02-21T02:40:12.318367Z","end":"2026-02-21T02:40:12.581271Z","steps":["trace[632145383] 'read index received' (duration: 171.453476ms)","trace[632145383] 'applied index is now lower than readState.Index' (duration: 91.449172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:40:12.581799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.134561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-21T02:40:12.581914Z","caller":"traceutil/trace.go:171","msg":"trace[1110535318] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1516; }","duration":"476.273924ms","start":"2026-02-21T02:40:12.105602Z","end":"2026-02-21T02:40:12.581876Z","steps":["trace[1110535318] 'agreement among raft nodes before linearized reading' (duration: 475.770852ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:12.582017Z","caller":"traceutil/trace.go:171","msg":"trace[1878382894] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"258.026785ms","start":"2026-02-21T02:40:12.323976Z","end":"2026-02-21T02:40:12.582002Z","steps":["trace[1878382894] 'process raft request' (duration: 257.211634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:12.582071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.948665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:40:12.58214Z","caller":"traceutil/trace.go:171","msg":"trace[1959820193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1517; }","duration":"192.070518ms","start":"2026-02-21T02:40:12.390052Z","end":"2026-02-21T02:40:12.582123Z","steps":["trace[1959820193] 'agreement among raft nodes before linearized reading' (duration: 191.894433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:12.582419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:40:12.105587Z","time spent":"476.778927ms","remote":"127.0.0.1:55544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":150,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-02-21T02:40:12.836857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.205269ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:40:12.837322Z","caller":"traceutil/trace.go:171","msg":"trace[108579085] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1584; }","duration":"211.432446ms","start":"2026-02-21T02:40:12.625844Z","end":"2026-02-21T02:40:12.837276Z","steps":["trace[108579085] 'read index received' (duration: 109.616172ms)","trace[108579085] 'applied index is now lower than readState.Index' (duration: 101.814704ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:12.837317Z","caller":"traceutil/trace.go:171","msg":"trace[1667942742] transaction","detail":"{read_only:false; response_revision:1518; number_of_response:1; }","duration":"250.515605ms","start":"2026-02-21T02:40:12.58677Z","end":"2026-02-21T02:40:12.837286Z","steps":["trace[1667942742] 'process raft request' (duration: 148.700811ms)","trace[1667942742] 'compare' (duration: 101.022494ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:40:12.837489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.630661ms","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-21T02:40:12.837534Z","caller":"traceutil/trace.go:171","msg":"trace[959371033] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1518; }","duration":"211.723304ms","start":"2026-02-21T02:40:12.6258Z","end":"2026-02-21T02:40:12.837523Z","steps":["trace[959371033] 'agreement among raft nodes before linearized reading' (duration: 211.588801ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:13.003802Z","caller":"traceutil/trace.go:171","msg":"trace[491228513] transaction","detail":"{read_only:false; response_revision:1519; number_of_response:1; }","duration":"162.763186ms","start":"2026-02-21T02:40:12.841007Z","end":"2026-02-21T02:40:13.00377Z","steps":["trace[491228513] 'process raft request' (duration: 104.277368ms)","trace[491228513] 'compare' (duration: 58.336684ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:13.435924Z","caller":"traceutil/trace.go:171","msg":"trace[279970846] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"116.192938ms","start":"2026-02-21T02:40:13.319705Z","end":"2026-02-21T02:40:13.435898Z","steps":["trace[279970846] 'read index received' (duration: 45.09133ms)","trace[279970846] 'applied index is now lower than readState.Index' (duration: 71.100168ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:13.43607Z","caller":"traceutil/trace.go:171","msg":"trace[1713369982] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"118.371144ms","start":"2026-02-21T02:40:13.317687Z","end":"2026-02-21T02:40:13.436058Z","steps":["trace[1713369982] 'process raft request' (duration: 47.110192ms)","trace[1713369982] 'compare' (duration: 70.999876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:40:13.43629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.575979ms","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-21T02:40:13.436327Z","caller":"traceutil/trace.go:171","msg":"trace[1136023818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1521; }","duration":"116.65384ms","start":"2026-02-21T02:40:13.319665Z","end":"2026-02-21T02:40:13.436318Z","steps":["trace[1136023818] 'agreement among raft nodes before linearized reading' (duration: 116.521477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:13.436503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.916721ms","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-21T02:40:13.436649Z","caller":"traceutil/trace.go:171","msg":"trace[1341879875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1521; }","duration":"116.088846ms","start":"2026-02-21T02:40:13.320534Z","end":"2026-02-21T02:40:13.436623Z","steps":["trace[1341879875] 'agreement among raft nodes before linearized reading' (duration: 115.747478ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:15.668268Z","caller":"traceutil/trace.go:171","msg":"trace[2033882698] transaction","detail":"{read_only:false; response_revision:1530; number_of_response:1; }","duration":"132.734777ms","start":"2026-02-21T02:40:15.535507Z","end":"2026-02-21T02:40:15.668241Z","steps":["trace[2033882698] 'process raft request' (duration: 65.29133ms)","trace[2033882698] 'compare' (duration: 67.257171ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:15.668477Z","caller":"traceutil/trace.go:171","msg":"trace[614640699] transaction","detail":"{read_only:false; response_revision:1531; number_of_response:1; }","duration":"132.744118ms","start":"2026-02-21T02:40:15.535702Z","end":"2026-02-21T02:40:15.668446Z","steps":["trace[614640699] 'process raft request' (duration: 132.499242ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:16.480498Z","caller":"traceutil/trace.go:171","msg":"trace[1533071739] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"139.61117ms","start":"2026-02-21T02:40:16.34086Z","end":"2026-02-21T02:40:16.480471Z","steps":["trace[1533071739] 'process raft request' (duration: 139.350803ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:40:17.101512Z","caller":"traceutil/trace.go:171","msg":"trace[1080947136] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"201.66668ms","start":"2026-02-21T02:40:16.899823Z","end":"2026-02-21T02:40:17.10149Z","steps":["trace[1080947136] 'process raft request' (duration: 201.516106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:40:22.895389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.426902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:40:22.895573Z","caller":"traceutil/trace.go:171","msg":"trace[602049730] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"295.41712ms","start":"2026-02-21T02:40:22.600128Z","end":"2026-02-21T02:40:22.895546Z","steps":["trace[602049730] 'process raft request' (duration: 188.691802ms)","trace[602049730] 'compare' (duration: 106.219046ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:40:33.62421Z","caller":"traceutil/trace.go:171","msg":"trace[1902777163] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"189.343778ms","start":"2026-02-21T02:40:33.434817Z","end":"2026-02-21T02:40:33.624161Z","steps":["trace[1902777163] 'process raft request' (duration: 123.738639ms)","trace[1902777163] 'compare' (duration: 65.175908ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:30.211092Z","caller":"traceutil/trace.go:171","msg":"trace[410369806] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"188.126292ms","start":"2026-02-21T02:41:30.022936Z","end":"2026-02-21T02:41:30.211063Z","steps":["trace[410369806] 'process raft request' (duration: 187.906937ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:37.054276Z","caller":"traceutil/trace.go:171","msg":"trace[840054010] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1969; }","duration":"108.682596ms","start":"2026-02-21T02:41:36.945569Z","end":"2026-02-21T02:41:37.054252Z","steps":["trace[840054010] 'read index received' (duration: 13.651395ms)","trace[840054010] 'applied index is now lower than readState.Index' (duration: 95.030361ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:37.054362Z","caller":"traceutil/trace.go:171","msg":"trace[565493656] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"116.518551ms","start":"2026-02-21T02:41:36.937795Z","end":"2026-02-21T02:41:37.054313Z","steps":["trace[565493656] 'process raft request' (duration: 75.560795ms)","trace[565493656] 'compare' (duration: 40.807972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:41:37.054507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.921933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-21T02:41:37.054568Z","caller":"traceutil/trace.go:171","msg":"trace[1932048065] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1889; }","duration":"109.011645ms","start":"2026-02-21T02:41:36.945539Z","end":"2026-02-21T02:41:37.05455Z","steps":["trace[1932048065] 'agreement among raft nodes before linearized reading' (duration: 108.791489ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:37.825192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.120363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:41:37.825338Z","caller":"traceutil/trace.go:171","msg":"trace[342376644] linearizableReadLoop","detail":"{readStateIndex:1976; appliedIndex:1975; }","duration":"572.028558ms","start":"2026-02-21T02:41:37.253284Z","end":"2026-02-21T02:41:37.825312Z","steps":["trace[342376644] 'read index received' (duration: 160.666839ms)","trace[342376644] 'applied index is now lower than readState.Index' (duration: 411.360229ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:41:37.825484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.188752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:41:37.825401Z","caller":"traceutil/trace.go:171","msg":"trace[348075281] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"585.16659ms","start":"2026-02-21T02:41:37.240175Z","end":"2026-02-21T02:41:37.825342Z","steps":["trace[348075281] 'process raft request' (duration: 173.78517ms)","trace[348075281] 'compare' (duration: 410.947839ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:37.825555Z","caller":"traceutil/trace.go:171","msg":"trace[536542627] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1891; }","duration":"572.300525ms","start":"2026-02-21T02:41:37.253238Z","end":"2026-02-21T02:41:37.825539Z","steps":["trace[536542627] 'agreement among raft nodes before linearized reading' (duration: 572.147581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:37.825611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:37.253219Z","time spent":"572.379917ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-21T02:41:37.825613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.566999ms","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-21T02:41:37.825668Z","caller":"traceutil/trace.go:171","msg":"trace[512199046] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1891; }","duration":"127.684581ms","start":"2026-02-21T02:41:37.697972Z","end":"2026-02-21T02:41:37.825656Z","steps":["trace[512199046] 'agreement among raft nodes before linearized reading' (duration: 127.463496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:37.82567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:37.240152Z","time spent":"585.422027ms","remote":"127.0.0.1:55484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:41:38.891522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9078865964035271051,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-21T02:41:38.89816Z","caller":"traceutil/trace.go:171","msg":"trace[1747691931] linearizableReadLoop","detail":"{readStateIndex:1978; appliedIndex:1977; }","duration":"507.674173ms","start":"2026-02-21T02:41:38.390464Z","end":"2026-02-21T02:41:38.898138Z","steps":["trace[1747691931] 'read index received' (duration: 507.58087ms)","trace[1747691931] 'applied index is now lower than readState.Index' (duration: 92.343µs)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:38.898238Z","caller":"traceutil/trace.go:171","msg":"trace[953235846] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"628.841805ms","start":"2026-02-21T02:41:38.269363Z","end":"2026-02-21T02:41:38.898205Z","steps":["trace[953235846] 'process raft request' (duration: 628.62276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:38.898289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.797727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:41:38.898341Z","caller":"traceutil/trace.go:171","msg":"trace[520728694] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1893; }","duration":"507.903869ms","start":"2026-02-21T02:41:38.390424Z","end":"2026-02-21T02:41:38.898327Z","steps":["trace[520728694] 'agreement among raft nodes before linearized reading' (duration: 507.799096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:38.898343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.051863ms","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:535"} {"level":"info","ts":"2026-02-21T02:41:38.898383Z","caller":"traceutil/trace.go:171","msg":"trace[1881675176] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1893; }","duration":"428.129795ms","start":"2026-02-21T02:41:38.470241Z","end":"2026-02-21T02:41:38.89837Z","steps":["trace[1881675176] 'agreement among raft nodes before linearized reading' (duration: 428.002591ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:38.898382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:38.390398Z","time spent":"507.975111ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-21T02:41:38.898404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.110436ms","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:510"} {"level":"warn","ts":"2026-02-21T02:41:38.898418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:38.269327Z","time spent":"629.00545ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-21T02:41:38.898463Z","caller":"traceutil/trace.go:171","msg":"trace[418937722] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1893; }","duration":"123.208939ms","start":"2026-02-21T02:41:38.775239Z","end":"2026-02-21T02:41:38.898448Z","steps":["trace[418937722] 'agreement among raft nodes before linearized reading' (duration: 123.065065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:38.89842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:38.470219Z","time spent":"428.192757ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-21T02:41:49.106182Z","caller":"traceutil/trace.go:171","msg":"trace[545466129] linearizableReadLoop","detail":"{readStateIndex:2030; appliedIndex:2029; }","duration":"127.274653ms","start":"2026-02-21T02:41:48.978886Z","end":"2026-02-21T02:41:49.106161Z","steps":["trace[545466129] 'read index received' (duration: 45.004556ms)","trace[545466129] 'applied index is now lower than readState.Index' (duration: 82.269137ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:49.10628Z","caller":"traceutil/trace.go:171","msg":"trace[1222358018] transaction","detail":"{read_only:false; response_revision:1942; number_of_response:1; }","duration":"139.328577ms","start":"2026-02-21T02:41:48.966904Z","end":"2026-02-21T02:41:49.106233Z","steps":["trace[1222358018] 'process raft request' (duration: 57.0476ms)","trace[1222358018] 'compare' (duration: 82.091492ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:41:49.10641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.496938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-21T02:41:49.106529Z","caller":"traceutil/trace.go:171","msg":"trace[490356853] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:1942; }","duration":"127.648421ms","start":"2026-02-21T02:41:48.978855Z","end":"2026-02-21T02:41:49.106503Z","steps":["trace[490356853] 'agreement among raft nodes before linearized reading' (duration: 127.418126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.106653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.381694ms","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:510"} {"level":"info","ts":"2026-02-21T02:41:49.106708Z","caller":"traceutil/trace.go:171","msg":"trace[2077209640] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1942; }","duration":"102.695422ms","start":"2026-02-21T02:41:49.003999Z","end":"2026-02-21T02:41:49.106695Z","steps":["trace[2077209640] 'agreement among raft nodes before linearized reading' (duration: 102.301422ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.30073Z","caller":"traceutil/trace.go:171","msg":"trace[1353292203] transaction","detail":"{read_only:false; response_revision:1943; number_of_response:1; }","duration":"188.64261ms","start":"2026-02-21T02:41:49.112052Z","end":"2026-02-21T02:41:49.300694Z","steps":["trace[1353292203] 'process raft request' (duration: 186.390373ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309418Z","caller":"traceutil/trace.go:171","msg":"trace[1148033821] transaction","detail":"{read_only:false; response_revision:1945; number_of_response:1; }","duration":"196.753585ms","start":"2026-02-21T02:41:49.112635Z","end":"2026-02-21T02:41:49.309389Z","steps":["trace[1148033821] 'process raft request' (duration: 196.675603ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309612Z","caller":"traceutil/trace.go:171","msg":"trace[2141347866] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2030; }","duration":"192.934828ms","start":"2026-02-21T02:41:49.116664Z","end":"2026-02-21T02:41:49.309599Z","steps":["trace[2141347866] 'read index received' (duration: 181.776346ms)","trace[2141347866] 'applied index is now lower than readState.Index' (duration: 11.157812ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:49.309421Z","caller":"traceutil/trace.go:171","msg":"trace[200077275] transaction","detail":"{read_only:false; response_revision:1944; number_of_response:1; }","duration":"196.934069ms","start":"2026-02-21T02:41:49.112467Z","end":"2026-02-21T02:41:49.309401Z","steps":["trace[200077275] 'process raft request' (duration: 196.701133ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309646Z","caller":"traceutil/trace.go:171","msg":"trace[1654957542] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"196.737344ms","start":"2026-02-21T02:41:49.112879Z","end":"2026-02-21T02:41:49.309616Z","steps":["trace[1654957542] 'process raft request' (duration: 196.472098ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309711Z","caller":"traceutil/trace.go:171","msg":"trace[588249646] transaction","detail":"{read_only:false; response_revision:1947; number_of_response:1; }","duration":"195.643706ms","start":"2026-02-21T02:41:49.114049Z","end":"2026-02-21T02:41:49.309692Z","steps":["trace[588249646] 'process raft request' (duration: 195.495492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.30983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.737747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1078"} {"level":"warn","ts":"2026-02-21T02:41:49.309844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.10541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-21T02:41:49.30987Z","caller":"traceutil/trace.go:171","msg":"trace[415746613] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1947; }","duration":"120.804808ms","start":"2026-02-21T02:41:49.189051Z","end":"2026-02-21T02:41:49.309856Z","steps":["trace[415746613] 'agreement among raft nodes before linearized reading' (duration: 120.726296ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309889Z","caller":"traceutil/trace.go:171","msg":"trace[46165936] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1947; }","duration":"139.185842ms","start":"2026-02-21T02:41:49.17069Z","end":"2026-02-21T02:41:49.309875Z","steps":["trace[46165936] 'agreement among raft nodes before linearized reading' (duration: 139.022128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.309823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.1296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"warn","ts":"2026-02-21T02:41:49.309944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.277317ms","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-21T02:41:49.309955Z","caller":"traceutil/trace.go:171","msg":"trace[1119146488] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1947; }","duration":"116.286385ms","start":"2026-02-21T02:41:49.193649Z","end":"2026-02-21T02:41:49.309935Z","steps":["trace[1119146488] 'agreement among raft nodes before linearized reading' (duration: 116.11321ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.309983Z","caller":"traceutil/trace.go:171","msg":"trace[1084556672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1947; }","duration":"193.323278ms","start":"2026-02-21T02:41:49.116646Z","end":"2026-02-21T02:41:49.309969Z","steps":["trace[1084556672] 'agreement among raft nodes before linearized reading' (duration: 193.203154ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.631862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.592407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:41:49.631983Z","caller":"traceutil/trace.go:171","msg":"trace[760553522] linearizableReadLoop","detail":"{readStateIndex:2037; appliedIndex:2035; }","duration":"314.899516ms","start":"2026-02-21T02:41:49.317062Z","end":"2026-02-21T02:41:49.631962Z","steps":["trace[760553522] 'read index received' (duration: 503.672µs)","trace[760553522] 'applied index is now lower than readState.Index' (duration: 314.394534ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:49.632245Z","caller":"traceutil/trace.go:171","msg":"trace[2108064527] transaction","detail":"{read_only:false; response_revision:1949; number_of_response:1; }","duration":"317.323008ms","start":"2026-02-21T02:41:49.314854Z","end":"2026-02-21T02:41:49.632177Z","steps":["trace[2108064527] 'process raft request' (duration: 111.270678ms)","trace[2108064527] 'compare' (duration: 205.376722ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:41:49.6323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.687849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:41:49.632385Z","caller":"traceutil/trace.go:171","msg":"trace[963960747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1949; }","duration":"241.799873ms","start":"2026-02-21T02:41:49.390561Z","end":"2026-02-21T02:41:49.63236Z","steps":["trace[963960747] 'agreement among raft nodes before linearized reading' (duration: 241.669079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.632451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:49.314824Z","time spent":"317.520203ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:41:49.632826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.762009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" ","response":"range_response_count:1 size:1078"} {"level":"warn","ts":"2026-02-21T02:41:49.632866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.436636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-02-21T02:41:49.632899Z","caller":"traceutil/trace.go:171","msg":"trace[1193148138] range","detail":"{range_begin:/registry/clusterroles/system:controller:persistent-volume-binder; range_end:; response_count:1; response_revision:1949; }","duration":"315.861931ms","start":"2026-02-21T02:41:49.317025Z","end":"2026-02-21T02:41:49.632887Z","steps":["trace[1193148138] 'agreement among raft nodes before linearized reading' (duration: 315.742218ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:49.632916Z","caller":"traceutil/trace.go:171","msg":"trace[2117903642] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:1949; }","duration":"251.524267ms","start":"2026-02-21T02:41:49.381375Z","end":"2026-02-21T02:41:49.6329Z","steps":["trace[2117903642] 'agreement among raft nodes before linearized reading' (duration: 251.387944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:49.632964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:49.317009Z","time spent":"315.945253ms","remote":"127.0.0.1:55798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":1100,"request content":"key:\"/registry/clusterroles/system:controller:persistent-volume-binder\" "} {"level":"warn","ts":"2026-02-21T02:41:50.180576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.207653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:daemon-set-controller\" ","response":"range_response_count:1 size:759"} {"level":"warn","ts":"2026-02-21T02:41:50.180666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.113586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:665"} {"level":"info","ts":"2026-02-21T02:41:50.180781Z","caller":"traceutil/trace.go:171","msg":"trace[2096700741] range","detail":"{range_begin:/registry/clusterroles/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:1949; }","duration":"224.240619ms","start":"2026-02-21T02:41:49.956494Z","end":"2026-02-21T02:41:50.180735Z","steps":["trace[2096700741] 'range keys from in-memory index tree' (duration: 224.031824ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:50.181273Z","caller":"traceutil/trace.go:171","msg":"trace[2136395844] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:daemon-set-controller; range_end:; response_count:1; response_revision:1949; }","duration":"244.366157ms","start":"2026-02-21T02:41:49.936276Z","end":"2026-02-21T02:41:50.180642Z","steps":["trace[2136395844] 'range keys from in-memory index tree' (duration: 244.10741ms)"],"step_count":1} {"level":"info","ts":"2026-02-21T02:41:50.354308Z","caller":"traceutil/trace.go:171","msg":"trace[1034578594] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"101.009538ms","start":"2026-02-21T02:41:50.253267Z","end":"2026-02-21T02:41:50.354277Z","steps":["trace[1034578594] 'process raft request' (duration: 100.810074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:51.703922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.216594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-21T02:41:51.704034Z","caller":"traceutil/trace.go:171","msg":"trace[285723808] linearizableReadLoop","detail":"{readStateIndex:2043; appliedIndex:2042; }","duration":"324.411166ms","start":"2026-02-21T02:41:51.379605Z","end":"2026-02-21T02:41:51.704016Z","steps":["trace[285723808] 'read index received' (duration: 138.988947ms)","trace[285723808] 'applied index is now lower than readState.Index' (duration: 185.420879ms)"],"step_count":2} {"level":"info","ts":"2026-02-21T02:41:51.704073Z","caller":"traceutil/trace.go:171","msg":"trace[42421490] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"327.701969ms","start":"2026-02-21T02:41:51.376344Z","end":"2026-02-21T02:41:51.704046Z","steps":["trace[42421490] 'process raft request' (duration: 142.26559ms)","trace[42421490] 'compare' (duration: 185.051779ms)"],"step_count":2} {"level":"warn","ts":"2026-02-21T02:41:51.70417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.528848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:700"} {"level":"info","ts":"2026-02-21T02:41:51.704203Z","caller":"traceutil/trace.go:171","msg":"trace[1749578174] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1955; }","duration":"316.591369ms","start":"2026-02-21T02:41:51.387598Z","end":"2026-02-21T02:41:51.70419Z","steps":["trace[1749578174] 'agreement among raft nodes before linearized reading' (duration: 316.513137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:51.704186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.56271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"warn","ts":"2026-02-21T02:41:51.704237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:51.387576Z","time spent":"316.653521ms","remote":"127.0.0.1:55798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":722,"request content":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" "} {"level":"info","ts":"2026-02-21T02:41:51.704268Z","caller":"traceutil/trace.go:171","msg":"trace[1355665601] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:1955; }","duration":"324.675593ms","start":"2026-02-21T02:41:51.379569Z","end":"2026-02-21T02:41:51.704244Z","steps":["trace[1355665601] 'agreement among raft nodes before linearized reading' (duration: 324.521539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:51.704287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:51.376323Z","time spent":"327.841843ms","remote":"127.0.0.1:55698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-21T02:41:51.704309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:51.379558Z","time spent":"324.741514ms","remote":"127.0.0.1:55812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":765,"request content":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" "} {"level":"warn","ts":"2026-02-21T02:41:51.704506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.768993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-21T02:41:51.704546Z","caller":"traceutil/trace.go:171","msg":"trace[605355303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1955; }","duration":"316.818464ms","start":"2026-02-21T02:41:51.387718Z","end":"2026-02-21T02:41:51.704536Z","steps":["trace[605355303] 'agreement among raft nodes before linearized reading' (duration: 316.756673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-21T02:41:51.704572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-21T02:41:51.387714Z","time spent":"316.852646ms","remote":"127.0.0.1:55454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}