{"level":"warn","ts":"2026-02-24T04:31:46.576534Z","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-24T04:31:46.576619Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-24T04:31:46.576689Z","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-24T04:31:46.576704Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-24T04:31:46.57672Z","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-24T04:31:46.57731Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-24T04:31:46.577387Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-24T04:31:46.581972Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.380809ms"} {"level":"info","ts":"2026-02-24T04:31:46.591884Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-24T04:31:46.591994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-24T04:31:46.592029Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-24T04:31:46.592055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-24T04:31:46.592069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-24T04:31:46.592267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-24T04:31:46.599906Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-24T04:31:46.601501Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-24T04:31:46.605Z","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-24T04:31:46.608248Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-24T04:31:46.60837Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-24T04:31:46.608532Z","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-24T04:31:46.608603Z","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-24T04:31:46.608617Z","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-24T04:31:46.611013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-24T04:31:46.611284Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-24T04:31:46.612829Z","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-24T04:31:46.613138Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-24T04:31:46.613209Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-24T04:31:46.613371Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-24T04:31:46.613475Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-24T04:31:46.993345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-24T04:31:46.99353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-24T04:31:46.993549Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-24T04:31:46.993564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-24T04:31:46.993571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-24T04:31:46.993582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-24T04:31:46.99376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-24T04:31:46.99501Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-24T04:31:46.995018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:31:46.995057Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:31:46.995153Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-24T04:31:46.996574Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:31:46.996689Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:31:46.996737Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-24T04:31:46.998053Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-24T04:31:46.999351Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-24T04:31:46.999639Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-24T04:31:47.007546Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"warn","ts":"2026-02-24T04:32:16.853714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.369478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T04:32:16.853779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.604963ms","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-24T04:32:16.853827Z","caller":"traceutil/trace.go:171","msg":"trace[1136619593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"132.590424ms","start":"2026-02-24T04:32:16.721216Z","end":"2026-02-24T04:32:16.853807Z","steps":["trace[1136619593] 'range keys from in-memory index tree' (duration: 132.287206ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:16.853877Z","caller":"traceutil/trace.go:171","msg":"trace[362510771] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:469; }","duration":"155.768907ms","start":"2026-02-24T04:32:16.698084Z","end":"2026-02-24T04:32:16.853853Z","steps":["trace[362510771] 'range keys from in-memory index tree' (duration: 155.444579ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:21.205157Z","caller":"traceutil/trace.go:171","msg":"trace[290952899] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"325.646453ms","start":"2026-02-24T04:32:20.879488Z","end":"2026-02-24T04:32:21.205134Z","steps":["trace[290952899] 'process raft request' (duration: 325.506449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:21.205562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:20.87947Z","time spent":"325.745505ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:32:21.63818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.912604ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:32:21.638262Z","caller":"traceutil/trace.go:171","msg":"trace[895752469] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"660.953761ms","start":"2026-02-24T04:32:20.977295Z","end":"2026-02-24T04:32:21.638249Z","steps":["trace[895752469] 'read index received' (duration: 227.99461ms)","trace[895752469] 'applied index is now lower than readState.Index' (duration: 432.958241ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:21.638328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.031433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:21.638345Z","caller":"traceutil/trace.go:171","msg":"trace[255909358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"661.089055ms","start":"2026-02-24T04:32:20.977249Z","end":"2026-02-24T04:32:21.638338Z","steps":["trace[255909358] 'agreement among raft nodes before linearized reading' (duration: 661.037103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:21.638374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:20.977231Z","time spent":"661.135226ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:32:21.638838Z","caller":"traceutil/trace.go:171","msg":"trace[6569159] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"757.701828ms","start":"2026-02-24T04:32:20.881127Z","end":"2026-02-24T04:32:21.638829Z","steps":["trace[6569159] 'process raft request' (duration: 618.95977ms)","trace[6569159] 'compare' (duration: 137.77541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:21.63888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:20.881117Z","time spent":"757.741099ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:32:23.95118Z","caller":"traceutil/trace.go:171","msg":"trace[934747543] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"113.163846ms","start":"2026-02-24T04:32:23.837998Z","end":"2026-02-24T04:32:23.951162Z","steps":["trace[934747543] 'read index received' (duration: 112.9772ms)","trace[934747543] 'applied index is now lower than readState.Index' (duration: 185.996µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:23.951365Z","caller":"traceutil/trace.go:171","msg":"trace[1085079481] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"303.442497ms","start":"2026-02-24T04:32:23.647777Z","end":"2026-02-24T04:32:23.95122Z","steps":["trace[1085079481] 'process raft request' (duration: 303.252462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:23.951494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.456315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qdmth\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-24T04:32:23.95159Z","caller":"traceutil/trace.go:171","msg":"trace[352332194] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qdmth; range_end:; response_count:1; response_revision:490; }","duration":"113.616979ms","start":"2026-02-24T04:32:23.837954Z","end":"2026-02-24T04:32:23.951571Z","steps":["trace[352332194] 'agreement among raft nodes before linearized reading' (duration: 113.30435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:23.952378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:23.647747Z","time spent":"303.738506ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:32:24.107675Z","caller":"traceutil/trace.go:171","msg":"trace[714981254] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"142.30091ms","start":"2026-02-24T04:32:23.965342Z","end":"2026-02-24T04:32:24.107643Z","steps":["trace[714981254] 'process raft request' (duration: 142.108084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:24.868045Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235209825,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:32:25.36911Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235209825,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T04:32:25.537198Z","caller":"traceutil/trace.go:171","msg":"trace[2005377749] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.238219099s","start":"2026-02-24T04:32:24.298955Z","end":"2026-02-24T04:32:25.537174Z","steps":["trace[2005377749] 'process raft request' (duration: 1.238079655s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:25.537347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:24.298928Z","time spent":"1.238340713s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:32:25.677629Z","caller":"traceutil/trace.go:171","msg":"trace[1615166064] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:513; }","duration":"1.310056354s","start":"2026-02-24T04:32:24.367545Z","end":"2026-02-24T04:32:25.677601Z","steps":["trace[1615166064] 'read index received' (duration: 1.169519815s)","trace[1615166064] 'applied index is now lower than readState.Index' (duration: 140.534719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:25.677948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.937625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:25.678023Z","caller":"traceutil/trace.go:171","msg":"trace[1773698726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"701.08715ms","start":"2026-02-24T04:32:24.976924Z","end":"2026-02-24T04:32:25.678011Z","steps":["trace[1773698726] 'agreement among raft nodes before linearized reading' (duration: 700.949826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:25.67809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:24.976896Z","time spent":"701.180772ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:32:25.677955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310387593s","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-24T04:32:25.678194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.967982ms","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-24T04:32:25.678208Z","caller":"traceutil/trace.go:171","msg":"trace[1382922389] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"1.310690212s","start":"2026-02-24T04:32:24.367499Z","end":"2026-02-24T04:32:25.678189Z","steps":["trace[1382922389] 'agreement among raft nodes before linearized reading' (duration: 1.31028155s)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:25.678301Z","caller":"traceutil/trace.go:171","msg":"trace[1541914392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:492; }","duration":"447.108646ms","start":"2026-02-24T04:32:25.231164Z","end":"2026-02-24T04:32:25.678273Z","steps":["trace[1541914392] 'agreement among raft nodes before linearized reading' (duration: 446.795827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:25.678392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:25.231149Z","time spent":"447.199008ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:32:25.678351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:24.367479Z","time spent":"1.310857837s","remote":"127.0.0.1:57424","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-24T04:32:30.163406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.917032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T04:32:30.1635Z","caller":"traceutil/trace.go:171","msg":"trace[1136900761] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:562; }","duration":"111.023325ms","start":"2026-02-24T04:32:30.052455Z","end":"2026-02-24T04:32:30.163478Z","steps":["trace[1136900761] 'range keys from in-memory index tree' (duration: 110.682546ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:39.173152Z","caller":"traceutil/trace.go:171","msg":"trace[1371023015] linearizableReadLoop","detail":"{readStateIndex:678; appliedIndex:677; }","duration":"225.435036ms","start":"2026-02-24T04:32:38.947698Z","end":"2026-02-24T04:32:39.173133Z","steps":["trace[1371023015] 'read index received' (duration: 225.279542ms)","trace[1371023015] 'applied index is now lower than readState.Index' (duration: 154.764µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:32:39.173229Z","caller":"traceutil/trace.go:171","msg":"trace[372222077] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"226.851917ms","start":"2026-02-24T04:32:38.946334Z","end":"2026-02-24T04:32:39.173186Z","steps":["trace[372222077] 'process raft request' (duration: 226.696292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:39.173386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.668404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-bt9mz\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-02-24T04:32:39.173414Z","caller":"traceutil/trace.go:171","msg":"trace[1252794211] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-bt9mz; range_end:; response_count:1; response_revision:651; }","duration":"225.735415ms","start":"2026-02-24T04:32:38.947669Z","end":"2026-02-24T04:32:39.173405Z","steps":["trace[1252794211] 'agreement among raft nodes before linearized reading' (duration: 225.585461ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:39.173404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.622556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:39.173509Z","caller":"traceutil/trace.go:171","msg":"trace[1484584866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:651; }","duration":"194.78864ms","start":"2026-02-24T04:32:38.978697Z","end":"2026-02-24T04:32:39.173486Z","steps":["trace[1484584866] 'agreement among raft nodes before linearized reading' (duration: 194.613965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.143298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235210177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:32:46.505433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.102884ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-24T04:32:46.50556Z","caller":"traceutil/trace.go:171","msg":"trace[963554702] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"862.67063ms","start":"2026-02-24T04:32:45.642866Z","end":"2026-02-24T04:32:46.505537Z","steps":["trace[963554702] 'read index received' (duration: 756.494734ms)","trace[963554702] 'applied index is now lower than readState.Index' (duration: 106.173836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:32:46.505796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.508324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:32:46.505846Z","caller":"traceutil/trace.go:171","msg":"trace[1736707543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"528.598566ms","start":"2026-02-24T04:32:45.977232Z","end":"2026-02-24T04:32:46.505831Z","steps":["trace[1736707543] 'agreement among raft nodes before linearized reading' (duration: 528.503934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.505876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.553058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T04:32:46.505876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.009129ms","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-24T04:32:46.505929Z","caller":"traceutil/trace.go:171","msg":"trace[41018365] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"861.668521ms","start":"2026-02-24T04:32:45.644247Z","end":"2026-02-24T04:32:46.505916Z","steps":["trace[41018365] 'agreement among raft nodes before linearized reading' (duration: 861.461805ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:46.505946Z","caller":"traceutil/trace.go:171","msg":"trace[1114622416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:674; }","duration":"863.131673ms","start":"2026-02-24T04:32:45.642797Z","end":"2026-02-24T04:32:46.505929Z","steps":["trace[1114622416] 'agreement among raft nodes before linearized reading' (duration: 862.817054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.50599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:45.642758Z","time spent":"863.220346ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:32:46.506021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.747088ms","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-24T04:32:46.506064Z","caller":"traceutil/trace.go:171","msg":"trace[838963957] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:674; }","duration":"344.836752ms","start":"2026-02-24T04:32:46.161215Z","end":"2026-02-24T04:32:46.506052Z","steps":["trace[838963957] 'agreement among raft nodes before linearized reading' (duration: 344.706528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.506077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"720.743281ms","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-24T04:32:46.5061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:46.161192Z","time spent":"344.898203ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:32:46.506107Z","caller":"traceutil/trace.go:171","msg":"trace[142964904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:674; }","duration":"720.787672ms","start":"2026-02-24T04:32:45.785308Z","end":"2026-02-24T04:32:46.506096Z","steps":["trace[142964904] 'agreement among raft nodes before linearized reading' (duration: 720.70545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.506172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:45.7853Z","time spent":"720.861525ms","remote":"127.0.0.1:57424","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-24T04:32:46.505964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:45.644218Z","time spent":"861.738053ms","remote":"127.0.0.1:57424","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-24T04:32:46.506182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.120432ms","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-24T04:32:46.50621Z","caller":"traceutil/trace.go:171","msg":"trace[392096812] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"721.206514ms","start":"2026-02-24T04:32:45.784996Z","end":"2026-02-24T04:32:46.506202Z","steps":["trace[392096812] 'agreement among raft nodes before linearized reading' (duration: 721.131602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:32:46.505893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:45.977214Z","time spent":"528.666938ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:32:46.506237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:32:45.784966Z","time spent":"721.264296ms","remote":"127.0.0.1:57424","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-24T04:32:46.681079Z","caller":"traceutil/trace.go:171","msg":"trace[2098574099] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"170.214088ms","start":"2026-02-24T04:32:46.510846Z","end":"2026-02-24T04:32:46.68106Z","steps":["trace[2098574099] 'process raft request' (duration: 170.069354ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:46.746278Z","caller":"traceutil/trace.go:171","msg":"trace[611109261] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"234.958739ms","start":"2026-02-24T04:32:46.51129Z","end":"2026-02-24T04:32:46.746249Z","steps":["trace[611109261] 'process raft request' (duration: 234.846686ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:46.746484Z","caller":"traceutil/trace.go:171","msg":"trace[377788065] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"235.36882ms","start":"2026-02-24T04:32:46.511081Z","end":"2026-02-24T04:32:46.74645Z","steps":["trace[377788065] 'process raft request' (duration: 230.331736ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:46.74661Z","caller":"traceutil/trace.go:171","msg":"trace[751917221] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"235.01382ms","start":"2026-02-24T04:32:46.511572Z","end":"2026-02-24T04:32:46.746586Z","steps":["trace[751917221] 'process raft request' (duration: 234.934908ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:32:46.746725Z","caller":"traceutil/trace.go:171","msg":"trace[1728601083] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"235.384171ms","start":"2026-02-24T04:32:46.511329Z","end":"2026-02-24T04:32:46.746713Z","steps":["trace[1728601083] 'process raft request' (duration: 234.873826ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:01.21472Z","caller":"traceutil/trace.go:171","msg":"trace[371081232] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"230.20539ms","start":"2026-02-24T04:33:00.984491Z","end":"2026-02-24T04:33:01.214696Z","steps":["trace[371081232] 'process raft request' (duration: 230.155059ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:01.215095Z","caller":"traceutil/trace.go:171","msg":"trace[1926543601] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"230.907767ms","start":"2026-02-24T04:33:00.984147Z","end":"2026-02-24T04:33:01.215055Z","steps":["trace[1926543601] 'process raft request' (duration: 230.309622ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:11.691612Z","caller":"traceutil/trace.go:171","msg":"trace[1732629541] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"274.947843ms","start":"2026-02-24T04:33:11.41663Z","end":"2026-02-24T04:33:11.691578Z","steps":["trace[1732629541] 'read index received' (duration: 274.263186ms)","trace[1732629541] 'applied index is now lower than readState.Index' (duration: 682.957µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:11.691702Z","caller":"traceutil/trace.go:171","msg":"trace[952300712] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"307.138362ms","start":"2026-02-24T04:33:11.384516Z","end":"2026-02-24T04:33:11.691654Z","steps":["trace[952300712] 'process raft request' (duration: 306.386663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:11.691815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.148897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:11.691994Z","caller":"traceutil/trace.go:171","msg":"trace[1231519930] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:753; }","duration":"275.321721ms","start":"2026-02-24T04:33:11.416616Z","end":"2026-02-24T04:33:11.691938Z","steps":["trace[1231519930] 'agreement among raft nodes before linearized reading' (duration: 275.081986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:11.691889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:11.384487Z","time spent":"307.297486ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:12.881134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.424349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:12.881221Z","caller":"traceutil/trace.go:171","msg":"trace[1343693701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"903.558683ms","start":"2026-02-24T04:33:11.977628Z","end":"2026-02-24T04:33:12.881187Z","steps":["trace[1343693701] 'range keys from in-memory index tree' (duration: 903.347398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:12.881259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:11.97761Z","time spent":"903.637154ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:33:12.88133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.300222ms","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-24T04:33:12.881481Z","caller":"traceutil/trace.go:171","msg":"trace[22506919] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:753; }","duration":"380.455585ms","start":"2026-02-24T04:33:12.500999Z","end":"2026-02-24T04:33:12.881455Z","steps":["trace[22506919] 'range keys from in-memory index tree' (duration: 380.286191ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:13.351193Z","caller":"traceutil/trace.go:171","msg":"trace[542573474] linearizableReadLoop","detail":"{readStateIndex:788; appliedIndex:787; }","duration":"453.854772ms","start":"2026-02-24T04:33:12.897315Z","end":"2026-02-24T04:33:13.351169Z","steps":["trace[542573474] 'read index received' (duration: 453.738119ms)","trace[542573474] 'applied index is now lower than readState.Index' (duration: 115.543µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:13.351488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.131358ms","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-24T04:33:13.351564Z","caller":"traceutil/trace.go:171","msg":"trace[1093291767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"454.252891ms","start":"2026-02-24T04:33:12.8973Z","end":"2026-02-24T04:33:13.351552Z","steps":["trace[1093291767] 'agreement among raft nodes before linearized reading' (duration: 453.968854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:13.351605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:12.897255Z","time spent":"454.341143ms","remote":"127.0.0.1:57424","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-24T04:33:13.351884Z","caller":"traceutil/trace.go:171","msg":"trace[1879820145] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"460.751118ms","start":"2026-02-24T04:33:12.890467Z","end":"2026-02-24T04:33:13.351219Z","steps":["trace[1879820145] 'process raft request' (duration: 460.558573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:13.351972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:12.89044Z","time spent":"461.473356ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:13.429742Z","caller":"traceutil/trace.go:171","msg":"trace[739967275] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"197.144709ms","start":"2026-02-24T04:33:13.232574Z","end":"2026-02-24T04:33:13.429719Z","steps":["trace[739967275] 'process raft request' (duration: 192.929817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:13.430066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.649553ms","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-24T04:33:13.430174Z","caller":"traceutil/trace.go:171","msg":"trace[1837353944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"276.824116ms","start":"2026-02-24T04:33:13.153327Z","end":"2026-02-24T04:33:13.430152Z","steps":["trace[1837353944] 'agreement among raft nodes before linearized reading' (duration: 276.469308ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:14.380782Z","caller":"traceutil/trace.go:171","msg":"trace[1570345397] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"402.106099ms","start":"2026-02-24T04:33:13.978652Z","end":"2026-02-24T04:33:14.380758Z","steps":["trace[1570345397] 'read index received' (duration: 401.874854ms)","trace[1570345397] 'applied index is now lower than readState.Index' (duration: 230.385µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:14.380927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.259892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:14.380901Z","caller":"traceutil/trace.go:171","msg":"trace[220120851] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"679.697326ms","start":"2026-02-24T04:33:13.701154Z","end":"2026-02-24T04:33:14.380851Z","steps":["trace[220120851] 'process raft request' (duration: 679.4384ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:14.380968Z","caller":"traceutil/trace.go:171","msg":"trace[1324255508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"402.356875ms","start":"2026-02-24T04:33:13.978598Z","end":"2026-02-24T04:33:14.380955Z","steps":["trace[1324255508] 'agreement among raft nodes before linearized reading' (duration: 402.254052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:14.381006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:13.978552Z","time spent":"402.446977ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:33:14.381105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:13.70112Z","time spent":"679.86844ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:15.742798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.172857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:15.742945Z","caller":"traceutil/trace.go:171","msg":"trace[345094997] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"267.371799ms","start":"2026-02-24T04:33:15.475553Z","end":"2026-02-24T04:33:15.742925Z","steps":["trace[345094997] 'read index received' (duration: 3.994006ms)","trace[345094997] 'applied index is now lower than readState.Index' (duration: 263.376023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:15.743335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.869627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:33:15.743657Z","caller":"traceutil/trace.go:171","msg":"trace[911913598] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"293.777388ms","start":"2026-02-24T04:33:15.449251Z","end":"2026-02-24T04:33:15.743029Z","steps":["trace[911913598] 'process raft request' (duration: 30.280573ms)","trace[911913598] 'compare' (duration: 263.037844ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:15.743671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.085027ms","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-24T04:33:15.743808Z","caller":"traceutil/trace.go:171","msg":"trace[2128112938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"212.046091ms","start":"2026-02-24T04:33:15.531367Z","end":"2026-02-24T04:33:15.743413Z","steps":["trace[2128112938] 'agreement among raft nodes before linearized reading' (duration: 211.723583ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:15.74393Z","caller":"traceutil/trace.go:171","msg":"trace[1648134222] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:761; }","duration":"268.398824ms","start":"2026-02-24T04:33:15.475504Z","end":"2026-02-24T04:33:15.743903Z","steps":["trace[1648134222] 'agreement among raft nodes before linearized reading' (duration: 267.84812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:16.278186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.244071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:16.27828Z","caller":"traceutil/trace.go:171","msg":"trace[1924511058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:763; }","duration":"299.410235ms","start":"2026-02-24T04:33:15.978848Z","end":"2026-02-24T04:33:16.278258Z","steps":["trace[1924511058] 'range keys from in-memory index tree' (duration: 299.142918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:16.278199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.110963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:16.278411Z","caller":"traceutil/trace.go:171","msg":"trace[1414283400] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:763; }","duration":"398.39678ms","start":"2026-02-24T04:33:15.879992Z","end":"2026-02-24T04:33:16.278388Z","steps":["trace[1414283400] 'count revisions from in-memory index tree' (duration: 397.96742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:16.278608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:15.87997Z","time spent":"398.620385ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:33:20.333699Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235210460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:33:20.834298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235210460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:33:20.848784Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023562555s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:33:20.849167Z","caller":"traceutil/trace.go:171","msg":"trace[837458753] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"1.024004795s","start":"2026-02-24T04:33:19.825142Z","end":"2026-02-24T04:33:20.849146Z","steps":["trace[837458753] 'process raft request' (duration: 1.023898763s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.849287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:19.825132Z","time spent":"1.024094118s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:20.898719Z","caller":"traceutil/trace.go:171","msg":"trace[434701754] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"1.066307139s","start":"2026-02-24T04:33:19.832386Z","end":"2026-02-24T04:33:20.898693Z","steps":["trace[434701754] 'process raft request' (duration: 1.066152475s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.898881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:19.832328Z","time spent":"1.066463112s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:20.899226Z","caller":"traceutil/trace.go:171","msg":"trace[441899708] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"889.937121ms","start":"2026-02-24T04:33:20.009268Z","end":"2026-02-24T04:33:20.899205Z","steps":["trace[441899708] 'process raft request' (duration: 889.655605ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:20.899246Z","caller":"traceutil/trace.go:171","msg":"trace[414329105] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:804; }","duration":"1.066662148s","start":"2026-02-24T04:33:19.832552Z","end":"2026-02-24T04:33:20.899214Z","steps":["trace[414329105] 'read index received' (duration: 1.016553255s)","trace[414329105] 'applied index is now lower than readState.Index' (duration: 50.106653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:20.899298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:20.009259Z","time spent":"890.001853ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:20.899419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066841832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:33:20.89945Z","caller":"traceutil/trace.go:171","msg":"trace[1738027606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:773; }","duration":"1.066927414s","start":"2026-02-24T04:33:19.832515Z","end":"2026-02-24T04:33:20.899443Z","steps":["trace[1738027606] 'agreement among raft nodes before linearized reading' (duration: 1.066801381s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.32687ms","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":"warn","ts":"2026-02-24T04:33:20.899474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:19.832507Z","time spent":"1.066961685s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:33:20.899485Z","caller":"traceutil/trace.go:171","msg":"trace[1888074093] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:773; }","duration":"997.356971ms","start":"2026-02-24T04:33:19.902118Z","end":"2026-02-24T04:33:20.899475Z","steps":["trace[1888074093] 'agreement among raft nodes before linearized reading' (duration: 997.30997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:19.90207Z","time spent":"997.431423ms","remote":"127.0.0.1:57480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:33:20.899502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.751681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:33:20.899583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.659525ms","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-24T04:33:20.899577Z","caller":"traceutil/trace.go:171","msg":"trace[145184974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:773; }","duration":"592.855803ms","start":"2026-02-24T04:33:20.306708Z","end":"2026-02-24T04:33:20.899564Z","steps":["trace[145184974] 'agreement among raft nodes before linearized reading' (duration: 592.749601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:20.306696Z","time spent":"592.935246ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:33:20.899599Z","caller":"traceutil/trace.go:171","msg":"trace[1954870001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:773; }","duration":"486.720346ms","start":"2026-02-24T04:33:20.412873Z","end":"2026-02-24T04:33:20.899593Z","steps":["trace[1954870001] 'agreement among raft nodes before linearized reading' (duration: 486.669995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:20.412851Z","time spent":"486.832028ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:33:20.899289Z","caller":"traceutil/trace.go:171","msg":"trace[616934943] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.066797841s","start":"2026-02-24T04:33:19.832478Z","end":"2026-02-24T04:33:20.899275Z","steps":["trace[616934943] 'process raft request' (duration: 1.066179466s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.101647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-24T04:33:20.899789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:19.83247Z","time spent":"1.067274633s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:20.899823Z","caller":"traceutil/trace.go:171","msg":"trace[1089294120] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:773; }","duration":"845.291982ms","start":"2026-02-24T04:33:20.054505Z","end":"2026-02-24T04:33:20.899797Z","steps":["trace[1089294120] 'agreement among raft nodes before linearized reading' (duration: 844.990014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:20.899904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:20.054484Z","time spent":"845.402824ms","remote":"127.0.0.1:57182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"info","ts":"2026-02-24T04:33:33.31828Z","caller":"traceutil/trace.go:171","msg":"trace[668255548] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"381.037801ms","start":"2026-02-24T04:33:32.93723Z","end":"2026-02-24T04:33:33.318268Z","steps":["trace[668255548] 'process raft request' (duration: 380.897848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.31835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.061881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:33.318377Z","caller":"traceutil/trace.go:171","msg":"trace[257150493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:856; }","duration":"341.132412ms","start":"2026-02-24T04:33:32.977237Z","end":"2026-02-24T04:33:33.318369Z","steps":["trace[257150493] 'agreement among raft nodes before linearized reading' (duration: 341.074211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.31838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:32.937214Z","time spent":"381.119792ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:33.318398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:32.977224Z","time spent":"341.168964ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:33:33.31827Z","caller":"traceutil/trace.go:171","msg":"trace[465989758] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"340.975199ms","start":"2026-02-24T04:33:32.977268Z","end":"2026-02-24T04:33:33.318244Z","steps":["trace[465989758] 'read index received' (duration: 340.795625ms)","trace[465989758] 'applied index is now lower than readState.Index' (duration: 178.794µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:33.318594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.835186ms","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-24T04:33:33.318645Z","caller":"traceutil/trace.go:171","msg":"trace[1852146154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:856; }","duration":"340.915877ms","start":"2026-02-24T04:33:32.977717Z","end":"2026-02-24T04:33:33.318633Z","steps":["trace[1852146154] 'agreement among raft nodes before linearized reading' (duration: 340.774804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.318677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:32.977707Z","time spent":"340.964078ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:33:33.318708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.71233ms","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-24T04:33:33.318735Z","caller":"traceutil/trace.go:171","msg":"trace[1022008398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"251.776642ms","start":"2026-02-24T04:33:33.06695Z","end":"2026-02-24T04:33:33.318726Z","steps":["trace[1022008398] 'agreement among raft nodes before linearized reading' (duration: 251.69208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.31871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.722685ms","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-24T04:33:33.318766Z","caller":"traceutil/trace.go:171","msg":"trace[2043943895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"298.810387ms","start":"2026-02-24T04:33:33.019949Z","end":"2026-02-24T04:33:33.318759Z","steps":["trace[2043943895] 'agreement among raft nodes before linearized reading' (duration: 298.693465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.318866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.099021ms","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-24T04:33:33.318929Z","caller":"traceutil/trace.go:171","msg":"trace[1163067349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:856; }","duration":"338.193113ms","start":"2026-02-24T04:33:32.980723Z","end":"2026-02-24T04:33:33.318916Z","steps":["trace[1163067349] 'agreement among raft nodes before linearized reading' (duration: 338.012459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:33.318969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:32.980713Z","time spent":"338.250774ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:33:33.457966Z","caller":"traceutil/trace.go:171","msg":"trace[1773521175] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"135.369043ms","start":"2026-02-24T04:33:33.32257Z","end":"2026-02-24T04:33:33.457939Z","steps":["trace[1773521175] 'process raft request' (duration: 134.049942ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:33.457959Z","caller":"traceutil/trace.go:171","msg":"trace[75128204] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"135.371693ms","start":"2026-02-24T04:33:33.322571Z","end":"2026-02-24T04:33:33.457943Z","steps":["trace[75128204] 'process raft request' (duration: 135.293491ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:33.458151Z","caller":"traceutil/trace.go:171","msg":"trace[1993159815] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"135.245471ms","start":"2026-02-24T04:33:33.322895Z","end":"2026-02-24T04:33:33.458141Z","steps":["trace[1993159815] 'process raft request' (duration: 135.014915ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:33.458289Z","caller":"traceutil/trace.go:171","msg":"trace[1260064967] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"135.303962ms","start":"2026-02-24T04:33:33.322975Z","end":"2026-02-24T04:33:33.458279Z","steps":["trace[1260064967] 'process raft request' (duration: 135.128168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:49.940806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.478575ms","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-24T04:33:49.940893Z","caller":"traceutil/trace.go:171","msg":"trace[2011123250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:920; }","duration":"328.616758ms","start":"2026-02-24T04:33:49.612255Z","end":"2026-02-24T04:33:49.940872Z","steps":["trace[2011123250] 'range keys from in-memory index tree' (duration: 328.30169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:49.940928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.8089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-24T04:33:49.940929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.0069ms","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-24T04:33:49.940949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.612239Z","time spent":"328.689029ms","remote":"127.0.0.1:57424","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-24T04:33:49.940983Z","caller":"traceutil/trace.go:171","msg":"trace[1676107473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:920; }","duration":"330.870691ms","start":"2026-02-24T04:33:49.610099Z","end":"2026-02-24T04:33:49.940969Z","steps":["trace[1676107473] 'range keys from in-memory index tree' (duration: 330.680336ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:49.940993Z","caller":"traceutil/trace.go:171","msg":"trace[1825228335] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:920; }","duration":"327.091893ms","start":"2026-02-24T04:33:49.613887Z","end":"2026-02-24T04:33:49.940979Z","steps":["trace[1825228335] 'range keys from in-memory index tree' (duration: 326.892777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:49.940962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.782936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T04:33:49.941028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.613881Z","time spent":"327.139624ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:33:49.941056Z","caller":"traceutil/trace.go:171","msg":"trace[2013944386] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:920; }","duration":"332.93733ms","start":"2026-02-24T04:33:49.608098Z","end":"2026-02-24T04:33:49.941035Z","steps":["trace[2013944386] 'range keys from in-memory index tree' (duration: 332.602222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:49.941014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.610022Z","time spent":"330.986773ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:33:49.941112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.608078Z","time spent":"333.024541ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:33:50.341576Z","caller":"traceutil/trace.go:171","msg":"trace[838553374] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"396.129795ms","start":"2026-02-24T04:33:49.945424Z","end":"2026-02-24T04:33:50.341554Z","steps":["trace[838553374] 'process raft request' (duration: 395.90836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:50.341766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.94541Z","time spent":"396.231817ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:50.341858Z","caller":"traceutil/trace.go:171","msg":"trace[1306912459] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"396.382481ms","start":"2026-02-24T04:33:49.945452Z","end":"2026-02-24T04:33:50.341835Z","steps":["trace[1306912459] 'process raft request' (duration: 396.024313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:50.34197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.945427Z","time spent":"396.479724ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:50.342143Z","caller":"traceutil/trace.go:171","msg":"trace[599030338] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"396.631517ms","start":"2026-02-24T04:33:49.945499Z","end":"2026-02-24T04:33:50.34213Z","steps":["trace[599030338] 'process raft request' (duration: 396.272849ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:50.342139Z","caller":"traceutil/trace.go:171","msg":"trace[2095845065] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:964; }","duration":"363.435377ms","start":"2026-02-24T04:33:49.978677Z","end":"2026-02-24T04:33:50.342112Z","steps":["trace[2095845065] 'read index received' (duration: 41.719731ms)","trace[2095845065] 'applied index is now lower than readState.Index' (duration: 321.714595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:50.342222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.945491Z","time spent":"396.693068ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:50.342279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.58073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:50.342346Z","caller":"traceutil/trace.go:171","msg":"trace[1717623103] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"363.686083ms","start":"2026-02-24T04:33:49.978645Z","end":"2026-02-24T04:33:50.342331Z","steps":["trace[1717623103] 'agreement among raft nodes before linearized reading' (duration: 363.55996ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:50.342377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.459193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-24T04:33:50.342383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:49.978635Z","time spent":"363.741604ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:33:50.342415Z","caller":"traceutil/trace.go:171","msg":"trace[1368548939] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:924; }","duration":"285.509004ms","start":"2026-02-24T04:33:50.056893Z","end":"2026-02-24T04:33:50.342402Z","steps":["trace[1368548939] 'agreement among raft nodes before linearized reading' (duration: 285.362341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:51.867905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.552218ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:51.867983Z","caller":"traceutil/trace.go:171","msg":"trace[1797291987] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"337.19507ms","start":"2026-02-24T04:33:51.530775Z","end":"2026-02-24T04:33:51.86797Z","steps":["trace[1797291987] 'read index received' (duration: 34.791µs)","trace[1797291987] 'applied index is now lower than readState.Index' (duration: 337.159459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:51.868078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.303153ms","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-24T04:33:51.86809Z","caller":"traceutil/trace.go:171","msg":"trace[863649045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:926; }","duration":"337.347273ms","start":"2026-02-24T04:33:51.530739Z","end":"2026-02-24T04:33:51.868086Z","steps":["trace[863649045] 'agreement among raft nodes before linearized reading' (duration: 337.261091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:51.868106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:51.530724Z","time spent":"337.378014ms","remote":"127.0.0.1:57424","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-24T04:33:51.868292Z","caller":"traceutil/trace.go:171","msg":"trace[198023805] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"699.903579ms","start":"2026-02-24T04:33:51.168367Z","end":"2026-02-24T04:33:51.86827Z","steps":["trace[198023805] 'process raft request' (duration: 71.914761ms)","trace[198023805] 'compare' (duration: 627.481756ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:51.868399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:51.168314Z","time spent":"700.028192ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:52.662307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.03945ms","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-24T04:33:52.6629Z","caller":"traceutil/trace.go:171","msg":"trace[1867656306] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:926; }","duration":"162.642975ms","start":"2026-02-24T04:33:52.500243Z","end":"2026-02-24T04:33:52.662886Z","steps":["trace[1867656306] 'range keys from in-memory index tree' (duration: 162.02534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.662617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.397759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:52.663066Z","caller":"traceutil/trace.go:171","msg":"trace[1027807479] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"687.371375ms","start":"2026-02-24T04:33:51.975687Z","end":"2026-02-24T04:33:52.663058Z","steps":["trace[1027807479] 'read index received' (duration: 23.511µs)","trace[1027807479] 'applied index is now lower than readState.Index' (duration: 687.347094ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:52.663126Z","caller":"traceutil/trace.go:171","msg":"trace[1272471972] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"791.399931ms","start":"2026-02-24T04:33:51.871718Z","end":"2026-02-24T04:33:52.663118Z","steps":["trace[1272471972] 'process raft request' (duration: 99.406898ms)","trace[1272471972] 'compare' (duration: 691.139423ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:52.663198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:51.871705Z","time spent":"791.438212ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:52.663411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.628621ms","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-24T04:33:52.663477Z","caller":"traceutil/trace.go:171","msg":"trace[765098348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"639.732324ms","start":"2026-02-24T04:33:52.023728Z","end":"2026-02-24T04:33:52.66346Z","steps":["trace[765098348] 'agreement among raft nodes before linearized reading' (duration: 639.531339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.663515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.023712Z","time spent":"639.795455ms","remote":"127.0.0.1:57424","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-24T04:33:52.663656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.7657ms","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-24T04:33:52.663684Z","caller":"traceutil/trace.go:171","msg":"trace[1747847914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"316.81084ms","start":"2026-02-24T04:33:52.346865Z","end":"2026-02-24T04:33:52.663676Z","steps":["trace[1747847914] 'agreement among raft nodes before linearized reading' (duration: 316.707998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.663708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.346844Z","time spent":"316.856792ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-24T04:33:52.663713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.003899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:33:52.66374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.111897ms","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-24T04:33:52.663757Z","caller":"traceutil/trace.go:171","msg":"trace[1603214613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"688.087421ms","start":"2026-02-24T04:33:51.975655Z","end":"2026-02-24T04:33:52.663743Z","steps":["trace[1603214613] 'agreement among raft nodes before linearized reading' (duration: 688.001629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.663762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.690268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T04:33:52.663835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:51.975638Z","time spent":"688.190254ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:33:52.663877Z","caller":"traceutil/trace.go:171","msg":"trace[360355687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"316.851501ms","start":"2026-02-24T04:33:52.347005Z","end":"2026-02-24T04:33:52.663856Z","steps":["trace[360355687] 'agreement among raft nodes before linearized reading' (duration: 316.572555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.663926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.34698Z","time spent":"316.931983ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:33:52.663785Z","caller":"traceutil/trace.go:171","msg":"trace[866919674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"317.215859ms","start":"2026-02-24T04:33:52.346555Z","end":"2026-02-24T04:33:52.663771Z","steps":["trace[866919674] 'agreement among raft nodes before linearized reading' (duration: 317.041085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.663988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.346533Z","time spent":"317.444685ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:33:52.893275Z","caller":"traceutil/trace.go:171","msg":"trace[1419432] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"223.238059ms","start":"2026-02-24T04:33:52.670015Z","end":"2026-02-24T04:33:52.893253Z","steps":["trace[1419432] 'process raft request' (duration: 137.80935ms)","trace[1419432] 'compare' (duration: 85.329147ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:52.989459Z","caller":"traceutil/trace.go:171","msg":"trace[2111769160] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"319.263057ms","start":"2026-02-24T04:33:52.670172Z","end":"2026-02-24T04:33:52.989435Z","steps":["trace[2111769160] 'process raft request' (duration: 318.99107ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:52.989494Z","caller":"traceutil/trace.go:171","msg":"trace[1452062324] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"319.231196ms","start":"2026-02-24T04:33:52.670213Z","end":"2026-02-24T04:33:52.989444Z","steps":["trace[1452062324] 'process raft request' (duration: 319.117043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.989615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.670155Z","time spent":"319.3816ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:52.989734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.670208Z","time spent":"319.376069ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:52.989966Z","caller":"traceutil/trace.go:171","msg":"trace[1893386009] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"319.694057ms","start":"2026-02-24T04:33:52.670249Z","end":"2026-02-24T04:33:52.989943Z","steps":["trace[1893386009] 'process raft request' (duration: 319.134423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:52.990085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:52.670242Z","time spent":"319.773409ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:53.78984Z","caller":"traceutil/trace.go:171","msg":"trace[582283765] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"139.990453ms","start":"2026-02-24T04:33:53.649827Z","end":"2026-02-24T04:33:53.789817Z","steps":["trace[582283765] 'process raft request' (duration: 139.803728ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:53.944606Z","caller":"traceutil/trace.go:171","msg":"trace[1549466916] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"152.052896ms","start":"2026-02-24T04:33:53.792533Z","end":"2026-02-24T04:33:53.944586Z","steps":["trace[1549466916] 'process raft request' (duration: 151.933474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.168511Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235210841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:33:55.66961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235210841,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:33:55.809576Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.444058439s","expected-duration":"1s"} {"level":"info","ts":"2026-02-24T04:33:55.810214Z","caller":"traceutil/trace.go:171","msg":"trace[1299782885] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"1.444785796s","start":"2026-02-24T04:33:54.365404Z","end":"2026-02-24T04:33:55.81019Z","steps":["trace[1299782885] 'process raft request' (duration: 1.444630992s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.810461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.365385Z","time spent":"1.444903198s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:55.855195Z","caller":"traceutil/trace.go:171","msg":"trace[120800189] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:977; }","duration":"1.187569517s","start":"2026-02-24T04:33:54.6676Z","end":"2026-02-24T04:33:55.85517Z","steps":["trace[120800189] 'read index received' (duration: 1.142228861s)","trace[120800189] 'applied index is now lower than readState.Index' (duration: 45.339106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:33:55.855386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.394038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:33:55.85542Z","caller":"traceutil/trace.go:171","msg":"trace[1643139748] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:934; }","duration":"668.497431ms","start":"2026-02-24T04:33:55.186913Z","end":"2026-02-24T04:33:55.85541Z","steps":["trace[1643139748] 'agreement among raft nodes before linearized reading' (duration: 668.420369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.855455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:55.186891Z","time spent":"668.555691ms","remote":"127.0.0.1:57536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:33:55.855459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187836524s","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-24T04:33:55.855535Z","caller":"traceutil/trace.go:171","msg":"trace[1195856397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"1.187970836s","start":"2026-02-24T04:33:54.667545Z","end":"2026-02-24T04:33:55.855516Z","steps":["trace[1195856397] 'agreement among raft nodes before linearized reading' (duration: 1.18771316s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.855582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.667522Z","time spent":"1.188052258s","remote":"127.0.0.1:57424","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-24T04:33:55.855813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.849844ms","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-24T04:33:55.855844Z","caller":"traceutil/trace.go:171","msg":"trace[228548974] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"860.901815ms","start":"2026-02-24T04:33:54.994933Z","end":"2026-02-24T04:33:55.855835Z","steps":["trace[228548974] 'agreement among raft nodes before linearized reading' (duration: 860.772322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.85587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.994922Z","time spent":"860.942016ms","remote":"127.0.0.1:57424","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-24T04:33:55.85591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.394918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:33:55.855946Z","caller":"traceutil/trace.go:171","msg":"trace[1840499680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"958.466549ms","start":"2026-02-24T04:33:54.89747Z","end":"2026-02-24T04:33:55.855936Z","steps":["trace[1840499680] 'agreement among raft nodes before linearized reading' (duration: 957.817664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.855973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.897451Z","time spent":"958.515611ms","remote":"127.0.0.1:57424","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-24T04:33:55.856095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.517173ms","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-24T04:33:55.856124Z","caller":"traceutil/trace.go:171","msg":"trace[936381566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"862.578005ms","start":"2026-02-24T04:33:54.993538Z","end":"2026-02-24T04:33:55.856116Z","steps":["trace[936381566] 'agreement among raft nodes before linearized reading' (duration: 862.470623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.856173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.99352Z","time spent":"862.646236ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-24T04:33:55.85625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.310419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:55.856273Z","caller":"traceutil/trace.go:171","msg":"trace[1916664448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"868.366681ms","start":"2026-02-24T04:33:54.987898Z","end":"2026-02-24T04:33:55.856264Z","steps":["trace[1916664448] 'agreement among raft nodes before linearized reading' (duration: 868.33535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.856295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.987877Z","time spent":"868.413832ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:33:55.856488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.845691ms","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-24T04:33:55.856513Z","caller":"traceutil/trace.go:171","msg":"trace[254658086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"862.871141ms","start":"2026-02-24T04:33:54.993634Z","end":"2026-02-24T04:33:55.856505Z","steps":["trace[254658086] 'agreement among raft nodes before linearized reading' (duration: 862.087843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:55.856544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:54.993619Z","time spent":"862.916683ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-24T04:33:55.997256Z","caller":"traceutil/trace.go:171","msg":"trace[111141101] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"136.93982ms","start":"2026-02-24T04:33:55.860301Z","end":"2026-02-24T04:33:55.997241Z","steps":["trace[111141101] 'process raft request' (duration: 136.834857ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:56.15194Z","caller":"traceutil/trace.go:171","msg":"trace[188956835] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"289.736243ms","start":"2026-02-24T04:33:55.86214Z","end":"2026-02-24T04:33:56.151877Z","steps":["trace[188956835] 'process raft request' (duration: 288.33946ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:56.152657Z","caller":"traceutil/trace.go:171","msg":"trace[1109523110] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"290.410009ms","start":"2026-02-24T04:33:55.862223Z","end":"2026-02-24T04:33:56.152633Z","steps":["trace[1109523110] 'process raft request' (duration: 288.3361ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:56.152831Z","caller":"traceutil/trace.go:171","msg":"trace[368372171] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"290.576313ms","start":"2026-02-24T04:33:55.862184Z","end":"2026-02-24T04:33:56.15276Z","steps":["trace[368372171] 'process raft request' (duration: 288.34315ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:56.153898Z","caller":"traceutil/trace.go:171","msg":"trace[1276467531] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"288.715889ms","start":"2026-02-24T04:33:55.862025Z","end":"2026-02-24T04:33:56.150741Z","steps":["trace[1276467531] 'process raft request' (duration: 213.648644ms)","trace[1276467531] 'compare' (duration: 74.683656ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:56.369348Z","caller":"traceutil/trace.go:171","msg":"trace[1043591802] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"128.87235ms","start":"2026-02-24T04:33:56.24046Z","end":"2026-02-24T04:33:56.369332Z","steps":["trace[1043591802] 'process raft request' (duration: 128.760527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:56.562709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.406836ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:33:56.562917Z","caller":"traceutil/trace.go:171","msg":"trace[69908144] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"103.633337ms","start":"2026-02-24T04:33:56.459268Z","end":"2026-02-24T04:33:56.562901Z","steps":["trace[69908144] 'process raft request' (duration: 103.554995ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:56.563203Z","caller":"traceutil/trace.go:171","msg":"trace[95656124] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"190.150492ms","start":"2026-02-24T04:33:56.37304Z","end":"2026-02-24T04:33:56.563191Z","steps":["trace[95656124] 'process raft request' (duration: 45.188873ms)","trace[95656124] 'compare' (duration: 144.191311ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:56.805997Z","caller":"traceutil/trace.go:171","msg":"trace[75981190] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"215.755484ms","start":"2026-02-24T04:33:56.590215Z","end":"2026-02-24T04:33:56.80597Z","steps":["trace[75981190] 'process raft request' (duration: 215.190621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:57.733192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.546723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:33:57.733287Z","caller":"traceutil/trace.go:171","msg":"trace[562871767] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:945; }","duration":"583.697107ms","start":"2026-02-24T04:33:57.149568Z","end":"2026-02-24T04:33:57.733265Z","steps":["trace[562871767] 'count revisions from in-memory index tree' (duration: 583.395119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:57.733348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:57.149554Z","time spent":"583.769518ms","remote":"127.0.0.1:57582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:33:57.733371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.125184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:33:57.733422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.259165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:33:57.733451Z","caller":"traceutil/trace.go:171","msg":"trace[222991493] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:945; }","duration":"316.257098ms","start":"2026-02-24T04:33:57.417176Z","end":"2026-02-24T04:33:57.733433Z","steps":["trace[222991493] 'count revisions from in-memory index tree' (duration: 316.033572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:57.733503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:57.417152Z","time spent":"316.341159ms","remote":"127.0.0.1:57278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:33:57.733524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.120223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T04:33:57.733599Z","caller":"traceutil/trace.go:171","msg":"trace[1242982017] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:945; }","duration":"640.238246ms","start":"2026-02-24T04:33:57.093344Z","end":"2026-02-24T04:33:57.733583Z","steps":["trace[1242982017] 'count revisions from in-memory index tree' (duration: 640.046762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:57.733639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:57.093325Z","time spent":"640.304107ms","remote":"127.0.0.1:57232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":104,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-24T04:33:57.733556Z","caller":"traceutil/trace.go:171","msg":"trace[1065904798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"756.389258ms","start":"2026-02-24T04:33:56.977096Z","end":"2026-02-24T04:33:57.733485Z","steps":["trace[1065904798] 'range keys from in-memory index tree' (duration: 756.146172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:57.734207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:56.977067Z","time spent":"757.123145ms","remote":"127.0.0.1:57156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-24T04:33:58.505811Z","caller":"traceutil/trace.go:171","msg":"trace[2102188101] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"336.973925ms","start":"2026-02-24T04:33:58.168805Z","end":"2026-02-24T04:33:58.505779Z","steps":["trace[2102188101] 'read index received' (duration: 336.746229ms)","trace[2102188101] 'applied index is now lower than readState.Index' (duration: 226.165µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:33:58.505879Z","caller":"traceutil/trace.go:171","msg":"trace[819754680] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"502.668751ms","start":"2026-02-24T04:33:58.003183Z","end":"2026-02-24T04:33:58.505852Z","steps":["trace[819754680] 'process raft request' (duration: 502.377164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.506023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.003164Z","time spent":"502.779853ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:58.506028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.20512ms","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-24T04:33:58.506164Z","caller":"traceutil/trace.go:171","msg":"trace[240464218] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"337.393204ms","start":"2026-02-24T04:33:58.168757Z","end":"2026-02-24T04:33:58.50615Z","steps":["trace[240464218] 'agreement among raft nodes before linearized reading' (duration: 337.158329ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.506211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.168736Z","time spent":"337.464255ms","remote":"127.0.0.1:57424","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-24T04:33:58.506326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.301792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-24T04:33:58.506453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.522157ms","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-24T04:33:58.506392Z","caller":"traceutil/trace.go:171","msg":"trace[59292997] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"337.380184ms","start":"2026-02-24T04:33:58.168996Z","end":"2026-02-24T04:33:58.506377Z","steps":["trace[59292997] 'agreement among raft nodes before linearized reading' (duration: 337.113797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.506651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.16899Z","time spent":"337.64224ms","remote":"127.0.0.1:57424","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-24T04:33:58.506665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.732392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:33:58.506899Z","caller":"traceutil/trace.go:171","msg":"trace[1908279311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"338.08664ms","start":"2026-02-24T04:33:58.168792Z","end":"2026-02-24T04:33:58.506879Z","steps":["trace[1908279311] 'agreement among raft nodes before linearized reading' (duration: 337.320242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.507058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.168772Z","time spent":"338.262565ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-24T04:33:58.506504Z","caller":"traceutil/trace.go:171","msg":"trace[884096798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:946; }","duration":"337.605239ms","start":"2026-02-24T04:33:58.168886Z","end":"2026-02-24T04:33:58.506492Z","steps":["trace[884096798] 'agreement among raft nodes before linearized reading' (duration: 337.439855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.507274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.168862Z","time spent":"338.393907ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-24T04:33:58.761477Z","caller":"traceutil/trace.go:171","msg":"trace[385117245] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"251.545975ms","start":"2026-02-24T04:33:58.509911Z","end":"2026-02-24T04:33:58.761457Z","steps":["trace[385117245] 'process raft request' (duration: 246.574708ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:58.852954Z","caller":"traceutil/trace.go:171","msg":"trace[1546578981] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"341.856129ms","start":"2026-02-24T04:33:58.51108Z","end":"2026-02-24T04:33:58.852936Z","steps":["trace[1546578981] 'process raft request' (duration: 341.827888ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:33:58.853015Z","caller":"traceutil/trace.go:171","msg":"trace[1547471728] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"341.991742ms","start":"2026-02-24T04:33:58.510989Z","end":"2026-02-24T04:33:58.852981Z","steps":["trace[1547471728] 'process raft request' (duration: 341.882369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.853084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.511075Z","time spent":"341.937961ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:33:58.853186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.510982Z","time spent":"342.119835ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:33:58.853379Z","caller":"traceutil/trace.go:171","msg":"trace[235911313] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"342.491574ms","start":"2026-02-24T04:33:58.510874Z","end":"2026-02-24T04:33:58.853366Z","steps":["trace[235911313] 'process raft request' (duration: 341.90528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:33:58.853451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:33:58.510866Z","time spent":"342.555195ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:34:01.790321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.364181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:01.790423Z","caller":"traceutil/trace.go:171","msg":"trace[830639922] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"237.506275ms","start":"2026-02-24T04:34:01.552885Z","end":"2026-02-24T04:34:01.790392Z","steps":["trace[830639922] 'range keys from in-memory index tree' (duration: 237.27847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.790528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.392178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-24T04:34:01.790524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.263487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:01.790571Z","caller":"traceutil/trace.go:171","msg":"trace[437342918] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:952; }","duration":"327.470491ms","start":"2026-02-24T04:34:01.46309Z","end":"2026-02-24T04:34:01.79056Z","steps":["trace[437342918] 'count revisions from in-memory index tree' (duration: 327.327597ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:01.790593Z","caller":"traceutil/trace.go:171","msg":"trace[1738809245] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:952; }","duration":"643.36593ms","start":"2026-02-24T04:34:01.147206Z","end":"2026-02-24T04:34:01.790571Z","steps":["trace[1738809245] 'count revisions from in-memory index tree' (duration: 643.168485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.790601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:01.463073Z","time spent":"327.518881ms","remote":"127.0.0.1:57484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:01.790626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:01.14719Z","time spent":"643.428841ms","remote":"127.0.0.1:57350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-02-24T04:34:01.790687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.166398ms","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-24T04:34:01.790752Z","caller":"traceutil/trace.go:171","msg":"trace[1518072014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"934.262931ms","start":"2026-02-24T04:34:00.856471Z","end":"2026-02-24T04:34:01.790734Z","steps":["trace[1518072014] 'range keys from in-memory index tree' (duration: 933.912062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.790804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.23637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-24T04:34:01.790835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.024335019s","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-24T04:34:01.790896Z","caller":"traceutil/trace.go:171","msg":"trace[622188093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"934.371523ms","start":"2026-02-24T04:34:00.856505Z","end":"2026-02-24T04:34:01.790876Z","steps":["trace[622188093] 'range keys from in-memory index tree' (duration: 933.961124ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:01.790916Z","caller":"traceutil/trace.go:171","msg":"trace[2121858432] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"1.024460622s","start":"2026-02-24T04:34:00.766437Z","end":"2026-02-24T04:34:01.790898Z","steps":["trace[2121858432] 'range keys from in-memory index tree' (duration: 1.024102013s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.790939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:00.856489Z","time spent":"934.440304ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-24T04:34:01.790976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.910572ms","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-24T04:34:01.790787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:00.856451Z","time spent":"934.327412ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T04:34:01.791021Z","caller":"traceutil/trace.go:171","msg":"trace[2118095118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"933.974703ms","start":"2026-02-24T04:34:00.857036Z","end":"2026-02-24T04:34:01.791011Z","steps":["trace[2118095118] 'range keys from in-memory index tree' (duration: 933.754079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.791113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:00.857027Z","time spent":"934.077026ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T04:34:01.790983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:34:00.766375Z","time spent":"1.024592684s","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-24T04:34:01.899048Z","caller":"traceutil/trace.go:171","msg":"trace[254489816] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"103.29308ms","start":"2026-02-24T04:34:01.79573Z","end":"2026-02-24T04:34:01.899023Z","steps":["trace[254489816] 'process raft request' (duration: 103.133996ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:01.967912Z","caller":"traceutil/trace.go:171","msg":"trace[833974090] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"171.981694ms","start":"2026-02-24T04:34:01.795909Z","end":"2026-02-24T04:34:01.967891Z","steps":["trace[833974090] 'process raft request' (duration: 171.848871ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:01.968125Z","caller":"traceutil/trace.go:171","msg":"trace[2013375437] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:998; }","duration":"158.595499ms","start":"2026-02-24T04:34:01.809508Z","end":"2026-02-24T04:34:01.968104Z","steps":["trace[2013375437] 'read index received' (duration: 89.347171ms)","trace[2013375437] 'applied index is now lower than readState.Index' (duration: 69.247118ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:01.9682Z","caller":"traceutil/trace.go:171","msg":"trace[1630669447] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"172.150988ms","start":"2026-02-24T04:34:01.796042Z","end":"2026-02-24T04:34:01.968193Z","steps":["trace[1630669447] 'process raft request' (duration: 171.80965ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:01.968391Z","caller":"traceutil/trace.go:171","msg":"trace[1435162291] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"172.21381ms","start":"2026-02-24T04:34:01.796156Z","end":"2026-02-24T04:34:01.968369Z","steps":["trace[1435162291] 'process raft request' (duration: 171.861882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:01.968743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.789684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-24T04:34:01.968851Z","caller":"traceutil/trace.go:171","msg":"trace[237752589] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:956; }","duration":"159.323206ms","start":"2026-02-24T04:34:01.809467Z","end":"2026-02-24T04:34:01.968791Z","steps":["trace[237752589] 'agreement among raft nodes before linearized reading' (duration: 158.706062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:34:02.980488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.875879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T04:34:02.981011Z","caller":"traceutil/trace.go:171","msg":"trace[1892143345] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:961; }","duration":"137.471823ms","start":"2026-02-24T04:34:02.843518Z","end":"2026-02-24T04:34:02.98099Z","steps":["trace[1892143345] 'range keys from in-memory index tree' (duration: 136.771287ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:08.116925Z","caller":"traceutil/trace.go:171","msg":"trace[1352957609] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"124.009436ms","start":"2026-02-24T04:34:07.992877Z","end":"2026-02-24T04:34:08.116886Z","steps":["trace[1352957609] 'process raft request' (duration: 123.160276ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:32.857297Z","caller":"traceutil/trace.go:171","msg":"trace[467740818] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"142.816427ms","start":"2026-02-24T04:34:32.714466Z","end":"2026-02-24T04:34:32.857282Z","steps":["trace[467740818] 'process raft request' (duration: 142.730165ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:34:33.017467Z","caller":"traceutil/trace.go:171","msg":"trace[1471599582] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"139.210663ms","start":"2026-02-24T04:34:32.878224Z","end":"2026-02-24T04:34:33.017434Z","steps":["trace[1471599582] 'read index received' (duration: 69.911454ms)","trace[1471599582] 'applied index is now lower than readState.Index' (duration: 69.298109ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:34:33.017564Z","caller":"traceutil/trace.go:171","msg":"trace[94439593] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"176.197008ms","start":"2026-02-24T04:34:32.841338Z","end":"2026-02-24T04:34:33.017535Z","steps":["trace[94439593] 'process raft request' (duration: 106.870407ms)","trace[94439593] 'compare' (duration: 69.083765ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:34:33.01781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.567342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-24T04:34:33.017863Z","caller":"traceutil/trace.go:171","msg":"trace[1918682050] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1108; }","duration":"139.655454ms","start":"2026-02-24T04:34:32.878192Z","end":"2026-02-24T04:34:33.017847Z","steps":["trace[1918682050] 'agreement among raft nodes before linearized reading' (duration: 139.426289ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:35:13.414472Z","caller":"traceutil/trace.go:171","msg":"trace[1897193888] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"132.712714ms","start":"2026-02-24T04:35:13.281729Z","end":"2026-02-24T04:35:13.414442Z","steps":["trace[1897193888] 'process raft request' (duration: 83.665422ms)","trace[1897193888] 'compare' (duration: 48.919949ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:20.833635Z","caller":"traceutil/trace.go:171","msg":"trace[189295947] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"204.774738ms","start":"2026-02-24T04:35:20.628838Z","end":"2026-02-24T04:35:20.833612Z","steps":["trace[189295947] 'read index received' (duration: 204.538563ms)","trace[189295947] 'applied index is now lower than readState.Index' (duration: 234.845µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:35:20.833678Z","caller":"traceutil/trace.go:171","msg":"trace[1638103682] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"228.492655ms","start":"2026-02-24T04:35:20.605168Z","end":"2026-02-24T04:35:20.83366Z","steps":["trace[1638103682] 'process raft request' (duration: 228.303251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:35:20.833935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.067715ms","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-24T04:35:20.834025Z","caller":"traceutil/trace.go:171","msg":"trace[1077248323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"205.197817ms","start":"2026-02-24T04:35:20.628805Z","end":"2026-02-24T04:35:20.834003Z","steps":["trace[1077248323] 'agreement among raft nodes before linearized reading' (duration: 204.941971ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:18.758453Z","caller":"traceutil/trace.go:171","msg":"trace[1250448133] transaction","detail":"{read_only:false; response_revision:1537; number_of_response:1; }","duration":"112.106803ms","start":"2026-02-24T04:36:18.646315Z","end":"2026-02-24T04:36:18.758422Z","steps":["trace[1250448133] 'process raft request' (duration: 111.931309ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:19.749853Z","caller":"traceutil/trace.go:171","msg":"trace[1886707371] linearizableReadLoop","detail":"{readStateIndex:1614; appliedIndex:1613; }","duration":"164.406907ms","start":"2026-02-24T04:36:19.585412Z","end":"2026-02-24T04:36:19.749819Z","steps":["trace[1886707371] 'read index received' (duration: 164.187192ms)","trace[1886707371] 'applied index is now lower than readState.Index' (duration: 218.905µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:19.749966Z","caller":"traceutil/trace.go:171","msg":"trace[149935903] transaction","detail":"{read_only:false; response_revision:1541; number_of_response:1; }","duration":"195.381431ms","start":"2026-02-24T04:36:19.554563Z","end":"2026-02-24T04:36:19.749945Z","steps":["trace[149935903] 'process raft request' (duration: 195.078844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:19.750148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.706954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-24T04:36:19.750187Z","caller":"traceutil/trace.go:171","msg":"trace[1356969824] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1541; }","duration":"164.821286ms","start":"2026-02-24T04:36:19.585355Z","end":"2026-02-24T04:36:19.750177Z","steps":["trace[1356969824] 'agreement among raft nodes before linearized reading' (duration: 164.576961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:20.034979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.788704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:20.035141Z","caller":"traceutil/trace.go:171","msg":"trace[1134339901] linearizableReadLoop","detail":"{readStateIndex:1615; appliedIndex:1614; }","duration":"209.758187ms","start":"2026-02-24T04:36:19.825366Z","end":"2026-02-24T04:36:20.035124Z","steps":["trace[1134339901] 'read index received' (duration: 65.736057ms)","trace[1134339901] 'applied index is now lower than readState.Index' (duration: 144.02092ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:20.035299Z","caller":"traceutil/trace.go:171","msg":"trace[521646679] transaction","detail":"{read_only:false; response_revision:1542; number_of_response:1; }","duration":"280.647826ms","start":"2026-02-24T04:36:19.754613Z","end":"2026-02-24T04:36:20.035261Z","steps":["trace[521646679] 'process raft request' (duration: 136.489333ms)","trace[521646679] 'compare' (duration: 143.661512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:20.035516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.182434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-24T04:36:20.035581Z","caller":"traceutil/trace.go:171","msg":"trace[1485611145] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1542; }","duration":"200.271225ms","start":"2026-02-24T04:36:19.835293Z","end":"2026-02-24T04:36:20.035564Z","steps":["trace[1485611145] 'agreement among raft nodes before linearized reading' (duration: 200.078921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:20.035686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.321441ms","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-24T04:36:20.03574Z","caller":"traceutil/trace.go:171","msg":"trace[1265503406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1542; }","duration":"210.423883ms","start":"2026-02-24T04:36:19.825301Z","end":"2026-02-24T04:36:20.035724Z","steps":["trace[1265503406] 'agreement among raft nodes before linearized reading' (duration: 210.137406ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:20.257151Z","caller":"traceutil/trace.go:171","msg":"trace[1044518632] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1615; }","duration":"189.987265ms","start":"2026-02-24T04:36:20.067138Z","end":"2026-02-24T04:36:20.257126Z","steps":["trace[1044518632] 'read index received' (duration: 189.76572ms)","trace[1044518632] 'applied index is now lower than readState.Index' (duration: 220.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:20.257524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.345433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T04:36:20.257768Z","caller":"traceutil/trace.go:171","msg":"trace[151220477] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:1544; }","duration":"190.65563ms","start":"2026-02-24T04:36:20.067094Z","end":"2026-02-24T04:36:20.25775Z","steps":["trace[151220477] 'agreement among raft nodes before linearized reading' (duration: 190.138108ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:20.257977Z","caller":"traceutil/trace.go:171","msg":"trace[2006619525] transaction","detail":"{read_only:false; response_revision:1543; number_of_response:1; }","duration":"218.067471ms","start":"2026-02-24T04:36:20.039893Z","end":"2026-02-24T04:36:20.25796Z","steps":["trace[2006619525] 'process raft request' (duration: 217.042677ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:20.258168Z","caller":"traceutil/trace.go:171","msg":"trace[491365545] transaction","detail":"{read_only:false; response_revision:1544; number_of_response:1; }","duration":"218.257126ms","start":"2026-02-24T04:36:20.039899Z","end":"2026-02-24T04:36:20.258157Z","steps":["trace[491365545] 'process raft request' (duration: 217.183081ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:20.50023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.116597ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:20.500327Z","caller":"traceutil/trace.go:171","msg":"trace[2013128446] transaction","detail":"{read_only:false; response_revision:1545; number_of_response:1; }","duration":"211.57498ms","start":"2026-02-24T04:36:20.288734Z","end":"2026-02-24T04:36:20.500309Z","steps":["trace[2013128446] 'process raft request' (duration: 42.287059ms)","trace[2013128446] 'compare' (duration: 168.861131ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:23.871443Z","caller":"traceutil/trace.go:171","msg":"trace[539199725] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"104.216539ms","start":"2026-02-24T04:36:23.767199Z","end":"2026-02-24T04:36:23.871415Z","steps":["trace[539199725] 'process raft request' (duration: 81.288732ms)","trace[539199725] 'compare' (duration: 22.819724ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:24.30894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.255028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:24.309231Z","caller":"traceutil/trace.go:171","msg":"trace[1701215979] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"250.361947ms","start":"2026-02-24T04:36:24.058838Z","end":"2026-02-24T04:36:24.3092Z","steps":["trace[1701215979] 'process raft request' (duration: 48.765531ms)","trace[1701215979] 'compare' (duration: 201.001592ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:24.681499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.008266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:24.681675Z","caller":"traceutil/trace.go:171","msg":"trace[1141640659] linearizableReadLoop","detail":"{readStateIndex:1635; appliedIndex:1633; }","duration":"201.215108ms","start":"2026-02-24T04:36:24.480422Z","end":"2026-02-24T04:36:24.681638Z","steps":["trace[1141640659] 'read index received' (duration: 58.11141ms)","trace[1141640659] 'applied index is now lower than readState.Index' (duration: 143.102708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:24.681771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.346961ms","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-24T04:36:24.681817Z","caller":"traceutil/trace.go:171","msg":"trace[1556938624] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1561; }","duration":"201.425103ms","start":"2026-02-24T04:36:24.480382Z","end":"2026-02-24T04:36:24.681807Z","steps":["trace[1556938624] 'agreement among raft nodes before linearized reading' (duration: 201.32542ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:24.682141Z","caller":"traceutil/trace.go:171","msg":"trace[223307432] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"368.948932ms","start":"2026-02-24T04:36:24.313178Z","end":"2026-02-24T04:36:24.682127Z","steps":["trace[223307432] 'process raft request' (duration: 225.23825ms)","trace[223307432] 'compare' (duration: 142.913584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:36:24.682209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:24.313149Z","time spent":"369.027244ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:24.68233Z","caller":"traceutil/trace.go:171","msg":"trace[444255966] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"368.665116ms","start":"2026-02-24T04:36:24.313657Z","end":"2026-02-24T04:36:24.682322Z","steps":["trace[444255966] 'process raft request' (duration: 367.935709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:24.682385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:24.31363Z","time spent":"368.728247ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:36:25.319398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.386023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:36:25.319528Z","caller":"traceutil/trace.go:171","msg":"trace[297176348] transaction","detail":"{read_only:false; response_revision:1563; number_of_response:1; }","duration":"277.307078ms","start":"2026-02-24T04:36:25.042201Z","end":"2026-02-24T04:36:25.319508Z","steps":["trace[297176348] 'process raft request' (duration: 57.750881ms)","trace[297176348] 'compare' (duration: 219.25333ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:32.922433Z","caller":"traceutil/trace.go:171","msg":"trace[506687072] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"101.828372ms","start":"2026-02-24T04:36:32.82057Z","end":"2026-02-24T04:36:32.922399Z","steps":["trace[506687072] 'process raft request' (duration: 101.604867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:33.24378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.652473ms","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-24T04:36:33.243881Z","caller":"traceutil/trace.go:171","msg":"trace[220753503] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1595; }","duration":"123.807977ms","start":"2026-02-24T04:36:33.120046Z","end":"2026-02-24T04:36:33.243854Z","steps":["trace[220753503] 'count revisions from in-memory index tree' (duration: 123.49851ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:51.349862Z","caller":"traceutil/trace.go:171","msg":"trace[352535462] transaction","detail":"{read_only:false; response_revision:1670; number_of_response:1; }","duration":"115.662248ms","start":"2026-02-24T04:36:51.234175Z","end":"2026-02-24T04:36:51.349837Z","steps":["trace[352535462] 'process raft request' (duration: 115.512705ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:53.696566Z","caller":"traceutil/trace.go:171","msg":"trace[1539152017] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"127.805887ms","start":"2026-02-24T04:36:53.568737Z","end":"2026-02-24T04:36:53.696543Z","steps":["trace[1539152017] 'process raft request' (duration: 127.654844ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:36:54.003347Z","caller":"traceutil/trace.go:171","msg":"trace[769729704] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"105.406233ms","start":"2026-02-24T04:36:53.897884Z","end":"2026-02-24T04:36:54.00329Z","steps":["trace[769729704] 'process raft request' (duration: 105.148827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:56.201249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674960235212751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T04:36:56.442739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015006736s","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-24T04:36:56.442845Z","caller":"traceutil/trace.go:171","msg":"trace[106421016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1685; }","duration":"1.01514711s","start":"2026-02-24T04:36:55.427674Z","end":"2026-02-24T04:36:56.442821Z","steps":["trace[106421016] 'range keys from in-memory index tree' (duration: 1.014802052s)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:56.442901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:55.427662Z","time spent":"1.015224202s","remote":"127.0.0.1:57424","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-24T04:36:56.4458Z","caller":"traceutil/trace.go:171","msg":"trace[2128818682] linearizableReadLoop","detail":"{readStateIndex:1767; appliedIndex:1765; }","duration":"744.992331ms","start":"2026-02-24T04:36:55.700754Z","end":"2026-02-24T04:36:56.445747Z","steps":["trace[2128818682] 'read index received' (duration: 739.812392ms)","trace[2128818682] 'applied index is now lower than readState.Index' (duration: 5.178729ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:36:56.445987Z","caller":"traceutil/trace.go:171","msg":"trace[668556150] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"700.054938ms","start":"2026-02-24T04:36:55.745857Z","end":"2026-02-24T04:36:56.445912Z","steps":["trace[668556150] 'process raft request' (duration: 699.69823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:56.446053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.278578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:36:56.446091Z","caller":"traceutil/trace.go:171","msg":"trace[911254363] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1686; }","duration":"745.354459ms","start":"2026-02-24T04:36:55.700724Z","end":"2026-02-24T04:36:56.446079Z","steps":["trace[911254363] 'agreement among raft nodes before linearized reading' (duration: 745.193635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:56.446097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.791221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T04:36:56.446155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:55.700709Z","time spent":"745.427811ms","remote":"127.0.0.1:57424","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-24T04:36:56.446176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:55.745832Z","time spent":"700.249503ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T04:36:56.446178Z","caller":"traceutil/trace.go:171","msg":"trace[1490948706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1686; }","duration":"467.915454ms","start":"2026-02-24T04:36:55.97824Z","end":"2026-02-24T04:36:56.446155Z","steps":["trace[1490948706] 'agreement among raft nodes before linearized reading' (duration: 467.780191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:36:56.446324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:36:55.97822Z","time spent":"468.095447ms","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-24T04:36:56.446505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.962315ms","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-24T04:36:56.446561Z","caller":"traceutil/trace.go:171","msg":"trace[1492703891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1686; }","duration":"176.060877ms","start":"2026-02-24T04:36:56.270487Z","end":"2026-02-24T04:36:56.446548Z","steps":["trace[1492703891] 'agreement among raft nodes before linearized reading' (duration: 175.942894ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:02.108807Z","caller":"traceutil/trace.go:171","msg":"trace[1075424536] transaction","detail":"{read_only:false; response_revision:1716; number_of_response:1; }","duration":"127.441579ms","start":"2026-02-24T04:37:01.98131Z","end":"2026-02-24T04:37:02.108752Z","steps":["trace[1075424536] 'process raft request' (duration: 49.509148ms)","trace[1075424536] 'compare' (duration: 77.767157ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.528025Z","caller":"traceutil/trace.go:171","msg":"trace[1115092804] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"258.224194ms","start":"2026-02-24T04:37:17.269769Z","end":"2026-02-24T04:37:17.527993Z","steps":["trace[1115092804] 'read index received' (duration: 257.863966ms)","trace[1115092804] 'applied index is now lower than readState.Index' (duration: 358.848µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.528206Z","caller":"traceutil/trace.go:171","msg":"trace[35163298] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"361.110349ms","start":"2026-02-24T04:37:17.167077Z","end":"2026-02-24T04:37:17.528188Z","steps":["trace[35163298] 'process raft request' (duration: 360.721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.528406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T04:37:17.167052Z","time spent":"361.19572ms","remote":"127.0.0.1:57424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T04:37:17.528428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.933576ms","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-24T04:37:17.528518Z","caller":"traceutil/trace.go:171","msg":"trace[1357825631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1802; }","duration":"130.071599ms","start":"2026-02-24T04:37:17.398421Z","end":"2026-02-24T04:37:17.528493Z","steps":["trace[1357825631] 'agreement among raft nodes before linearized reading' (duration: 129.854804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.528724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.955211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-24T04:37:17.528801Z","caller":"traceutil/trace.go:171","msg":"trace[2090223831] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1802; }","duration":"259.164676ms","start":"2026-02-24T04:37:17.269618Z","end":"2026-02-24T04:37:17.528783Z","steps":["trace[2090223831] 'agreement among raft nodes before linearized reading' (duration: 258.679824ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:17.680932Z","caller":"traceutil/trace.go:171","msg":"trace[1046298076] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1888; }","duration":"100.371527ms","start":"2026-02-24T04:37:17.580538Z","end":"2026-02-24T04:37:17.68091Z","steps":["trace[1046298076] 'read index received' (duration: 86.267303ms)","trace[1046298076] 'applied index is now lower than readState.Index' (duration: 14.103124ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.681194Z","caller":"traceutil/trace.go:171","msg":"trace[1831313575] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"147.849628ms","start":"2026-02-24T04:37:17.533329Z","end":"2026-02-24T04:37:17.681179Z","steps":["trace[1831313575] 'process raft request' (duration: 133.358675ms)","trace[1831313575] 'compare' (duration: 13.781027ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:17.681298Z","caller":"traceutil/trace.go:171","msg":"trace[448112128] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"146.556259ms","start":"2026-02-24T04:37:17.534709Z","end":"2026-02-24T04:37:17.681265Z","steps":["trace[448112128] 'process raft request' (duration: 146.15252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:17.681756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.193196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2821"} {"level":"info","ts":"2026-02-24T04:37:17.681803Z","caller":"traceutil/trace.go:171","msg":"trace[810294885] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1804; }","duration":"101.280838ms","start":"2026-02-24T04:37:17.58051Z","end":"2026-02-24T04:37:17.68179Z","steps":["trace[810294885] 'agreement among raft nodes before linearized reading' (duration: 101.085934ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:18.865705Z","caller":"traceutil/trace.go:171","msg":"trace[1684811439] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1894; }","duration":"133.404416ms","start":"2026-02-24T04:37:18.732275Z","end":"2026-02-24T04:37:18.86568Z","steps":["trace[1684811439] 'read index received' (duration: 62.382914ms)","trace[1684811439] 'applied index is now lower than readState.Index' (duration: 71.020442ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:18.865759Z","caller":"traceutil/trace.go:171","msg":"trace[1002498107] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"155.638367ms","start":"2026-02-24T04:37:18.710088Z","end":"2026-02-24T04:37:18.865726Z","steps":["trace[1002498107] 'process raft request' (duration: 84.613115ms)","trace[1002498107] 'compare' (duration: 70.830298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:18.865932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.632931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-24T04:37:18.867136Z","caller":"traceutil/trace.go:171","msg":"trace[1907448468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1809; }","duration":"134.87311ms","start":"2026-02-24T04:37:18.732245Z","end":"2026-02-24T04:37:18.867118Z","steps":["trace[1907448468] 'agreement among raft nodes before linearized reading' (duration: 133.55904ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:19.006862Z","caller":"traceutil/trace.go:171","msg":"trace[521976959] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"135.162336ms","start":"2026-02-24T04:37:18.87167Z","end":"2026-02-24T04:37:19.006833Z","steps":["trace[521976959] 'process raft request' (duration: 134.91152ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:19.007127Z","caller":"traceutil/trace.go:171","msg":"trace[1676359734] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"136.743762ms","start":"2026-02-24T04:37:18.87036Z","end":"2026-02-24T04:37:19.007104Z","steps":["trace[1676359734] 'process raft request' (duration: 96.913506ms)","trace[1676359734] 'compare' (duration: 39.186821ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:19.762225Z","caller":"traceutil/trace.go:171","msg":"trace[546563011] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"218.481791ms","start":"2026-02-24T04:37:19.543661Z","end":"2026-02-24T04:37:19.762143Z","steps":["trace[546563011] 'process raft request' (duration: 218.292756ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T04:37:20.024928Z","caller":"traceutil/trace.go:171","msg":"trace[1631855450] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"257.749864ms","start":"2026-02-24T04:37:19.76715Z","end":"2026-02-24T04:37:20.0249Z","steps":["trace[1631855450] 'process raft request' (duration: 257.58943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:20.250151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.231255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-24T04:37:20.250219Z","caller":"traceutil/trace.go:171","msg":"trace[242404076] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:1816; }","duration":"178.367028ms","start":"2026-02-24T04:37:20.071835Z","end":"2026-02-24T04:37:20.250202Z","steps":["trace[242404076] 'range keys from in-memory index tree' (duration: 177.98697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:37.939296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.939644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T04:37:37.939439Z","caller":"traceutil/trace.go:171","msg":"trace[1248277099] linearizableReadLoop","detail":"{readStateIndex:1985; appliedIndex:1984; }","duration":"156.565138ms","start":"2026-02-24T04:37:37.782852Z","end":"2026-02-24T04:37:37.939417Z","steps":["trace[1248277099] 'read index received' (duration: 20.329457ms)","trace[1248277099] 'applied index is now lower than readState.Index' (duration: 136.234361ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T04:37:37.939467Z","caller":"traceutil/trace.go:171","msg":"trace[1786218254] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"157.122181ms","start":"2026-02-24T04:37:37.782312Z","end":"2026-02-24T04:37:37.939434Z","steps":["trace[1786218254] 'process raft request' (duration: 20.960981ms)","trace[1786218254] 'compare' (duration: 135.790391ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T04:37:37.939849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.583442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-24T04:37:37.939895Z","caller":"traceutil/trace.go:171","msg":"trace[87762926] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:1894; }","duration":"147.673734ms","start":"2026-02-24T04:37:37.792206Z","end":"2026-02-24T04:37:37.93988Z","steps":["trace[87762926] 'agreement among raft nodes before linearized reading' (duration: 147.51868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T04:37:37.940261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.856975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-24T04:37:37.940374Z","caller":"traceutil/trace.go:171","msg":"trace[139367146] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:1894; }","duration":"157.469579ms","start":"2026-02-24T04:37:37.782827Z","end":"2026-02-24T04:37:37.940296Z","steps":["trace[139367146] 'agreement among raft nodes before linearized reading' (duration: 156.691851ms)"],"step_count":1}