{"level":"warn","ts":"2026-02-26T06:15:39.999848Z","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-26T06:15:40.000177Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-26T06:15:40.000267Z","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-26T06:15:40.000276Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-26T06:15:40.000311Z","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-26T06:15:40.001178Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-26T06:15:40.001289Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-26T06:15:40.008541Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.935152ms"} {"level":"info","ts":"2026-02-26T06:15:40.0251Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-26T06:15:40.02522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T06:15:40.025249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-26T06:15:40.025262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T06:15:40.025278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-26T06:15:40.025386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-26T06:15:40.040298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T06:15:40.042021Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T06:15:40.043767Z","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-26T06:15:40.051555Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T06:15:40.051864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T06:15:40.052091Z","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-26T06:15:40.052232Z","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-26T06:15:40.052291Z","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-26T06:15:40.053608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-26T06:15:40.054386Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-26T06:15:40.056295Z","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-26T06:15:40.056479Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-26T06:15:40.056569Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-26T06:15:40.056622Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T06:15:40.056679Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T06:15:40.92638Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T06:15:40.926464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T06:15:40.926521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-26T06:15:40.926547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-26T06:15:40.926587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-26T06:15:40.926617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-26T06:15:40.92664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-26T06:15:40.928158Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:15:40.92912Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:15:40.929114Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T06:15:40.92921Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T06:15:40.92948Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T06:15:40.929526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T06:15:40.929805Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:15:40.929913Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:15:40.929948Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T06:15:40.933216Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-26T06:15:40.934143Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T06:15:59.176424Z","caller":"traceutil/trace.go:171","msg":"trace[1491039015] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"215.895953ms","start":"2026-02-26T06:15:58.960492Z","end":"2026-02-26T06:15:59.176388Z","steps":["trace[1491039015] 'process raft request' (duration: 215.659967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:15:59.464844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.748167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T06:15:59.464874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.766678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-26T06:15:59.46495Z","caller":"traceutil/trace.go:171","msg":"trace[390411967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"112.862849ms","start":"2026-02-26T06:15:59.352059Z","end":"2026-02-26T06:15:59.464922Z","steps":["trace[390411967] 'range keys from in-memory index tree' (duration: 112.624154ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:15:59.46496Z","caller":"traceutil/trace.go:171","msg":"trace[721115923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"217.93414ms","start":"2026-02-26T06:15:59.247006Z","end":"2026-02-26T06:15:59.46494Z","steps":["trace[721115923] 'range keys from in-memory index tree' (duration: 217.666736ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:05.392248Z","caller":"traceutil/trace.go:171","msg":"trace[1932690714] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"188.726893ms","start":"2026-02-26T06:16:05.203488Z","end":"2026-02-26T06:16:05.392215Z","steps":["trace[1932690714] 'process raft request' (duration: 188.502429ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:13.636154Z","caller":"traceutil/trace.go:171","msg":"trace[2080822361] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"206.007016ms","start":"2026-02-26T06:16:13.430114Z","end":"2026-02-26T06:16:13.636122Z","steps":["trace[2080822361] 'read index received' (duration: 205.879003ms)","trace[2080822361] 'applied index is now lower than readState.Index' (duration: 126.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:13.636244Z","caller":"traceutil/trace.go:171","msg":"trace[1298830161] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"392.074069ms","start":"2026-02-26T06:16:13.244134Z","end":"2026-02-26T06:16:13.636208Z","steps":["trace[1298830161] 'process raft request' (duration: 391.762244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:13.636485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.064967ms","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-26T06:16:13.636574Z","caller":"traceutil/trace.go:171","msg":"trace[1624840980] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"198.134238ms","start":"2026-02-26T06:16:13.438383Z","end":"2026-02-26T06:16:13.636517Z","steps":["trace[1624840980] 'agreement among raft nodes before linearized reading' (duration: 197.949085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:13.636833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.20212ms","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-26T06:16:13.636953Z","caller":"traceutil/trace.go:171","msg":"trace[1476152170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:474; }","duration":"100.385324ms","start":"2026-02-26T06:16:13.536534Z","end":"2026-02-26T06:16:13.636919Z","steps":["trace[1476152170] 'agreement among raft nodes before linearized reading' (duration: 100.016806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:13.636942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.821342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:16:13.636957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:13.244108Z","time spent":"392.218341ms","remote":"127.0.0.1:49266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4609,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:13.637032Z","caller":"traceutil/trace.go:171","msg":"trace[1007814655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"206.964575ms","start":"2026-02-26T06:16:13.430046Z","end":"2026-02-26T06:16:13.637011Z","steps":["trace[1007814655] 'agreement among raft nodes before linearized reading' (duration: 206.264201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:19.816434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.728332ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T06:16:19.816594Z","caller":"traceutil/trace.go:171","msg":"trace[1125225514] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"140.283392ms","start":"2026-02-26T06:16:19.676285Z","end":"2026-02-26T06:16:19.816568Z","steps":["trace[1125225514] 'read index received' (duration: 108.092µs)","trace[1125225514] 'applied index is now lower than readState.Index' (duration: 140.17355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:19.816813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.09613ms","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-26T06:16:19.816851Z","caller":"traceutil/trace.go:171","msg":"trace[1592786105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"139.221382ms","start":"2026-02-26T06:16:19.677618Z","end":"2026-02-26T06:16:19.81684Z","steps":["trace[1592786105] 'agreement among raft nodes before linearized reading' (duration: 139.10082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:19.816863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.169187ms","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-26T06:16:19.816945Z","caller":"traceutil/trace.go:171","msg":"trace[156138656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:491; }","duration":"134.265979ms","start":"2026-02-26T06:16:19.682658Z","end":"2026-02-26T06:16:19.816924Z","steps":["trace[156138656] 'agreement among raft nodes before linearized reading' (duration: 134.094316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:19.816931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.568309ms","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-26T06:16:19.817015Z","caller":"traceutil/trace.go:171","msg":"trace[1937908290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:491; }","duration":"131.689773ms","start":"2026-02-26T06:16:19.685307Z","end":"2026-02-26T06:16:19.816997Z","steps":["trace[1937908290] 'agreement among raft nodes before linearized reading' (duration: 131.454718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:19.817076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.768133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T06:16:19.81806Z","caller":"traceutil/trace.go:171","msg":"trace[1009172076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:491; }","duration":"141.284962ms","start":"2026-02-26T06:16:19.676204Z","end":"2026-02-26T06:16:19.817489Z","steps":["trace[1009172076] 'agreement among raft nodes before linearized reading' (duration: 140.484316ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:19.995264Z","caller":"traceutil/trace.go:171","msg":"trace[1674925221] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"172.216172ms","start":"2026-02-26T06:16:19.823016Z","end":"2026-02-26T06:16:19.995232Z","steps":["trace[1674925221] 'process raft request' (duration: 171.955617ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:20.252176Z","caller":"traceutil/trace.go:171","msg":"trace[909424439] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"428.96848ms","start":"2026-02-26T06:16:19.823179Z","end":"2026-02-26T06:16:20.252147Z","steps":["trace[909424439] 'process raft request' (duration: 374.208453ms)","trace[909424439] 'compare' (duration: 54.504653ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:20.252457Z","caller":"traceutil/trace.go:171","msg":"trace[1988136015] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"429.171155ms","start":"2026-02-26T06:16:19.823257Z","end":"2026-02-26T06:16:20.252428Z","steps":["trace[1988136015] 'process raft request' (duration: 428.839278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:20.252473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:19.823157Z","time spent":"429.201795ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:20.252634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:19.82325Z","time spent":"429.283867ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:20.252728Z","caller":"traceutil/trace.go:171","msg":"trace[2034979773] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"429.167446ms","start":"2026-02-26T06:16:19.82354Z","end":"2026-02-26T06:16:20.252707Z","steps":["trace[2034979773] 'process raft request' (duration: 428.802358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:20.252825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:19.823494Z","time spent":"429.286758ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:20.936341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261327,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:16:20.973723Z","caller":"traceutil/trace.go:171","msg":"trace[933511896] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"537.755173ms","start":"2026-02-26T06:16:20.435936Z","end":"2026-02-26T06:16:20.973691Z","steps":["trace[933511896] 'read index received' (duration: 537.496517ms)","trace[933511896] 'applied index is now lower than readState.Index' (duration: 257.516µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:20.973956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.982218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:20.974002Z","caller":"traceutil/trace.go:171","msg":"trace[1480396474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"538.072849ms","start":"2026-02-26T06:16:20.435918Z","end":"2026-02-26T06:16:20.973991Z","steps":["trace[1480396474] 'agreement among raft nodes before linearized reading' (duration: 537.921797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:20.97405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:20.435837Z","time spent":"538.203332ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:16:20.974494Z","caller":"traceutil/trace.go:171","msg":"trace[1839388241] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"700.705911ms","start":"2026-02-26T06:16:20.273728Z","end":"2026-02-26T06:16:20.974434Z","steps":["trace[1839388241] 'process raft request' (duration: 699.771265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:20.975241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:20.273704Z","time spent":"701.391804ms","remote":"127.0.0.1:49276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:22.757326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261331,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:16:23.042064Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.037243278s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T06:16:23.04264Z","caller":"traceutil/trace.go:171","msg":"trace[1121424576] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"786.234297ms","start":"2026-02-26T06:16:22.256354Z","end":"2026-02-26T06:16:23.042588Z","steps":["trace[1121424576] 'read index received' (duration: 785.86863ms)","trace[1121424576] 'applied index is now lower than readState.Index' (duration: 364.487µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:23.042789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.41513ms","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-26T06:16:23.042835Z","caller":"traceutil/trace.go:171","msg":"trace[1304494178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"786.509231ms","start":"2026-02-26T06:16:22.256311Z","end":"2026-02-26T06:16:23.04282Z","steps":["trace[1304494178] 'agreement among raft nodes before linearized reading' (duration: 786.406439ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:23.042796Z","caller":"traceutil/trace.go:171","msg":"trace[403588710] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.038101074s","start":"2026-02-26T06:16:22.004645Z","end":"2026-02-26T06:16:23.042746Z","steps":["trace[403588710] 'process raft request' (duration: 1.037673016s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:23.042884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.256298Z","time spent":"786.577153ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:16:23.043048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.272979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T06:16:23.043045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.004608Z","time spent":"1.038318449s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:23.043083Z","caller":"traceutil/trace.go:171","msg":"trace[1727548233] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"785.35823ms","start":"2026-02-26T06:16:22.257713Z","end":"2026-02-26T06:16:23.043071Z","steps":["trace[1727548233] 'agreement among raft nodes before linearized reading' (duration: 785.30608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:23.043117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.257684Z","time spent":"785.426792ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:16:23.043227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.920123ms","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-26T06:16:23.04325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.53403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:23.043257Z","caller":"traceutil/trace.go:171","msg":"trace[1157981078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"784.970994ms","start":"2026-02-26T06:16:22.258279Z","end":"2026-02-26T06:16:23.04325Z","steps":["trace[1157981078] 'agreement among raft nodes before linearized reading' (duration: 784.917273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:23.043288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.258267Z","time spent":"785.015344ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T06:16:23.043289Z","caller":"traceutil/trace.go:171","msg":"trace[448509887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"700.61306ms","start":"2026-02-26T06:16:22.342661Z","end":"2026-02-26T06:16:23.043274Z","steps":["trace[448509887] 'agreement among raft nodes before linearized reading' (duration: 700.531499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:23.043325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.374423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:23.043363Z","caller":"traceutil/trace.go:171","msg":"trace[1394057400] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:497; }","duration":"613.459594ms","start":"2026-02-26T06:16:22.429893Z","end":"2026-02-26T06:16:23.043352Z","steps":["trace[1394057400] 'agreement among raft nodes before linearized reading' (duration: 613.415683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:23.0434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.429853Z","time spent":"613.541215ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:16:23.043383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:22.342642Z","time spent":"700.718873ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:16:23.931121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261334,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:16:24.029294Z","caller":"traceutil/trace.go:171","msg":"trace[43416727] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"980.583034ms","start":"2026-02-26T06:16:23.048685Z","end":"2026-02-26T06:16:24.029268Z","steps":["trace[43416727] 'process raft request' (duration: 980.410201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:24.029481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:23.04865Z","time spent":"980.734688ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:24.054694Z","caller":"traceutil/trace.go:171","msg":"trace[1628131736] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"1.005764177s","start":"2026-02-26T06:16:23.048889Z","end":"2026-02-26T06:16:24.054653Z","steps":["trace[1628131736] 'process raft request' (duration: 1.005659015s)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:24.054865Z","caller":"traceutil/trace.go:171","msg":"trace[1438403982] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:516; }","duration":"624.320518ms","start":"2026-02-26T06:16:23.43051Z","end":"2026-02-26T06:16:24.05483Z","steps":["trace[1438403982] 'read index received' (duration: 598.617196ms)","trace[1438403982] 'applied index is now lower than readState.Index' (duration: 25.700172ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:24.054948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:23.048869Z","time spent":"1.005948801s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:24.055003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.492842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:24.055052Z","caller":"traceutil/trace.go:171","msg":"trace[1561121289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:501; }","duration":"624.604744ms","start":"2026-02-26T06:16:23.430433Z","end":"2026-02-26T06:16:24.055037Z","steps":["trace[1561121289] 'agreement among raft nodes before linearized reading' (duration: 624.514372ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:24.054714Z","caller":"traceutil/trace.go:171","msg":"trace[2049122509] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.005824118s","start":"2026-02-26T06:16:23.048849Z","end":"2026-02-26T06:16:24.054673Z","steps":["trace[2049122509] 'process raft request' (duration: 1.005507643s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:24.055091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:23.430401Z","time spent":"624.682595ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:16:24.055193Z","caller":"traceutil/trace.go:171","msg":"trace[1090547627] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"956.634575ms","start":"2026-02-26T06:16:23.09847Z","end":"2026-02-26T06:16:24.055105Z","steps":["trace[1090547627] 'process raft request' (duration: 956.126496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:24.055238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:23.0488Z","time spent":"1.006311727s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:24.055473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:23.098454Z","time spent":"956.84849ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:24.446274Z","caller":"traceutil/trace.go:171","msg":"trace[621880091] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"133.617468ms","start":"2026-02-26T06:16:24.312631Z","end":"2026-02-26T06:16:24.446248Z","steps":["trace[621880091] 'read index received' (duration: 133.534597ms)","trace[621880091] 'applied index is now lower than readState.Index' (duration: 81.381µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:24.44635Z","caller":"traceutil/trace.go:171","msg":"trace[1870141302] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"133.6939ms","start":"2026-02-26T06:16:24.312604Z","end":"2026-02-26T06:16:24.446298Z","steps":["trace[1870141302] 'process raft request' (duration: 133.479576ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:24.446617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.941745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T06:16:24.446736Z","caller":"traceutil/trace.go:171","msg":"trace[930741086] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:503; }","duration":"134.116938ms","start":"2026-02-26T06:16:24.312592Z","end":"2026-02-26T06:16:24.446708Z","steps":["trace[930741086] 'agreement among raft nodes before linearized reading' (duration: 133.772791ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:24.505544Z","caller":"traceutil/trace.go:171","msg":"trace[858998047] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"176.706266ms","start":"2026-02-26T06:16:24.32881Z","end":"2026-02-26T06:16:24.505516Z","steps":["trace[858998047] 'process raft request' (duration: 176.547653ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:27.463659Z","caller":"traceutil/trace.go:171","msg":"trace[444684271] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"127.220778ms","start":"2026-02-26T06:16:27.336405Z","end":"2026-02-26T06:16:27.463626Z","steps":["trace[444684271] 'process raft request' (duration: 127.045715ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:27.694834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.308893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:16:27.695105Z","caller":"traceutil/trace.go:171","msg":"trace[1114702009] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"346.910732ms","start":"2026-02-26T06:16:27.348147Z","end":"2026-02-26T06:16:27.695057Z","steps":["trace[1114702009] 'process raft request' (duration: 212.832005ms)","trace[1114702009] 'compare' (duration: 133.088778ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:27.695239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.295841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:27.695298Z","caller":"traceutil/trace.go:171","msg":"trace[912040465] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:552; }","duration":"125.394113ms","start":"2026-02-26T06:16:27.569879Z","end":"2026-02-26T06:16:27.695273Z","steps":["trace[912040465] 'agreement among raft nodes before linearized reading' (duration: 125.305481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:27.695342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:27.348117Z","time spent":"347.104825ms","remote":"127.0.0.1:49276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18048,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:27.695148Z","caller":"traceutil/trace.go:171","msg":"trace[1674223016] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"125.175179ms","start":"2026-02-26T06:16:27.569953Z","end":"2026-02-26T06:16:27.695128Z","steps":["trace[1674223016] 'read index received' (duration: 33.051µs)","trace[1674223016] 'applied index is now lower than readState.Index' (duration: 125.141398ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:27.695398Z","caller":"traceutil/trace.go:171","msg":"trace[1578273481] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"319.963466ms","start":"2026-02-26T06:16:27.375405Z","end":"2026-02-26T06:16:27.695369Z","steps":["trace[1578273481] 'process raft request' (duration: 319.609729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:27.695484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:27.375379Z","time spent":"320.068347ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T06:16:27.908227Z","caller":"traceutil/trace.go:171","msg":"trace[1106730854] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"134.483724ms","start":"2026-02-26T06:16:27.773714Z","end":"2026-02-26T06:16:27.908198Z","steps":["trace[1106730854] 'process raft request' (duration: 134.337672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.257187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.413763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:16:28.257309Z","caller":"traceutil/trace.go:171","msg":"trace[1084082931] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"285.442398ms","start":"2026-02-26T06:16:27.971847Z","end":"2026-02-26T06:16:28.25729Z","steps":["trace[1084082931] 'read index received' (duration: 36.731899ms)","trace[1084082931] 'applied index is now lower than readState.Index' (duration: 248.709179ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:28.257327Z","caller":"traceutil/trace.go:171","msg":"trace[864877646] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"302.741132ms","start":"2026-02-26T06:16:27.954559Z","end":"2026-02-26T06:16:28.257301Z","steps":["trace[864877646] 'process raft request' (duration: 54.116515ms)","trace[864877646] 'compare' (duration: 248.095327ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:28.257447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:27.954528Z","time spent":"302.856685ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T06:16:28.257504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.643192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-26T06:16:28.257544Z","caller":"traceutil/trace.go:171","msg":"trace[555112248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:555; }","duration":"285.731103ms","start":"2026-02-26T06:16:27.971803Z","end":"2026-02-26T06:16:28.257534Z","steps":["trace[555112248] 'agreement among raft nodes before linearized reading' (duration: 285.613521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.257845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.325234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:16:28.257874Z","caller":"traceutil/trace.go:171","msg":"trace[1228395437] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:555; }","duration":"188.407525ms","start":"2026-02-26T06:16:28.06946Z","end":"2026-02-26T06:16:28.257868Z","steps":["trace[1228395437] 'agreement among raft nodes before linearized reading' (duration: 188.307424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.257875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.071434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:16:28.257915Z","caller":"traceutil/trace.go:171","msg":"trace[2083011011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"217.161536ms","start":"2026-02-26T06:16:28.04074Z","end":"2026-02-26T06:16:28.257901Z","steps":["trace[2083011011] 'agreement among raft nodes before linearized reading' (duration: 216.947082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.258554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.052521ms","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-26T06:16:28.258607Z","caller":"traceutil/trace.go:171","msg":"trace[1649504153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"187.132443ms","start":"2026-02-26T06:16:28.071462Z","end":"2026-02-26T06:16:28.258594Z","steps":["trace[1649504153] 'agreement among raft nodes before linearized reading' (duration: 186.291646ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:28.520731Z","caller":"traceutil/trace.go:171","msg":"trace[347001025] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"257.980392ms","start":"2026-02-26T06:16:28.262711Z","end":"2026-02-26T06:16:28.520692Z","steps":["trace[347001025] 'process raft request' (duration: 253.549599ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:28.521117Z","caller":"traceutil/trace.go:171","msg":"trace[257664923] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"258.258257ms","start":"2026-02-26T06:16:28.262835Z","end":"2026-02-26T06:16:28.521093Z","steps":["trace[257664923] 'process raft request' (duration: 257.774748ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:28.521259Z","caller":"traceutil/trace.go:171","msg":"trace[1336316682] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"151.815569ms","start":"2026-02-26T06:16:28.369425Z","end":"2026-02-26T06:16:28.521241Z","steps":["trace[1336316682] 'read index received' (duration: 146.877097ms)","trace[1336316682] 'applied index is now lower than readState.Index' (duration: 4.936852ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:28.521337Z","caller":"traceutil/trace.go:171","msg":"trace[97605291] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"257.313529ms","start":"2026-02-26T06:16:28.263989Z","end":"2026-02-26T06:16:28.521303Z","steps":["trace[97605291] 'process raft request' (duration: 257.032474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.521536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.087384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-26T06:16:28.521589Z","caller":"traceutil/trace.go:171","msg":"trace[927384467] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:559; }","duration":"152.156295ms","start":"2026-02-26T06:16:28.369417Z","end":"2026-02-26T06:16:28.521574Z","steps":["trace[927384467] 'agreement among raft nodes before linearized reading' (duration: 151.87982ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:28.599283Z","caller":"traceutil/trace.go:171","msg":"trace[2114321084] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"186.958959ms","start":"2026-02-26T06:16:28.412286Z","end":"2026-02-26T06:16:28.599245Z","steps":["trace[2114321084] 'process raft request' (duration: 186.719405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:28.599725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.752783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:28.600214Z","caller":"traceutil/trace.go:171","msg":"trace[1732669173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"172.285054ms","start":"2026-02-26T06:16:28.427905Z","end":"2026-02-26T06:16:28.60019Z","steps":["trace[1732669173] 'agreement among raft nodes before linearized reading' (duration: 171.692932ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:28.875935Z","caller":"traceutil/trace.go:171","msg":"trace[718042029] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"100.552267ms","start":"2026-02-26T06:16:28.775346Z","end":"2026-02-26T06:16:28.875898Z","steps":["trace[718042029] 'process raft request' (duration: 100.312942ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.205975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.0123ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-26T06:16:30.208459Z","caller":"traceutil/trace.go:171","msg":"trace[81028737] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"600.422559ms","start":"2026-02-26T06:16:29.607983Z","end":"2026-02-26T06:16:30.208406Z","steps":["trace[81028737] 'process raft request' (duration: 599.887589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.208634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:29.607919Z","time spent":"600.636463ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T06:16:30.208676Z","caller":"traceutil/trace.go:171","msg":"trace[1319751416] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"238.239291ms","start":"2026-02-26T06:16:29.970402Z","end":"2026-02-26T06:16:30.208641Z","steps":["trace[1319751416] 'read index received' (duration: 8.601831ms)","trace[1319751416] 'applied index is now lower than readState.Index' (duration: 229.63587ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:30.208794Z","caller":"traceutil/trace.go:171","msg":"trace[344960598] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"408.361743ms","start":"2026-02-26T06:16:29.800352Z","end":"2026-02-26T06:16:30.208714Z","steps":["trace[344960598] 'process raft request' (duration: 408.032057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.209017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.620249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-02-26T06:16:30.209045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:29.800221Z","time spent":"408.70894ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T06:16:30.209065Z","caller":"traceutil/trace.go:171","msg":"trace[1548572640] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:573; }","duration":"238.73536ms","start":"2026-02-26T06:16:29.970312Z","end":"2026-02-26T06:16:30.209048Z","steps":["trace[1548572640] 'agreement among raft nodes before linearized reading' (duration: 238.473476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.804091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.742784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:16:30.804545Z","caller":"traceutil/trace.go:171","msg":"trace[1343235640] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"376.396397ms","start":"2026-02-26T06:16:30.428089Z","end":"2026-02-26T06:16:30.804485Z","steps":["trace[1343235640] 'read index received' (duration: 69.118497ms)","trace[1343235640] 'applied index is now lower than readState.Index' (duration: 307.27395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:30.804864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.747053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2mjhb\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-02-26T06:16:30.804997Z","caller":"traceutil/trace.go:171","msg":"trace[169645216] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2mjhb; range_end:; response_count:1; response_revision:576; }","duration":"376.901275ms","start":"2026-02-26T06:16:30.42807Z","end":"2026-02-26T06:16:30.804971Z","steps":["trace[169645216] 'agreement among raft nodes before linearized reading' (duration: 376.488999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.805124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.428058Z","time spent":"377.017996ms","remote":"127.0.0.1:49276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18357,"request content":"key:\"/registry/pods/kube-system/cilium-2mjhb\" "} {"level":"info","ts":"2026-02-26T06:16:30.805179Z","caller":"traceutil/trace.go:171","msg":"trace[828251457] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"375.395386ms","start":"2026-02-26T06:16:30.429742Z","end":"2026-02-26T06:16:30.805138Z","steps":["trace[828251457] 'process raft request' (duration: 374.487254ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:30.805196Z","caller":"traceutil/trace.go:171","msg":"trace[1270442482] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"431.124916ms","start":"2026-02-26T06:16:30.373223Z","end":"2026-02-26T06:16:30.804348Z","steps":["trace[1270442482] 'process raft request' (duration: 123.964786ms)","trace[1270442482] 'compare' (duration: 306.56469ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:30.805349Z","caller":"traceutil/trace.go:171","msg":"trace[1068586551] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"354.462963ms","start":"2026-02-26T06:16:30.450866Z","end":"2026-02-26T06:16:30.805329Z","steps":["trace[1068586551] 'process raft request' (duration: 353.58364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.807343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.450848Z","time spent":"356.35009ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:30.805373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.429731Z","time spent":"375.540359ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T06:16:30.805412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.373162Z","time spent":"432.12932ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:30.805704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.2669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:30.808791Z","caller":"traceutil/trace.go:171","msg":"trace[1285545038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"379.382445ms","start":"2026-02-26T06:16:30.429386Z","end":"2026-02-26T06:16:30.808768Z","steps":["trace[1285545038] 'agreement among raft nodes before linearized reading' (duration: 376.24991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.808868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.42937Z","time spent":"379.482286ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:16:30.805765Z","caller":"traceutil/trace.go:171","msg":"trace[2118315881] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"352.363901ms","start":"2026-02-26T06:16:30.453378Z","end":"2026-02-26T06:16:30.805742Z","steps":["trace[2118315881] 'process raft request' (duration: 351.899645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.809234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:30.453339Z","time spent":"355.827751ms","remote":"127.0.0.1:49144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:30.805854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.79779ms","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-26T06:16:30.809391Z","caller":"traceutil/trace.go:171","msg":"trace[1715187886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"282.384031ms","start":"2026-02-26T06:16:30.526982Z","end":"2026-02-26T06:16:30.809366Z","steps":["trace[1715187886] 'agreement among raft nodes before linearized reading' (duration: 278.78507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.805893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.936212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:16:30.809768Z","caller":"traceutil/trace.go:171","msg":"trace[974865427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"282.815508ms","start":"2026-02-26T06:16:30.52694Z","end":"2026-02-26T06:16:30.809755Z","steps":["trace[974865427] 'agreement among raft nodes before linearized reading' (duration: 278.853841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:30.805968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.079364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:16:30.809908Z","caller":"traceutil/trace.go:171","msg":"trace[1526034488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"283.044871ms","start":"2026-02-26T06:16:30.526851Z","end":"2026-02-26T06:16:30.809896Z","steps":["trace[1526034488] 'agreement among raft nodes before linearized reading' (duration: 279.064164ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:30.992448Z","caller":"traceutil/trace.go:171","msg":"trace[1449955544] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"168.53319ms","start":"2026-02-26T06:16:30.82389Z","end":"2026-02-26T06:16:30.992423Z","steps":["trace[1449955544] 'process raft request' (duration: 168.466149ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:30.992614Z","caller":"traceutil/trace.go:171","msg":"trace[1346146657] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"172.091383ms","start":"2026-02-26T06:16:30.820492Z","end":"2026-02-26T06:16:30.992584Z","steps":["trace[1346146657] 'process raft request' (duration: 166.509383ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:30.992599Z","caller":"traceutil/trace.go:171","msg":"trace[1434360821] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"168.676383ms","start":"2026-02-26T06:16:30.823882Z","end":"2026-02-26T06:16:30.992558Z","steps":["trace[1434360821] 'process raft request' (duration: 168.4197ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:31.040906Z","caller":"traceutil/trace.go:171","msg":"trace[699066512] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"107.442868ms","start":"2026-02-26T06:16:30.933419Z","end":"2026-02-26T06:16:31.040862Z","steps":["trace[699066512] 'process raft request' (duration: 107.065602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:31.41327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.464333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:16:31.413396Z","caller":"traceutil/trace.go:171","msg":"trace[1281217619] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"171.072527ms","start":"2026-02-26T06:16:31.242302Z","end":"2026-02-26T06:16:31.413375Z","steps":["trace[1281217619] 'read index received' (duration: 100.361µs)","trace[1281217619] 'applied index is now lower than readState.Index' (duration: 170.970626ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:31.4135Z","caller":"traceutil/trace.go:171","msg":"trace[860210132] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"322.025747ms","start":"2026-02-26T06:16:31.091435Z","end":"2026-02-26T06:16:31.413461Z","steps":["trace[860210132] 'process raft request' (duration: 50.230699ms)","trace[860210132] 'compare' (duration: 271.312052ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:31.413649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.355361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-02-26T06:16:31.41369Z","caller":"traceutil/trace.go:171","msg":"trace[722963973] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:584; }","duration":"171.437452ms","start":"2026-02-26T06:16:31.242238Z","end":"2026-02-26T06:16:31.413675Z","steps":["trace[722963973] 'agreement among raft nodes before linearized reading' (duration: 171.188229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:31.413736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:31.0914Z","time spent":"322.19439ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:32.207419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.598826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:32.207615Z","caller":"traceutil/trace.go:171","msg":"trace[23009039] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:587; }","duration":"141.9057ms","start":"2026-02-26T06:16:32.065668Z","end":"2026-02-26T06:16:32.207574Z","steps":["trace[23009039] 'range keys from in-memory index tree' (duration: 141.475484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:32.207455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.064921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:16:32.207868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.491797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:32.208071Z","caller":"traceutil/trace.go:171","msg":"trace[722531758] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:587; }","duration":"144.740701ms","start":"2026-02-26T06:16:32.063308Z","end":"2026-02-26T06:16:32.208049Z","steps":["trace[722531758] 'range keys from in-memory index tree' (duration: 143.856828ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:32.207804Z","caller":"traceutil/trace.go:171","msg":"trace[391336654] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:587; }","duration":"144.498837ms","start":"2026-02-26T06:16:32.063267Z","end":"2026-02-26T06:16:32.207766Z","steps":["trace[391336654] 'count revisions from in-memory index tree' (duration: 143.916869ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:44.67191Z","caller":"traceutil/trace.go:171","msg":"trace[1618851556] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"242.400008ms","start":"2026-02-26T06:16:44.429462Z","end":"2026-02-26T06:16:44.671862Z","steps":["trace[1618851556] 'read index received' (duration: 242.164785ms)","trace[1618851556] 'applied index is now lower than readState.Index' (duration: 233.153µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:44.671912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.187646Z","time spent":"484.257498ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T06:16:44.672203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.689833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:44.672355Z","caller":"traceutil/trace.go:171","msg":"trace[180057260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"242.939086ms","start":"2026-02-26T06:16:44.429381Z","end":"2026-02-26T06:16:44.67232Z","steps":["trace[180057260] 'agreement among raft nodes before linearized reading' (duration: 242.639411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.173176Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:16:45.673919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:16:45.878863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.207469134s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T06:16:45.879786Z","caller":"traceutil/trace.go:171","msg":"trace[345298604] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.32803796s","start":"2026-02-26T06:16:44.551354Z","end":"2026-02-26T06:16:45.879392Z","steps":["trace[345298604] 'process raft request' (duration: 1.327845118s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.879934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.551331Z","time spent":"1.328519297s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:45.951379Z","caller":"traceutil/trace.go:171","msg":"trace[575358053] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"1.279282393s","start":"2026-02-26T06:16:44.672069Z","end":"2026-02-26T06:16:45.951351Z","steps":["trace[575358053] 'read index received' (duration: 1.206986599s)","trace[575358053] 'applied index is now lower than readState.Index' (duration: 72.294724ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:45.95146Z","caller":"traceutil/trace.go:171","msg":"trace[1361469847] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.277872102s","start":"2026-02-26T06:16:44.673549Z","end":"2026-02-26T06:16:45.95141Z","steps":["trace[1361469847] 'process raft request' (duration: 1.277693389s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.951528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414523957s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:45.951551Z","caller":"traceutil/trace.go:171","msg":"trace[745092195] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:686; }","duration":"1.414616058s","start":"2026-02-26T06:16:44.536928Z","end":"2026-02-26T06:16:45.951544Z","steps":["trace[745092195] 'agreement among raft nodes before linearized reading' (duration: 1.414506447s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.951572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.536901Z","time spent":"1.414666449s","remote":"127.0.0.1:49398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:16:45.951633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.673518Z","time spent":"1.278016093s","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:45.951786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069933088s","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-26T06:16:45.951802Z","caller":"traceutil/trace.go:171","msg":"trace[1768018345] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"1.069991249s","start":"2026-02-26T06:16:44.881807Z","end":"2026-02-26T06:16:45.951798Z","steps":["trace[1768018345] 'agreement among raft nodes before linearized reading' (duration: 1.069927747s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.951816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.881786Z","time spent":"1.07002744s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:16:45.952195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.013387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:45.952248Z","caller":"traceutil/trace.go:171","msg":"trace[1852963665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"524.068308ms","start":"2026-02-26T06:16:45.428168Z","end":"2026-02-26T06:16:45.952236Z","steps":["trace[1852963665] 'agreement among raft nodes before linearized reading' (duration: 523.964096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.952284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.428077Z","time spent":"524.19902ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:16:45.952625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.300253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:16:45.952676Z","caller":"traceutil/trace.go:171","msg":"trace[1907797713] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:686; }","duration":"896.409095ms","start":"2026-02-26T06:16:45.056252Z","end":"2026-02-26T06:16:45.952661Z","steps":["trace[1907797713] 'agreement among raft nodes before linearized reading' (duration: 896.05559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.952711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.056221Z","time spent":"896.482326ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:16:45.952844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.070078ms","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-26T06:16:45.952879Z","caller":"traceutil/trace.go:171","msg":"trace[574301453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"466.158319ms","start":"2026-02-26T06:16:45.486711Z","end":"2026-02-26T06:16:45.95287Z","steps":["trace[574301453] 'agreement among raft nodes before linearized reading' (duration: 465.998627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.952909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.48669Z","time spent":"466.21167ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:16:45.953039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.063374003s","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-26T06:16:45.953346Z","caller":"traceutil/trace.go:171","msg":"trace[1928098420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"1.06378683s","start":"2026-02-26T06:16:44.889535Z","end":"2026-02-26T06:16:45.953322Z","steps":["trace[1928098420] 'agreement among raft nodes before linearized reading' (duration: 1.06314235s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.953408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:44.889494Z","time spent":"1.063901652s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:16:45.953434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.222276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:16:45.953535Z","caller":"traceutil/trace.go:171","msg":"trace[448494473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:686; }","duration":"897.390249ms","start":"2026-02-26T06:16:45.056116Z","end":"2026-02-26T06:16:45.953506Z","steps":["trace[448494473] 'agreement among raft nodes before linearized reading' (duration: 897.071544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:45.953626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.056093Z","time spent":"897.52059ms","remote":"127.0.0.1:49346","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-26T06:16:46.460066Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261688,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:16:46.642311Z","caller":"traceutil/trace.go:171","msg":"trace[1172571547] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"683.800073ms","start":"2026-02-26T06:16:45.95848Z","end":"2026-02-26T06:16:46.64228Z","steps":["trace[1172571547] 'process raft request' (duration: 679.019304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.642479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.958463Z","time spent":"683.926776ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:46.737815Z","caller":"traceutil/trace.go:171","msg":"trace[871568144] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"777.388391ms","start":"2026-02-26T06:16:45.960402Z","end":"2026-02-26T06:16:46.737791Z","steps":["trace[871568144] 'process raft request' (duration: 777.181878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.737985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.960387Z","time spent":"777.496162ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:46.738248Z","caller":"traceutil/trace.go:171","msg":"trace[63447268] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"777.745006ms","start":"2026-02-26T06:16:45.960484Z","end":"2026-02-26T06:16:46.738229Z","steps":["trace[63447268] 'process raft request' (duration: 777.26697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.73832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.960475Z","time spent":"777.813827ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:46.738521Z","caller":"traceutil/trace.go:171","msg":"trace[242430057] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"775.910901ms","start":"2026-02-26T06:16:45.962589Z","end":"2026-02-26T06:16:46.7385Z","steps":["trace[242430057] 'process raft request' (duration: 775.567506ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:46.738553Z","caller":"traceutil/trace.go:171","msg":"trace[1070456415] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"775.22352ms","start":"2026-02-26T06:16:45.963316Z","end":"2026-02-26T06:16:46.738539Z","steps":["trace[1070456415] 'process raft request' (duration: 775.019257ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:46.738537Z","caller":"traceutil/trace.go:171","msg":"trace[1713361822] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:711; }","duration":"779.305389ms","start":"2026-02-26T06:16:45.959199Z","end":"2026-02-26T06:16:46.738505Z","steps":["trace[1713361822] 'read index received' (duration: 678.383785ms)","trace[1713361822] 'applied index is now lower than readState.Index' (duration: 100.920104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:46.738606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.962569Z","time spent":"775.994242ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:46.738618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.9633Z","time spent":"775.283091ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:46.738708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:46.06522Z","time spent":"673.483405ms","remote":"127.0.0.1:49168","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-26T06:16:46.738759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.350457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:16:46.738799Z","caller":"traceutil/trace.go:171","msg":"trace[2058200832] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:691; }","duration":"641.415188ms","start":"2026-02-26T06:16:46.097372Z","end":"2026-02-26T06:16:46.738787Z","steps":["trace[2058200832] 'agreement among raft nodes before linearized reading' (duration: 641.348517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.738829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:46.097361Z","time spent":"641.460158ms","remote":"127.0.0.1:49460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:16:46.738854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.651454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T06:16:46.738877Z","caller":"traceutil/trace.go:171","msg":"trace[981209037] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:691; }","duration":"779.687765ms","start":"2026-02-26T06:16:45.959183Z","end":"2026-02-26T06:16:46.73887Z","steps":["trace[981209037] 'agreement among raft nodes before linearized reading' (duration: 779.639914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.738897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:45.959175Z","time spent":"779.718325ms","remote":"127.0.0.1:49348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-26T06:16:46.739015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.19904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:46.739053Z","caller":"traceutil/trace.go:171","msg":"trace[1006817947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"308.27135ms","start":"2026-02-26T06:16:46.430763Z","end":"2026-02-26T06:16:46.739035Z","steps":["trace[1006817947] 'agreement among raft nodes before linearized reading' (duration: 308.211829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:46.739079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:46.43073Z","time spent":"308.343721ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:16:47.930977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261697,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:16:48.231741Z","caller":"traceutil/trace.go:171","msg":"trace[1846215439] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"946.063545ms","start":"2026-02-26T06:16:47.285645Z","end":"2026-02-26T06:16:48.231709Z","steps":["trace[1846215439] 'process raft request' (duration: 945.495426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:48.231989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:47.285615Z","time spent":"946.205867ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:16:48.84219Z","caller":"traceutil/trace.go:171","msg":"trace[835839469] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"1.411839419s","start":"2026-02-26T06:16:47.430321Z","end":"2026-02-26T06:16:48.842161Z","steps":["trace[835839469] 'read index received' (duration: 800.862867ms)","trace[835839469] 'applied index is now lower than readState.Index' (duration: 610.975072ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:48.842272Z","caller":"traceutil/trace.go:171","msg":"trace[500968654] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"1.540387888s","start":"2026-02-26T06:16:47.301844Z","end":"2026-02-26T06:16:48.842232Z","steps":["trace[500968654] 'process raft request' (duration: 1.540156055s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:16:48.842474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:47.301814Z","time spent":"1.54056125s","remote":"127.0.0.1:49266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5212,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:16:48.868278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.521534ms","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-26T06:16:48.868364Z","caller":"traceutil/trace.go:171","msg":"trace[737927620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"125.652008ms","start":"2026-02-26T06:16:48.742682Z","end":"2026-02-26T06:16:48.868334Z","steps":["trace[737927620] 'agreement among raft nodes before linearized reading' (duration: 99.678756ms)","trace[737927620] 'range keys from in-memory index tree' (duration: 25.764758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:48.868535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.833987ms","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":"warn","ts":"2026-02-26T06:16:48.86859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.849153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:48.868614Z","caller":"traceutil/trace.go:171","msg":"trace[347490259] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:694; }","duration":"627.913434ms","start":"2026-02-26T06:16:48.240692Z","end":"2026-02-26T06:16:48.868606Z","steps":["trace[347490259] 'agreement among raft nodes before linearized reading' (duration: 601.649418ms)","trace[347490259] 'count revisions from in-memory index tree' (duration: 26.213965ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:48.868626Z","caller":"traceutil/trace.go:171","msg":"trace[916673680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"122.961049ms","start":"2026-02-26T06:16:48.745639Z","end":"2026-02-26T06:16:48.8686Z","steps":["trace[916673680] 'agreement among raft nodes before linearized reading' (duration: 96.705683ms)","trace[916673680] 'range keys from in-memory index tree' (duration: 26.034532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:48.868689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.732664ms","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-26T06:16:48.868717Z","caller":"traceutil/trace.go:171","msg":"trace[383106612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"124.777665ms","start":"2026-02-26T06:16:48.743926Z","end":"2026-02-26T06:16:48.868704Z","steps":["trace[383106612] 'agreement among raft nodes before linearized reading' (duration: 98.425998ms)","trace[383106612] 'range keys from in-memory index tree' (duration: 26.298156ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:48.868811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.740942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-26T06:16:48.868834Z","caller":"traceutil/trace.go:171","msg":"trace[881377593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"221.805583ms","start":"2026-02-26T06:16:48.647022Z","end":"2026-02-26T06:16:48.868828Z","steps":["trace[881377593] 'agreement among raft nodes before linearized reading' (duration: 195.341535ms)","trace[881377593] 'range keys from in-memory index tree' (duration: 26.409068ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:48.868634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:48.240672Z","time spent":"627.955875ms","remote":"127.0.0.1:49326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:16:48.869026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438718984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:16:48.869049Z","caller":"traceutil/trace.go:171","msg":"trace[1208819411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"1.438774914s","start":"2026-02-26T06:16:47.430267Z","end":"2026-02-26T06:16:48.869042Z","steps":["trace[1208819411] 'agreement among raft nodes before linearized reading' (duration: 1.412018592s)","trace[1208819411] 'range keys from in-memory index tree' (duration: 26.652381ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:48.869068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:16:47.430248Z","time spent":"1.438815965s","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:16:48.869235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.450414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:16:48.86927Z","caller":"traceutil/trace.go:171","msg":"trace[593833756] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"125.528895ms","start":"2026-02-26T06:16:48.743732Z","end":"2026-02-26T06:16:48.869261Z","steps":["trace[593833756] 'agreement among raft nodes before linearized reading' (duration: 98.6047ms)","trace[593833756] 'range keys from in-memory index tree' (duration: 26.827384ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:16:55.097599Z","caller":"traceutil/trace.go:171","msg":"trace[653985749] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"107.552349ms","start":"2026-02-26T06:16:54.990021Z","end":"2026-02-26T06:16:55.097574Z","steps":["trace[653985749] 'process raft request' (duration: 107.469298ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:55.097597Z","caller":"traceutil/trace.go:171","msg":"trace[548681461] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"107.789392ms","start":"2026-02-26T06:16:54.98978Z","end":"2026-02-26T06:16:55.097569Z","steps":["trace[548681461] 'process raft request' (duration: 107.261724ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:55.097547Z","caller":"traceutil/trace.go:171","msg":"trace[879873955] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:744; }","duration":"101.243618ms","start":"2026-02-26T06:16:54.996285Z","end":"2026-02-26T06:16:55.097528Z","steps":["trace[879873955] 'read index received' (duration: 11.322571ms)","trace[879873955] 'applied index is now lower than readState.Index' (duration: 89.919887ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:16:55.097803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.495332ms","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-26T06:16:55.097879Z","caller":"traceutil/trace.go:171","msg":"trace[393678310] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:719; }","duration":"101.592304ms","start":"2026-02-26T06:16:54.996269Z","end":"2026-02-26T06:16:55.097861Z","steps":["trace[393678310] 'agreement among raft nodes before linearized reading' (duration: 101.405211ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:59.394548Z","caller":"traceutil/trace.go:171","msg":"trace[1761004436] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"240.43489ms","start":"2026-02-26T06:16:59.154097Z","end":"2026-02-26T06:16:59.394532Z","steps":["trace[1761004436] 'process raft request' (duration: 240.369409ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:59.414139Z","caller":"traceutil/trace.go:171","msg":"trace[912193956] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"259.107307ms","start":"2026-02-26T06:16:59.155009Z","end":"2026-02-26T06:16:59.414116Z","steps":["trace[912193956] 'process raft request' (duration: 259.060446ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:16:59.414191Z","caller":"traceutil/trace.go:171","msg":"trace[1216948085] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"259.493833ms","start":"2026-02-26T06:16:59.154657Z","end":"2026-02-26T06:16:59.414151Z","steps":["trace[1216948085] 'process raft request' (duration: 259.281179ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:01.535244Z","caller":"traceutil/trace.go:171","msg":"trace[1907112503] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"104.16255ms","start":"2026-02-26T06:17:01.431045Z","end":"2026-02-26T06:17:01.535208Z","steps":["trace[1907112503] 'process raft request' (duration: 104.036257ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:01.537765Z","caller":"traceutil/trace.go:171","msg":"trace[692007188] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"106.595834ms","start":"2026-02-26T06:17:01.431144Z","end":"2026-02-26T06:17:01.53774Z","steps":["trace[692007188] 'process raft request' (duration: 106.482893ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:07.733961Z","caller":"traceutil/trace.go:171","msg":"trace[687627264] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"152.98895ms","start":"2026-02-26T06:17:07.580934Z","end":"2026-02-26T06:17:07.733923Z","steps":["trace[687627264] 'process raft request' (duration: 152.085356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:11.495361Z","caller":"traceutil/trace.go:171","msg":"trace[968308101] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"209.66074ms","start":"2026-02-26T06:17:11.285674Z","end":"2026-02-26T06:17:11.495335Z","steps":["trace[968308101] 'process raft request' (duration: 209.497028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:11.657149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.15703ms","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-26T06:17:11.657267Z","caller":"traceutil/trace.go:171","msg":"trace[803007174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:762; }","duration":"127.356042ms","start":"2026-02-26T06:17:11.529883Z","end":"2026-02-26T06:17:11.657239Z","steps":["trace[803007174] 'range keys from in-memory index tree' (duration: 126.892046ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:12.373551Z","caller":"traceutil/trace.go:171","msg":"trace[140738194] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"619.040157ms","start":"2026-02-26T06:17:11.754466Z","end":"2026-02-26T06:17:12.373506Z","steps":["trace[140738194] 'process raft request' (duration: 618.648261ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:12.373737Z","caller":"traceutil/trace.go:171","msg":"trace[332855328] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"496.426352ms","start":"2026-02-26T06:17:11.877283Z","end":"2026-02-26T06:17:12.373709Z","steps":["trace[332855328] 'read index received' (duration: 495.641931ms)","trace[332855328] 'applied index is now lower than readState.Index' (duration: 782.971µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:12.373951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.653656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:17:12.373999Z","caller":"traceutil/trace.go:171","msg":"trace[728794455] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:765; }","duration":"496.722357ms","start":"2026-02-26T06:17:11.87726Z","end":"2026-02-26T06:17:12.373982Z","steps":["trace[728794455] 'agreement among raft nodes before linearized reading' (duration: 496.591765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:12.37404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:11.87717Z","time spent":"496.861209ms","remote":"127.0.0.1:49434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:17:12.374158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:11.754447Z","time spent":"619.305181ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:14.260953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.35922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:14.261151Z","caller":"traceutil/trace.go:171","msg":"trace[504980162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"828.616545ms","start":"2026-02-26T06:17:13.432479Z","end":"2026-02-26T06:17:14.261096Z","steps":["trace[504980162] 'range keys from in-memory index tree' (duration: 828.204488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.261275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:13.432439Z","time spent":"828.810208ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:17:14.261046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296464088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:14.261474Z","caller":"traceutil/trace.go:171","msg":"trace[1574160471] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:765; }","duration":"1.296985506s","start":"2026-02-26T06:17:12.96447Z","end":"2026-02-26T06:17:14.261456Z","steps":["trace[1574160471] 'count revisions from in-memory index tree' (duration: 1.296341107s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.261514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:12.964438Z","time spent":"1.297066437s","remote":"127.0.0.1:49310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:17:14.261569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829375323s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:17:14.261628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148238078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:17:14.261688Z","caller":"traceutil/trace.go:171","msg":"trace[852142250] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"1.148352329s","start":"2026-02-26T06:17:13.11332Z","end":"2026-02-26T06:17:14.261672Z","steps":["trace[852142250] 'range keys from in-memory index tree' (duration: 1.147888513s)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:14.261693Z","caller":"traceutil/trace.go:171","msg":"trace[1496298630] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:765; }","duration":"1.829492885s","start":"2026-02-26T06:17:12.432176Z","end":"2026-02-26T06:17:14.261669Z","steps":["trace[1496298630] 'range keys from in-memory index tree' (duration: 1.829363733s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.261722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:13.113291Z","time spent":"1.14842203s","remote":"127.0.0.1:49346","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-26T06:17:14.261952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.316665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:17:14.261986Z","caller":"traceutil/trace.go:171","msg":"trace[1896146181] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:765; }","duration":"114.405066ms","start":"2026-02-26T06:17:14.14757Z","end":"2026-02-26T06:17:14.261976Z","steps":["trace[1896146181] 'count revisions from in-memory index tree' (duration: 114.186043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.262075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.759058ms","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-26T06:17:14.262293Z","caller":"traceutil/trace.go:171","msg":"trace[142842799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"761.993982ms","start":"2026-02-26T06:17:13.500275Z","end":"2026-02-26T06:17:14.262269Z","steps":["trace[142842799] 'range keys from in-memory index tree' (duration: 761.610456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.262318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.228673ms","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-26T06:17:14.26233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:13.500254Z","time spent":"762.067173ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T06:17:14.262358Z","caller":"traceutil/trace.go:171","msg":"trace[44499001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"576.266174ms","start":"2026-02-26T06:17:13.686079Z","end":"2026-02-26T06:17:14.262345Z","steps":["trace[44499001] 'range keys from in-memory index tree' (duration: 576.076752ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.262389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:13.686002Z","time spent":"576.379706ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:17:14.262419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.06613ms","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-26T06:17:14.262446Z","caller":"traceutil/trace.go:171","msg":"trace[1622010326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"557.147291ms","start":"2026-02-26T06:17:13.705291Z","end":"2026-02-26T06:17:14.262438Z","steps":["trace[1622010326] 'range keys from in-memory index tree' (duration: 556.925298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:14.262466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:13.70526Z","time spent":"557.201793ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:15.156067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.34568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:17:15.156627Z","caller":"traceutil/trace.go:171","msg":"trace[2003433198] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"889.039259ms","start":"2026-02-26T06:17:14.267563Z","end":"2026-02-26T06:17:15.156603Z","steps":["trace[2003433198] 'process raft request' (duration: 534.035081ms)","trace[2003433198] 'compare' (duration: 354.120976ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:15.15666Z","caller":"traceutil/trace.go:171","msg":"trace[1689753821] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"888.124986ms","start":"2026-02-26T06:17:14.268468Z","end":"2026-02-26T06:17:15.156593Z","steps":["trace[1689753821] 'process raft request' (duration: 887.790201ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:15.156694Z","caller":"traceutil/trace.go:171","msg":"trace[767372508] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"882.680548ms","start":"2026-02-26T06:17:14.273971Z","end":"2026-02-26T06:17:15.156651Z","steps":["trace[767372508] 'process raft request' (duration: 882.323113ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:15.156828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.267526Z","time spent":"889.169491ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:15.156901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.273959Z","time spent":"882.83218ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:15.157063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.268454Z","time spent":"888.312178ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:15.156572Z","caller":"traceutil/trace.go:171","msg":"trace[814673727] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"889.10144ms","start":"2026-02-26T06:17:14.267421Z","end":"2026-02-26T06:17:15.156522Z","steps":["trace[814673727] 'process raft request' (duration: 888.743725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:15.157341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.267407Z","time spent":"889.76113ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:15.222914Z","caller":"traceutil/trace.go:171","msg":"trace[80237482] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:797; }","duration":"843.58807ms","start":"2026-02-26T06:17:14.379309Z","end":"2026-02-26T06:17:15.222897Z","steps":["trace[80237482] 'read index received' (duration: 422.287683ms)","trace[80237482] 'applied index is now lower than readState.Index' (duration: 421.299087ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:15.222962Z","caller":"traceutil/trace.go:171","msg":"trace[1522736394] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"945.086401ms","start":"2026-02-26T06:17:14.277845Z","end":"2026-02-26T06:17:15.222932Z","steps":["trace[1522736394] 'process raft request' (duration: 944.943478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:15.223121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"843.804383ms","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-26T06:17:15.223164Z","caller":"traceutil/trace.go:171","msg":"trace[699554011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"843.886014ms","start":"2026-02-26T06:17:14.379268Z","end":"2026-02-26T06:17:15.223154Z","steps":["trace[699554011] 'agreement among raft nodes before linearized reading' (duration: 843.691441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:15.223201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.379249Z","time spent":"843.945294ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:15.223373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:14.277837Z","time spent":"945.182743ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:15.37627Z","caller":"traceutil/trace.go:171","msg":"trace[629759367] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"146.933552ms","start":"2026-02-26T06:17:15.229299Z","end":"2026-02-26T06:17:15.376232Z","steps":["trace[629759367] 'read index received' (duration: 77.688042ms)","trace[629759367] 'applied index is now lower than readState.Index' (duration: 69.24399ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:15.376353Z","caller":"traceutil/trace.go:171","msg":"trace[389280512] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"148.184809ms","start":"2026-02-26T06:17:15.228132Z","end":"2026-02-26T06:17:15.376317Z","steps":["trace[389280512] 'process raft request' (duration: 78.916699ms)","trace[389280512] 'compare' (duration: 69.033067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:15.376463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.127835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T06:17:15.376522Z","caller":"traceutil/trace.go:171","msg":"trace[1585567020] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:771; }","duration":"147.237336ms","start":"2026-02-26T06:17:15.229269Z","end":"2026-02-26T06:17:15.376506Z","steps":["trace[1585567020] 'agreement among raft nodes before linearized reading' (duration: 147.097994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.662661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154261928,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T06:17:17.783917Z","caller":"traceutil/trace.go:171","msg":"trace[1882189514] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"941.572442ms","start":"2026-02-26T06:17:16.842311Z","end":"2026-02-26T06:17:17.783883Z","steps":["trace[1882189514] 'process raft request' (duration: 941.285147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.784287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:16.842271Z","time spent":"941.825065ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:17.784423Z","caller":"traceutil/trace.go:171","msg":"trace[1978764101] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"621.532503ms","start":"2026-02-26T06:17:17.162279Z","end":"2026-02-26T06:17:17.783811Z","steps":["trace[1978764101] 'read index received' (duration: 621.201007ms)","trace[1978764101] 'applied index is now lower than readState.Index' (duration: 329.826µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:17.784779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.535488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:17.784887Z","caller":"traceutil/trace.go:171","msg":"trace[448772495] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"353.687079ms","start":"2026-02-26T06:17:17.431184Z","end":"2026-02-26T06:17:17.784871Z","steps":["trace[448772495] 'agreement among raft nodes before linearized reading' (duration: 353.514677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.784955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.16371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-26T06:17:17.784965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.404977ms","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-26T06:17:17.785047Z","caller":"traceutil/trace.go:171","msg":"trace[275129465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"622.329183ms","start":"2026-02-26T06:17:17.162698Z","end":"2026-02-26T06:17:17.785027Z","steps":["trace[275129465] 'agreement among raft nodes before linearized reading' (duration: 622.07105ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:17.785073Z","caller":"traceutil/trace.go:171","msg":"trace[863234126] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"402.579269ms","start":"2026-02-26T06:17:17.382466Z","end":"2026-02-26T06:17:17.785045Z","steps":["trace[863234126] 'agreement among raft nodes before linearized reading' (duration: 402.212304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.785039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.983809ms","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-26T06:17:17.785103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.162655Z","time spent":"622.438586ms","remote":"127.0.0.1:49346","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-26T06:17:17.785123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.382436Z","time spent":"402.678251ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-26T06:17:17.785162Z","caller":"traceutil/trace.go:171","msg":"trace[2139279521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"622.121312ms","start":"2026-02-26T06:17:17.163012Z","end":"2026-02-26T06:17:17.785133Z","steps":["trace[2139279521] 'agreement among raft nodes before linearized reading' (duration: 621.753246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.785005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.431156Z","time spent":"353.833222ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:17:17.785222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.162922Z","time spent":"622.290324ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:17.785402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.114575ms","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-26T06:17:17.78546Z","caller":"traceutil/trace.go:171","msg":"trace[1292089681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"623.223287ms","start":"2026-02-26T06:17:17.162223Z","end":"2026-02-26T06:17:17.785446Z","steps":["trace[1292089681] 'agreement among raft nodes before linearized reading' (duration: 621.775746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.785499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.162201Z","time spent":"623.289127ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:17:17.785569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.171426ms","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-26T06:17:17.785628Z","caller":"traceutil/trace.go:171","msg":"trace[710520721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"623.280927ms","start":"2026-02-26T06:17:17.162333Z","end":"2026-02-26T06:17:17.785614Z","steps":["trace[710520721] 'agreement among raft nodes before linearized reading' (duration: 622.297383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:17.785675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.162322Z","time spent":"623.343408ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:18.330864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.943601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:17:18.331159Z","caller":"traceutil/trace.go:171","msg":"trace[131867970] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"538.828669ms","start":"2026-02-26T06:17:17.792309Z","end":"2026-02-26T06:17:18.331138Z","steps":["trace[131867970] 'process raft request' (duration: 538.741027ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:18.331142Z","caller":"traceutil/trace.go:171","msg":"trace[1336847617] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"539.329716ms","start":"2026-02-26T06:17:17.791772Z","end":"2026-02-26T06:17:18.331102Z","steps":["trace[1336847617] 'process raft request' (duration: 188.029751ms)","trace[1336847617] 'compare' (duration: 350.793388ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:18.331245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.792297Z","time spent":"538.91059ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:18.331362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.791754Z","time spent":"539.491189ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:18.331528Z","caller":"traceutil/trace.go:171","msg":"trace[1568448986] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"539.675692ms","start":"2026-02-26T06:17:17.791806Z","end":"2026-02-26T06:17:18.331481Z","steps":["trace[1568448986] 'process raft request' (duration: 539.186114ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:18.331504Z","caller":"traceutil/trace.go:171","msg":"trace[115245134] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"538.280282ms","start":"2026-02-26T06:17:17.793186Z","end":"2026-02-26T06:17:18.331466Z","steps":["trace[115245134] 'process raft request' (duration: 537.904885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:18.331595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.791779Z","time spent":"539.784233ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:17:18.331682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.793177Z","time spent":"538.404613ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:18.331914Z","caller":"traceutil/trace.go:171","msg":"trace[714086615] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"538.635596ms","start":"2026-02-26T06:17:17.793249Z","end":"2026-02-26T06:17:18.331885Z","steps":["trace[714086615] 'process raft request' (duration: 538.125279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:18.332689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:17.793245Z","time spent":"538.742778ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:18.916223Z","caller":"traceutil/trace.go:171","msg":"trace[2039319593] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"106.675007ms","start":"2026-02-26T06:17:18.809518Z","end":"2026-02-26T06:17:18.916193Z","steps":["trace[2039319593] 'process raft request' (duration: 106.501574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:29.864618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.232699ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-26T06:17:38.643235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154262186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:17:39.144346Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594529431154262186,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T06:17:39.218819Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.110132769s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T06:17:39.219459Z","caller":"traceutil/trace.go:171","msg":"trace[2057025681] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"1.110836071s","start":"2026-02-26T06:17:38.108593Z","end":"2026-02-26T06:17:39.219429Z","steps":["trace[2057025681] 'process raft request' (duration: 1.110588736s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.219671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.108557Z","time spent":"1.110993394s","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:39.279247Z","caller":"traceutil/trace.go:171","msg":"trace[316902377] linearizableReadLoop","detail":"{readStateIndex:926; appliedIndex:924; }","duration":"1.136988876s","start":"2026-02-26T06:17:38.142222Z","end":"2026-02-26T06:17:39.27921Z","steps":["trace[316902377] 'read index received' (duration: 1.076931967s)","trace[316902377] 'applied index is now lower than readState.Index' (duration: 60.055549ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:39.279411Z","caller":"traceutil/trace.go:171","msg":"trace[1615453536] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"211.558946ms","start":"2026-02-26T06:17:39.067834Z","end":"2026-02-26T06:17:39.279393Z","steps":["trace[1615453536] 'process raft request' (duration: 211.142009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.279594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.470282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:39.279682Z","caller":"traceutil/trace.go:171","msg":"trace[2126436192] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:889; }","duration":"849.588814ms","start":"2026-02-26T06:17:38.430056Z","end":"2026-02-26T06:17:39.279645Z","steps":["trace[2126436192] 'agreement among raft nodes before linearized reading' (duration: 849.470702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.279732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.430036Z","time spent":"849.687906ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.018823ms","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-26T06:17:39.280141Z","caller":"traceutil/trace.go:171","msg":"trace[2072352306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"705.057674ms","start":"2026-02-26T06:17:38.575073Z","end":"2026-02-26T06:17:39.280131Z","steps":["trace[2072352306] 'agreement among raft nodes before linearized reading' (duration: 704.855661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.575039Z","time spent":"705.138206ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.605768ms","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-26T06:17:39.280246Z","caller":"traceutil/trace.go:171","msg":"trace[991904478] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"741.733729ms","start":"2026-02-26T06:17:38.538496Z","end":"2026-02-26T06:17:39.28023Z","steps":["trace[991904478] 'agreement among raft nodes before linearized reading' (duration: 741.513915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.538472Z","time spent":"741.801791ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.071608ms","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-26T06:17:39.280378Z","caller":"traceutil/trace.go:171","msg":"trace[124933789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"703.302622ms","start":"2026-02-26T06:17:38.577049Z","end":"2026-02-26T06:17:39.280352Z","steps":["trace[124933789] 'agreement among raft nodes before linearized reading' (duration: 702.834054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.577026Z","time spent":"703.396093ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.900463ms","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-26T06:17:39.280521Z","caller":"traceutil/trace.go:171","msg":"trace[1389870821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"708.975275ms","start":"2026-02-26T06:17:38.571537Z","end":"2026-02-26T06:17:39.280512Z","steps":["trace[1389870821] 'agreement among raft nodes before linearized reading' (duration: 708.879903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.571517Z","time spent":"709.029545ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.345031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:39.280664Z","caller":"traceutil/trace.go:171","msg":"trace[2052032756] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:889; }","duration":"683.517103ms","start":"2026-02-26T06:17:38.597122Z","end":"2026-02-26T06:17:39.280639Z","steps":["trace[2052032756] 'agreement among raft nodes before linearized reading' (duration: 683.310599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.321298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T06:17:39.280714Z","caller":"traceutil/trace.go:171","msg":"trace[723462415] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:889; }","duration":"695.368769ms","start":"2026-02-26T06:17:38.585335Z","end":"2026-02-26T06:17:39.280704Z","steps":["trace[723462415] 'agreement among raft nodes before linearized reading' (duration: 695.270637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.597099Z","time spent":"683.613725ms","remote":"127.0.0.1:49578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:17:39.280744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.585328Z","time spent":"695.40992ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T06:17:39.280757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.138514524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:17:39.280821Z","caller":"traceutil/trace.go:171","msg":"trace[958636948] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:889; }","duration":"1.138629435s","start":"2026-02-26T06:17:38.142177Z","end":"2026-02-26T06:17:39.280807Z","steps":["trace[958636948] 'agreement among raft nodes before linearized reading' (duration: 1.138503303s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:39.280863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:38.14216Z","time spent":"1.138694967s","remote":"127.0.0.1:49510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-26T06:17:40.048119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.038867ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:17:40.048223Z","caller":"traceutil/trace.go:171","msg":"trace[1404717258] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"759.590742ms","start":"2026-02-26T06:17:39.288612Z","end":"2026-02-26T06:17:40.048203Z","steps":["trace[1404717258] 'process raft request' (duration: 570.394242ms)","trace[1404717258] 'compare' (duration: 188.926036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:40.048267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.288593Z","time spent":"759.651674ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.087711Z","caller":"traceutil/trace.go:171","msg":"trace[123775440] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"798.900695ms","start":"2026-02-26T06:17:39.288783Z","end":"2026-02-26T06:17:40.087684Z","steps":["trace[123775440] 'process raft request' (duration: 798.789183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.087935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.288773Z","time spent":"799.032758ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.088057Z","caller":"traceutil/trace.go:171","msg":"trace[1007330245] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"798.818395ms","start":"2026-02-26T06:17:39.289208Z","end":"2026-02-26T06:17:40.088026Z","steps":["trace[1007330245] 'process raft request' (duration: 798.413657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.088223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.289193Z","time spent":"798.933257ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.088244Z","caller":"traceutil/trace.go:171","msg":"trace[356334898] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"799.530897ms","start":"2026-02-26T06:17:39.2887Z","end":"2026-02-26T06:17:40.088231Z","steps":["trace[356334898] 'process raft request' (duration: 798.699092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.088332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.288669Z","time spent":"799.610928ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.088825Z","caller":"traceutil/trace.go:171","msg":"trace[50132261] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"797.626412ms","start":"2026-02-26T06:17:39.291186Z","end":"2026-02-26T06:17:40.088812Z","steps":["trace[50132261] 'process raft request' (duration: 796.714366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.088887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.291175Z","time spent":"797.680773ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:17:40.091807Z","caller":"traceutil/trace.go:171","msg":"trace[312404252] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:926; }","duration":"660.511095ms","start":"2026-02-26T06:17:39.431272Z","end":"2026-02-26T06:17:40.091783Z","steps":["trace[312404252] 'read index received' (duration: 427.665043ms)","trace[312404252] 'applied index is now lower than readState.Index' (duration: 232.844362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:40.09191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.657638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:40.091944Z","caller":"traceutil/trace.go:171","msg":"trace[806819843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"660.739201ms","start":"2026-02-26T06:17:39.431194Z","end":"2026-02-26T06:17:40.091933Z","steps":["trace[806819843] 'agreement among raft nodes before linearized reading' (duration: 660.669129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:40.092004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:17:39.431164Z","time spent":"660.832212ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:17:46.203056Z","caller":"traceutil/trace.go:171","msg":"trace[267942135] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"107.409248ms","start":"2026-02-26T06:17:46.095615Z","end":"2026-02-26T06:17:46.203024Z","steps":["trace[267942135] 'read index received' (duration: 107.145043ms)","trace[267942135] 'applied index is now lower than readState.Index' (duration: 263.215µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:46.20313Z","caller":"traceutil/trace.go:171","msg":"trace[1181454302] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"122.885018ms","start":"2026-02-26T06:17:46.080215Z","end":"2026-02-26T06:17:46.2031Z","steps":["trace[1181454302] 'process raft request' (duration: 122.625704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:46.203303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.648992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T06:17:46.203418Z","caller":"traceutil/trace.go:171","msg":"trace[1351865794] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:906; }","duration":"107.824915ms","start":"2026-02-26T06:17:46.095565Z","end":"2026-02-26T06:17:46.20339Z","steps":["trace[1351865794] 'agreement among raft nodes before linearized reading' (duration: 107.595731ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:50.489289Z","caller":"traceutil/trace.go:171","msg":"trace[475204178] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"134.841215ms","start":"2026-02-26T06:17:50.354409Z","end":"2026-02-26T06:17:50.48925Z","steps":["trace[475204178] 'read index received' (duration: 61.904003ms)","trace[475204178] 'applied index is now lower than readState.Index' (duration: 72.935662ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:50.489367Z","caller":"traceutil/trace.go:171","msg":"trace[1597094852] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"171.620731ms","start":"2026-02-26T06:17:50.317683Z","end":"2026-02-26T06:17:50.489304Z","steps":["trace[1597094852] 'process raft request' (duration: 98.723829ms)","trace[1597094852] 'compare' (duration: 72.683498ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:50.489652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.223353ms","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-26T06:17:50.489708Z","caller":"traceutil/trace.go:171","msg":"trace[1430414819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:922; }","duration":"135.338694ms","start":"2026-02-26T06:17:50.354355Z","end":"2026-02-26T06:17:50.489693Z","steps":["trace[1430414819] 'agreement among raft nodes before linearized reading' (duration: 135.045579ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:52.374949Z","caller":"traceutil/trace.go:171","msg":"trace[1841463118] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"142.621946ms","start":"2026-02-26T06:17:52.23231Z","end":"2026-02-26T06:17:52.374932Z","steps":["trace[1841463118] 'process raft request' (duration: 142.503274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.616237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.258177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:17:52.616471Z","caller":"traceutil/trace.go:171","msg":"trace[1421083284] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:964; }","duration":"184.132449ms","start":"2026-02-26T06:17:52.432318Z","end":"2026-02-26T06:17:52.61645Z","steps":["trace[1421083284] 'read index received' (duration: 8.28381ms)","trace[1421083284] 'applied index is now lower than readState.Index' (duration: 175.847149ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:17:52.616598Z","caller":"traceutil/trace.go:171","msg":"trace[1708079752] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"234.474952ms","start":"2026-02-26T06:17:52.382111Z","end":"2026-02-26T06:17:52.616586Z","steps":["trace[1708079752] 'process raft request' (duration: 234.256258ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:17:52.616675Z","caller":"traceutil/trace.go:171","msg":"trace[1939488845] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"235.181054ms","start":"2026-02-26T06:17:52.381465Z","end":"2026-02-26T06:17:52.616646Z","steps":["trace[1939488845] 'process raft request' (duration: 59.107212ms)","trace[1939488845] 'compare' (duration: 175.001772ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:17:52.616955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.119704ms","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-26T06:17:52.616985Z","caller":"traceutil/trace.go:171","msg":"trace[736752541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:926; }","duration":"122.212456ms","start":"2026-02-26T06:17:52.494763Z","end":"2026-02-26T06:17:52.616975Z","steps":["trace[736752541] 'agreement among raft nodes before linearized reading' (duration: 122.075083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.617077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.75677ms","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-26T06:17:52.617099Z","caller":"traceutil/trace.go:171","msg":"trace[619331842] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:926; }","duration":"184.804041ms","start":"2026-02-26T06:17:52.432286Z","end":"2026-02-26T06:17:52.61709Z","steps":["trace[619331842] 'agreement among raft nodes before linearized reading' (duration: 184.76816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:52.617561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.446976ms","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-26T06:17:52.618094Z","caller":"traceutil/trace.go:171","msg":"trace[652709897] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:924; }","duration":"185.779529ms","start":"2026-02-26T06:17:52.432298Z","end":"2026-02-26T06:17:52.618077Z","steps":["trace[652709897] 'range keys from in-memory index tree' (duration: 183.428886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:17:55.613884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.382644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:17:55.613978Z","caller":"traceutil/trace.go:171","msg":"trace[1306610308] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"184.598628ms","start":"2026-02-26T06:17:55.429358Z","end":"2026-02-26T06:17:55.613957Z","steps":["trace[1306610308] 'range keys from in-memory index tree' (duration: 184.288091ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:02.069842Z","caller":"traceutil/trace.go:171","msg":"trace[155229612] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"255.500354ms","start":"2026-02-26T06:18:01.814309Z","end":"2026-02-26T06:18:02.06981Z","steps":["trace[155229612] 'process raft request' (duration: 255.288899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:02.663728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.08608ms","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-26T06:18:02.663892Z","caller":"traceutil/trace.go:171","msg":"trace[1710466204] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1008; }","duration":"231.286394ms","start":"2026-02-26T06:18:02.432582Z","end":"2026-02-26T06:18:02.663869Z","steps":["trace[1710466204] 'range keys from in-memory index tree' (duration: 231.06055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:02.664507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.380767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:02.664635Z","caller":"traceutil/trace.go:171","msg":"trace[1076486830] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"239.777348ms","start":"2026-02-26T06:18:02.424838Z","end":"2026-02-26T06:18:02.664616Z","steps":["trace[1076486830] 'read index received' (duration: 52.271µs)","trace[1076486830] 'applied index is now lower than readState.Index' (duration: 239.723667ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:02.664735Z","caller":"traceutil/trace.go:171","msg":"trace[792106941] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"581.854039ms","start":"2026-02-26T06:18:02.082866Z","end":"2026-02-26T06:18:02.66472Z","steps":["trace[792106941] 'process raft request' (duration: 228.162587ms)","trace[792106941] 'compare' (duration: 353.223634ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:02.664801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:02.082832Z","time spent":"581.935661ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:02.664848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.813749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:18:02.664931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.073304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T06:18:02.664962Z","caller":"traceutil/trace.go:171","msg":"trace[2072144497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"233.958533ms","start":"2026-02-26T06:18:02.430977Z","end":"2026-02-26T06:18:02.664935Z","steps":["trace[2072144497] 'agreement among raft nodes before linearized reading' (duration: 233.784839ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:02.665018Z","caller":"traceutil/trace.go:171","msg":"trace[825587803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"240.223676ms","start":"2026-02-26T06:18:02.42477Z","end":"2026-02-26T06:18:02.664994Z","steps":["trace[825587803] 'agreement among raft nodes before linearized reading' (duration: 239.973111ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:03.768564Z","caller":"traceutil/trace.go:171","msg":"trace[897737954] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"213.561463ms","start":"2026-02-26T06:18:03.554972Z","end":"2026-02-26T06:18:03.768533Z","steps":["trace[897737954] 'read index received' (duration: 213.502012ms)","trace[897737954] 'applied index is now lower than readState.Index' (duration: 57.991µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:03.768679Z","caller":"traceutil/trace.go:171","msg":"trace[1630343036] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"302.452114ms","start":"2026-02-26T06:18:03.466178Z","end":"2026-02-26T06:18:03.76863Z","steps":["trace[1630343036] 'process raft request' (duration: 302.2188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:03.768845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.846187ms","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:529"} {"level":"info","ts":"2026-02-26T06:18:03.768921Z","caller":"traceutil/trace.go:171","msg":"trace[1843447215] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"213.98828ms","start":"2026-02-26T06:18:03.554913Z","end":"2026-02-26T06:18:03.768901Z","steps":["trace[1843447215] 'agreement among raft nodes before linearized reading' (duration: 213.738636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:03.768999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:03.466134Z","time spent":"302.716659ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:04.759794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.118219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:04.759917Z","caller":"traceutil/trace.go:171","msg":"trace[426317549] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"567.734494ms","start":"2026-02-26T06:18:04.192163Z","end":"2026-02-26T06:18:04.759897Z","steps":["trace[426317549] 'read index received' (duration: 84.36415ms)","trace[426317549] 'applied index is now lower than readState.Index' (duration: 483.368664ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:04.760737Z","caller":"traceutil/trace.go:171","msg":"trace[1719412114] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"985.887695ms","start":"2026-02-26T06:18:03.774806Z","end":"2026-02-26T06:18:04.760694Z","steps":["trace[1719412114] 'process raft request' (duration: 501.748128ms)","trace[1719412114] 'compare' (duration: 482.402856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:04.76087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.685532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-26T06:18:04.760954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:03.774777Z","time spent":"986.050559ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:04.76097Z","caller":"traceutil/trace.go:171","msg":"trace[1582685082] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:1018; }","duration":"568.858786ms","start":"2026-02-26T06:18:04.19209Z","end":"2026-02-26T06:18:04.760949Z","steps":["trace[1582685082] 'agreement among raft nodes before linearized reading' (duration: 568.486258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:04.761044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.192037Z","time spent":"568.996938ms","remote":"127.0.0.1:49128","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-26T06:18:04.761535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.237096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:04.761596Z","caller":"traceutil/trace.go:171","msg":"trace[844187865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"331.378149ms","start":"2026-02-26T06:18:04.430202Z","end":"2026-02-26T06:18:04.76158Z","steps":["trace[844187865] 'agreement among raft nodes before linearized reading' (duration: 329.971263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:04.761632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.430176Z","time spent":"331.44662ms","remote":"127.0.0.1:49098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T06:18:05.01796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.320517ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-26T06:18:05.01821Z","caller":"traceutil/trace.go:171","msg":"trace[1081905512] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"252.263603ms","start":"2026-02-26T06:18:04.765916Z","end":"2026-02-26T06:18:05.01818Z","steps":["trace[1081905512] 'read index received' (duration: 123.633641ms)","trace[1081905512] 'applied index is now lower than readState.Index' (duration: 128.627022ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:05.018571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.621621ms","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:529"} {"level":"info","ts":"2026-02-26T06:18:05.018661Z","caller":"traceutil/trace.go:171","msg":"trace[1334834257] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1018; }","duration":"252.767573ms","start":"2026-02-26T06:18:04.765871Z","end":"2026-02-26T06:18:05.018639Z","steps":["trace[1334834257] 'agreement among raft nodes before linearized reading' (duration: 252.440857ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.190638Z","caller":"traceutil/trace.go:171","msg":"trace[305712279] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"424.510487ms","start":"2026-02-26T06:18:04.766094Z","end":"2026-02-26T06:18:05.190604Z","steps":["trace[305712279] 'process raft request' (duration: 415.036655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.190889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.766071Z","time spent":"424.689401ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:05.191072Z","caller":"traceutil/trace.go:171","msg":"trace[662225817] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"424.149371ms","start":"2026-02-26T06:18:04.766889Z","end":"2026-02-26T06:18:05.191039Z","steps":["trace[662225817] 'process raft request' (duration: 423.600641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.191295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.766872Z","time spent":"424.289683ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:05.191321Z","caller":"traceutil/trace.go:171","msg":"trace[1311043240] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"424.223002ms","start":"2026-02-26T06:18:04.767082Z","end":"2026-02-26T06:18:05.191305Z","steps":["trace[1311043240] 'process raft request' (duration: 423.774634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.191395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.767065Z","time spent":"424.293573ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:05.191088Z","caller":"traceutil/trace.go:171","msg":"trace[1234050431] linearizableReadLoop","detail":"{readStateIndex:1066; appliedIndex:1062; }","duration":"172.801144ms","start":"2026-02-26T06:18:05.018258Z","end":"2026-02-26T06:18:05.191059Z","steps":["trace[1234050431] 'read index received' (duration: 162.818492ms)","trace[1234050431] 'applied index is now lower than readState.Index' (duration: 9.981542ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:05.191733Z","caller":"traceutil/trace.go:171","msg":"trace[200317535] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"424.608749ms","start":"2026-02-26T06:18:04.767109Z","end":"2026-02-26T06:18:05.191717Z","steps":["trace[200317535] 'process raft request' (duration: 423.874246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.191801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.699629ms","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-26T06:18:05.19187Z","caller":"traceutil/trace.go:171","msg":"trace[1196447990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1022; }","duration":"425.827821ms","start":"2026-02-26T06:18:04.766029Z","end":"2026-02-26T06:18:05.191857Z","steps":["trace[1196447990] 'agreement among raft nodes before linearized reading' (duration: 425.120509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:05.191911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.766Z","time spent":"425.902292ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:05.19181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:04.767076Z","time spent":"424.69316ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:05.203003Z","caller":"traceutil/trace.go:171","msg":"trace[756784787] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"178.416924ms","start":"2026-02-26T06:18:05.024574Z","end":"2026-02-26T06:18:05.202991Z","steps":["trace[756784787] 'process raft request' (duration: 178.367023ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:05.203049Z","caller":"traceutil/trace.go:171","msg":"trace[1791474068] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"183.246693ms","start":"2026-02-26T06:18:05.019786Z","end":"2026-02-26T06:18:05.203033Z","steps":["trace[1791474068] 'process raft request' (duration: 183.08587ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:07.395879Z","caller":"traceutil/trace.go:171","msg":"trace[1064349648] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"195.751989ms","start":"2026-02-26T06:18:07.200093Z","end":"2026-02-26T06:18:07.395845Z","steps":["trace[1064349648] 'process raft request' (duration: 195.468864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:07.887701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.965255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:07.888053Z","caller":"traceutil/trace.go:171","msg":"trace[1899092554] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1070; }","duration":"612.344813ms","start":"2026-02-26T06:18:07.275686Z","end":"2026-02-26T06:18:07.88803Z","steps":["trace[1899092554] 'read index received' (duration: 119.960724ms)","trace[1899092554] 'applied index is now lower than readState.Index' (duration: 492.382709ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:07.888088Z","caller":"traceutil/trace.go:171","msg":"trace[2042509099] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"687.894673ms","start":"2026-02-26T06:18:07.200153Z","end":"2026-02-26T06:18:07.888048Z","steps":["trace[2042509099] 'process raft request' (duration: 304.46097ms)","trace[2042509099] 'compare' (duration: 382.798212ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:07.888157Z","caller":"traceutil/trace.go:171","msg":"trace[1787082701] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"687.927194ms","start":"2026-02-26T06:18:07.200175Z","end":"2026-02-26T06:18:07.888102Z","steps":["trace[1787082701] 'process raft request' (duration: 687.69576ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:07.888188Z","caller":"traceutil/trace.go:171","msg":"trace[645456786] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"687.056969ms","start":"2026-02-26T06:18:07.201084Z","end":"2026-02-26T06:18:07.888141Z","steps":["trace[645456786] 'process raft request' (duration: 686.843105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:07.888501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.818702ms","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-26T06:18:07.888539Z","caller":"traceutil/trace.go:171","msg":"trace[2117817015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1030; }","duration":"612.897302ms","start":"2026-02-26T06:18:07.275631Z","end":"2026-02-26T06:18:07.888528Z","steps":["trace[2117817015] 'agreement among raft nodes before linearized reading' (duration: 612.513426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:07.888536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.200156Z","time spent":"688.085436ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:07.888571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.201075Z","time spent":"687.210771ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:07.888582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.275613Z","time spent":"612.958184ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T06:18:07.888495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.200145Z","time spent":"688.031705ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:07.88864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.654368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-26T06:18:07.888816Z","caller":"traceutil/trace.go:171","msg":"trace[1600412320] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1030; }","duration":"548.828371ms","start":"2026-02-26T06:18:07.339944Z","end":"2026-02-26T06:18:07.888772Z","steps":["trace[1600412320] 'agreement among raft nodes before linearized reading' (duration: 548.313342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:07.888782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.32537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T06:18:07.888868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.33987Z","time spent":"548.986575ms","remote":"127.0.0.1:49196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":645,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-26T06:18:07.888894Z","caller":"traceutil/trace.go:171","msg":"trace[1923342310] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1030; }","duration":"458.541074ms","start":"2026-02-26T06:18:07.43033Z","end":"2026-02-26T06:18:07.888871Z","steps":["trace[1923342310] 'agreement among raft nodes before linearized reading' (duration: 458.293699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:07.890035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:07.430305Z","time spent":"459.712815ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:18:08.039167Z","caller":"traceutil/trace.go:171","msg":"trace[755092060] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"142.648226ms","start":"2026-02-26T06:18:07.896485Z","end":"2026-02-26T06:18:08.039133Z","steps":["trace[755092060] 'process raft request' (duration: 142.183538ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:18:08.051539Z","caller":"traceutil/trace.go:171","msg":"trace[1857707342] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"154.726196ms","start":"2026-02-26T06:18:07.896782Z","end":"2026-02-26T06:18:08.051508Z","steps":["trace[1857707342] 'read index received' (duration: 141.896023ms)","trace[1857707342] 'applied index is now lower than readState.Index' (duration: 12.828583ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:08.051872Z","caller":"traceutil/trace.go:171","msg":"trace[591132164] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"155.104492ms","start":"2026-02-26T06:18:07.896705Z","end":"2026-02-26T06:18:08.05181Z","steps":["trace[591132164] 'process raft request' (duration: 154.632694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:08.052115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.142873ms","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:529"} {"level":"info","ts":"2026-02-26T06:18:08.052172Z","caller":"traceutil/trace.go:171","msg":"trace[304140802] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1032; }","duration":"155.415438ms","start":"2026-02-26T06:18:07.896742Z","end":"2026-02-26T06:18:08.052158Z","steps":["trace[304140802] 'agreement among raft nodes before linearized reading' (duration: 154.903829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:08.775008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.53486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:18:08.775709Z","caller":"traceutil/trace.go:171","msg":"trace[185259261] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"624.983072ms","start":"2026-02-26T06:18:08.150697Z","end":"2026-02-26T06:18:08.775681Z","steps":["trace[185259261] 'read index received' (duration: 72.475324ms)","trace[185259261] 'applied index is now lower than readState.Index' (duration: 552.505748ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:18:08.775714Z","caller":"traceutil/trace.go:171","msg":"trace[1605057848] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"669.612181ms","start":"2026-02-26T06:18:08.106067Z","end":"2026-02-26T06:18:08.775679Z","steps":["trace[1605057848] 'process raft request' (duration: 117.154434ms)","trace[1605057848] 'compare' (duration: 551.304956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:08.776687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:08.106037Z","time spent":"670.196581ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:08.776728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.039151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-02-26T06:18:08.777047Z","caller":"traceutil/trace.go:171","msg":"trace[24047924] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1034; }","duration":"626.403128ms","start":"2026-02-26T06:18:08.150628Z","end":"2026-02-26T06:18:08.777031Z","steps":["trace[24047924] 'agreement among raft nodes before linearized reading' (duration: 625.493481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:08.777268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:08.150602Z","time spent":"626.648032ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-26T06:18:09.613705Z","caller":"traceutil/trace.go:171","msg":"trace[1566824267] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"830.639811ms","start":"2026-02-26T06:18:08.78304Z","end":"2026-02-26T06:18:09.613679Z","steps":["trace[1566824267] 'process raft request' (duration: 830.430327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:09.613899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:08.78297Z","time spent":"830.816575ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:18:09.613934Z","caller":"traceutil/trace.go:171","msg":"trace[1057439474] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"830.821125ms","start":"2026-02-26T06:18:08.783074Z","end":"2026-02-26T06:18:09.613895Z","steps":["trace[1057439474] 'process raft request' (duration: 830.565369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:09.614141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:08.783036Z","time spent":"830.998948ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:18:09.7593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.577192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-26T06:18:09.759407Z","caller":"traceutil/trace.go:171","msg":"trace[1803819817] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1036; }","duration":"358.748386ms","start":"2026-02-26T06:18:09.400645Z","end":"2026-02-26T06:18:09.759393Z","steps":["trace[1803819817] 'agreement among raft nodes before linearized reading' (duration: 358.47321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:09.759468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:09.400594Z","time spent":"358.851438ms","remote":"127.0.0.1:49346","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-26T06:18:09.759084Z","caller":"traceutil/trace.go:171","msg":"trace[279637988] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1078; }","duration":"358.319398ms","start":"2026-02-26T06:18:09.400708Z","end":"2026-02-26T06:18:09.759027Z","steps":["trace[279637988] 'read index received' (duration: 212.674177ms)","trace[279637988] 'applied index is now lower than readState.Index' (duration: 145.64335ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:18:09.760026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.564478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:18:09.760096Z","caller":"traceutil/trace.go:171","msg":"trace[1518087966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"328.729651ms","start":"2026-02-26T06:18:09.431342Z","end":"2026-02-26T06:18:09.760072Z","steps":["trace[1518087966] 'agreement among raft nodes before linearized reading' (duration: 328.550118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:18:09.760141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:18:09.4313Z","time spent":"328.832553ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T06:18:56.602339Z","caller":"traceutil/trace.go:171","msg":"trace[59477754] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"167.910663ms","start":"2026-02-26T06:18:56.434397Z","end":"2026-02-26T06:18:56.602307Z","steps":["trace[59477754] 'process raft request' (duration: 121.410614ms)","trace[59477754] 'compare' (duration: 46.244514ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:19:32.558455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.838138ms","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-26T06:19:32.558608Z","caller":"traceutil/trace.go:171","msg":"trace[409712972] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1416; }","duration":"127.010601ms","start":"2026-02-26T06:19:32.431576Z","end":"2026-02-26T06:19:32.558586Z","steps":["trace[409712972] 'range keys from in-memory index tree' (duration: 126.816258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:19:32.558643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.351944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T06:19:32.558903Z","caller":"traceutil/trace.go:171","msg":"trace[1935540673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1416; }","duration":"128.528946ms","start":"2026-02-26T06:19:32.430211Z","end":"2026-02-26T06:19:32.55874Z","steps":["trace[1935540673] 'range keys from in-memory index tree' (duration: 128.253523ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:20:01.262962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.481565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:20:01.263094Z","caller":"traceutil/trace.go:171","msg":"trace[376443548] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1613; }","duration":"316.683726ms","start":"2026-02-26T06:20:00.946392Z","end":"2026-02-26T06:20:01.263076Z","steps":["trace[376443548] 'read index received' (duration: 147.811133ms)","trace[376443548] 'applied index is now lower than readState.Index' (duration: 168.871283ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:20:01.263295Z","caller":"traceutil/trace.go:171","msg":"trace[984358273] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"388.319077ms","start":"2026-02-26T06:20:00.874937Z","end":"2026-02-26T06:20:01.263256Z","steps":["trace[984358273] 'process raft request' (duration: 219.398813ms)","trace[984358273] 'compare' (duration: 168.316852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:20:01.263673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:20:00.874921Z","time spent":"388.464079ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T06:20:01.263652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.984645ms","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-26T06:20:01.263749Z","caller":"traceutil/trace.go:171","msg":"trace[1721283602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1547; }","duration":"215.114496ms","start":"2026-02-26T06:20:01.048611Z","end":"2026-02-26T06:20:01.263725Z","steps":["trace[1721283602] 'agreement among raft nodes before linearized reading' (duration: 214.668589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:20:01.264009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.53283ms","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-26T06:20:01.264134Z","caller":"traceutil/trace.go:171","msg":"trace[1279131316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1547; }","duration":"317.739953ms","start":"2026-02-26T06:20:00.946366Z","end":"2026-02-26T06:20:01.264106Z","steps":["trace[1279131316] 'agreement among raft nodes before linearized reading' (duration: 316.809877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:20:01.264189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:20:00.946355Z","time spent":"317.822154ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T06:20:01.532849Z","caller":"traceutil/trace.go:171","msg":"trace[2106058431] transaction","detail":"{read_only:false; response_revision:1549; number_of_response:1; }","duration":"263.866004ms","start":"2026-02-26T06:20:01.268951Z","end":"2026-02-26T06:20:01.532817Z","steps":["trace[2106058431] 'process raft request' (duration: 260.295222ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:20:10.040237Z","caller":"traceutil/trace.go:171","msg":"trace[1634054724] transaction","detail":"{read_only:false; response_revision:1587; number_of_response:1; }","duration":"122.306012ms","start":"2026-02-26T06:20:09.917893Z","end":"2026-02-26T06:20:10.040199Z","steps":["trace[1634054724] 'process raft request' (duration: 57.39096ms)","trace[1634054724] 'compare' (duration: 64.762ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:20:10.605138Z","caller":"traceutil/trace.go:171","msg":"trace[1371820681] linearizableReadLoop","detail":"{readStateIndex:1659; appliedIndex:1658; }","duration":"103.625784ms","start":"2026-02-26T06:20:10.501479Z","end":"2026-02-26T06:20:10.605105Z","steps":["trace[1371820681] 'read index received' (duration: 43.999944ms)","trace[1371820681] 'applied index is now lower than readState.Index' (duration: 59.62447ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:20:10.605258Z","caller":"traceutil/trace.go:171","msg":"trace[1550678903] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"108.251095ms","start":"2026-02-26T06:20:10.496962Z","end":"2026-02-26T06:20:10.605213Z","steps":["trace[1550678903] 'process raft request' (duration: 48.557684ms)","trace[1550678903] 'compare' (duration: 59.434577ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:20:10.605457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.892289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-26T06:20:10.605552Z","caller":"traceutil/trace.go:171","msg":"trace[1070405452] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1590; }","duration":"104.067031ms","start":"2026-02-26T06:20:10.501465Z","end":"2026-02-26T06:20:10.605531Z","steps":["trace[1070405452] 'agreement among raft nodes before linearized reading' (duration: 103.751076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:21:34.458542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.773197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T06:21:34.458986Z","caller":"traceutil/trace.go:171","msg":"trace[915872983] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"200.411537ms","start":"2026-02-26T06:21:34.25855Z","end":"2026-02-26T06:21:34.458961Z","steps":["trace[915872983] 'process raft request' (duration: 25.1114ms)","trace[915872983] 'compare' (duration: 174.574773ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:21:35.24904Z","caller":"traceutil/trace.go:171","msg":"trace[1984898822] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"129.107567ms","start":"2026-02-26T06:21:35.119823Z","end":"2026-02-26T06:21:35.248931Z","steps":["trace[1984898822] 'process raft request' (duration: 128.761921ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:44.134154Z","caller":"traceutil/trace.go:171","msg":"trace[1069246147] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"336.014191ms","start":"2026-02-26T06:21:43.798093Z","end":"2026-02-26T06:21:44.134107Z","steps":["trace[1069246147] 'process raft request' (duration: 256.3009ms)","trace[1069246147] 'compare' (duration: 79.210652ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:21:44.134363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.927286ms","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-26T06:21:44.134468Z","caller":"traceutil/trace.go:171","msg":"trace[164449199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2050; }","duration":"318.096499ms","start":"2026-02-26T06:21:43.816357Z","end":"2026-02-26T06:21:44.134454Z","steps":["trace[164449199] 'agreement among raft nodes before linearized reading' (duration: 317.854094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:21:44.13452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:21:43.816336Z","time spent":"318.174301ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T06:21:44.134638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:21:43.798071Z","time spent":"336.201273ms","remote":"127.0.0.1:49266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6902,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T06:21:44.134179Z","caller":"traceutil/trace.go:171","msg":"trace[609491721] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"317.388966ms","start":"2026-02-26T06:21:43.816407Z","end":"2026-02-26T06:21:44.133796Z","steps":["trace[609491721] 'read index received' (duration: 237.95291ms)","trace[609491721] 'applied index is now lower than readState.Index' (duration: 79.434126ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:21:44.135055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.741552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T06:21:44.135092Z","caller":"traceutil/trace.go:171","msg":"trace[822123528] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2050; }","duration":"308.791492ms","start":"2026-02-26T06:21:43.826288Z","end":"2026-02-26T06:21:44.13508Z","steps":["trace[822123528] 'agreement among raft nodes before linearized reading' (duration: 308.695331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:21:44.135119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T06:21:43.826278Z","time spent":"308.834453ms","remote":"127.0.0.1:49346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T06:21:44.135464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.69678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:912"} {"level":"info","ts":"2026-02-26T06:21:44.135524Z","caller":"traceutil/trace.go:171","msg":"trace[1086093675] range","detail":"{range_begin:/registry/clusterroles/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:2050; }","duration":"294.768501ms","start":"2026-02-26T06:21:43.840739Z","end":"2026-02-26T06:21:44.135508Z","steps":["trace[1086093675] 'agreement among raft nodes before linearized reading' (duration: 294.636999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T06:21:44.135791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.907224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-02-26T06:21:44.135918Z","caller":"traceutil/trace.go:171","msg":"trace[639076674] range","detail":"{range_begin:/registry/clusterrolebindings/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:2050; }","duration":"275.079996ms","start":"2026-02-26T06:21:43.86081Z","end":"2026-02-26T06:21:44.13589Z","steps":["trace[639076674] 'agreement among raft nodes before linearized reading' (duration: 274.68175ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:44.471878Z","caller":"traceutil/trace.go:171","msg":"trace[193681497] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"103.65972ms","start":"2026-02-26T06:21:44.368197Z","end":"2026-02-26T06:21:44.471857Z","steps":["trace[193681497] 'read index received' (duration: 35.542388ms)","trace[193681497] 'applied index is now lower than readState.Index' (duration: 68.116222ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T06:21:44.471993Z","caller":"traceutil/trace.go:171","msg":"trace[2145656655] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2057; }","duration":"104.402453ms","start":"2026-02-26T06:21:44.367501Z","end":"2026-02-26T06:21:44.471904Z","steps":["trace[2145656655] 'process raft request' (duration: 36.266911ms)","trace[2145656655] 'compare' (duration: 67.98729ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T06:21:44.472169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.942095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-26T06:21:44.472219Z","caller":"traceutil/trace.go:171","msg":"trace[1468699630] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2057; }","duration":"104.069647ms","start":"2026-02-26T06:21:44.368138Z","end":"2026-02-26T06:21:44.472208Z","steps":["trace[1468699630] 'agreement among raft nodes before linearized reading' (duration: 103.808323ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T06:21:44.612154Z","caller":"traceutil/trace.go:171","msg":"trace[175304500] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"121.887488ms","start":"2026-02-26T06:21:44.490196Z","end":"2026-02-26T06:21:44.612083Z","steps":["trace[175304500] 'process raft request' (duration: 88.854256ms)","trace[175304500] 'compare' (duration: 32.775818ms)"],"step_count":2}