{"level":"warn","ts":"2026-02-25T06:09:10.58812Z","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-25T06:09:10.588206Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-25T06:09:10.58828Z","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-25T06:09:10.588294Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-02-25T06:09:10.588315Z","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-25T06:09:10.588964Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-02-25T06:09:10.589051Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-25T06:09:10.596339Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.051007ms"} {"level":"info","ts":"2026-02-25T06:09:10.608621Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-02-25T06:09:10.608713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:09:10.608762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-02-25T06:09:10.608779Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:09:10.608788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-02-25T06:09:10.608827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-02-25T06:09:10.622171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:09:10.623905Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:09:10.625103Z","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-25T06:09:10.62792Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:09:10.637064Z","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-25T06:09:10.637359Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:09:10.6374Z","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-25T06:09:10.637432Z","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-25T06:09:10.637822Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-02-25T06:09:10.638008Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-02-25T06:09:10.639263Z","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-25T06:09:10.639411Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-02-25T06:09:10.63944Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-02-25T06:09:10.639659Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:09:10.639751Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:09:11.50965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:09:11.509851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:09:11.510047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-02-25T06:09:11.510149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:09:11.510201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-02-25T06:09:11.510241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-02-25T06:09:11.510306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-02-25T06:09:12.234742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:09:12.234764Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:09:12.234802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:09:12.234797Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:09:12.235054Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:09:12.235136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:09:12.238318Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:09:12.238568Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:09:12.238627Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:09:12.239524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:09:12.242177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"warn","ts":"2026-02-25T06:09:18.666012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646664833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:09:19.166854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646664833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:09:19.58532Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419852496s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:09:19.585849Z","caller":"traceutil/trace.go:171","msg":"trace[2086318511] transaction","detail":"{read_only:false; number_of_response:0; response_revision:270; }","duration":"1.420419009s","start":"2026-02-25T06:09:18.165383Z","end":"2026-02-25T06:09:19.585802Z","steps":["trace[2086318511] 'process raft request' (duration: 1.420298556s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.586442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.165371Z","time spent":"1.420579793s","remote":"127.0.0.1:57530","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:09:19.594442Z","caller":"traceutil/trace.go:171","msg":"trace[412460800] transaction","detail":"{read_only:false; number_of_response:0; response_revision:271; }","duration":"1.426309439s","start":"2026-02-25T06:09:18.168109Z","end":"2026-02-25T06:09:19.594418Z","steps":["trace[412460800] 'process raft request' (duration: 1.426181996s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:19.594504Z","caller":"traceutil/trace.go:171","msg":"trace[191590713] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:278; }","duration":"1.428556412s","start":"2026-02-25T06:09:18.165912Z","end":"2026-02-25T06:09:19.594468Z","steps":["trace[191590713] 'read index received' (duration: 1.419686821s)","trace[191590713] 'applied index is now lower than readState.Index' (duration: 8.868411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:09:19.594575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.168094Z","time spent":"1.426425071s","remote":"127.0.0.1:57530","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:09:19.594621Z","caller":"traceutil/trace.go:171","msg":"trace[720886227] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"1.428001839s","start":"2026-02-25T06:09:18.166605Z","end":"2026-02-25T06:09:19.594607Z","steps":["trace[720886227] 'process raft request' (duration: 1.427538828s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.594699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.166591Z","time spent":"1.42806442s","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":190,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:09:19.594757Z","caller":"traceutil/trace.go:171","msg":"trace[1797960] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.426596315s","start":"2026-02-25T06:09:18.168147Z","end":"2026-02-25T06:09:19.594743Z","steps":["trace[1797960] 'process raft request' (duration: 1.426193516s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.594935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335107623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:09:19.594935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428980352s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5870"} {"level":"info","ts":"2026-02-25T06:09:19.595017Z","caller":"traceutil/trace.go:171","msg":"trace[1897198904] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:274; }","duration":"1.429112705s","start":"2026-02-25T06:09:18.165891Z","end":"2026-02-25T06:09:19.595003Z","steps":["trace[1897198904] 'agreement among raft nodes before linearized reading' (duration: 1.428932521s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.595052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.165882Z","time spent":"1.429162986s","remote":"127.0.0.1:57530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5893,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-25T06:09:19.595075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.16813Z","time spent":"1.426653716s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:19.595185Z","caller":"traceutil/trace.go:171","msg":"trace[1080240856] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"1.426308928s","start":"2026-02-25T06:09:18.168864Z","end":"2026-02-25T06:09:19.595173Z","steps":["trace[1080240856] 'process raft request' (duration: 1.42553461s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.59525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.168839Z","time spent":"1.42637015s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:19.594987Z","caller":"traceutil/trace.go:171","msg":"trace[1316985025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:274; }","duration":"1.335220686s","start":"2026-02-25T06:09:18.259757Z","end":"2026-02-25T06:09:19.594978Z","steps":["trace[1316985025] 'agreement among raft nodes before linearized reading' (duration: 1.335135564s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.595413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.259698Z","time spent":"1.335706857s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:09:19.595453Z","caller":"traceutil/trace.go:171","msg":"trace[700926883] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"1.426495293s","start":"2026-02-25T06:09:18.168941Z","end":"2026-02-25T06:09:19.595437Z","steps":["trace[700926883] 'process raft request' (duration: 1.425495559s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:19.596305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:18.168934Z","time spent":"1.426554574s","remote":"127.0.0.1:57586","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-25T06:09:20.915651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.800804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T06:09:20.915791Z","caller":"traceutil/trace.go:171","msg":"trace[1102214459] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:300; }","duration":"266.014229ms","start":"2026-02-25T06:09:20.64975Z","end":"2026-02-25T06:09:20.915765Z","steps":["trace[1102214459] 'range keys from in-memory index tree' (duration: 265.65696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.916044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.289347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T06:09:20.916157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.557673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-25T06:09:20.916186Z","caller":"traceutil/trace.go:171","msg":"trace[1696667139] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:300; }","duration":"365.585674ms","start":"2026-02-25T06:09:20.550591Z","end":"2026-02-25T06:09:20.916177Z","steps":["trace[1696667139] 'range keys from in-memory index tree' (duration: 365.433551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.916222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:20.55053Z","time spent":"365.684127ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-25T06:09:20.916348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.397596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-25T06:09:20.916383Z","caller":"traceutil/trace.go:171","msg":"trace[1718653188] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:300; }","duration":"316.481567ms","start":"2026-02-25T06:09:20.599893Z","end":"2026-02-25T06:09:20.916375Z","steps":["trace[1718653188] 'range keys from in-memory index tree' (duration: 316.196801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.916414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:20.599864Z","time spent":"316.542749ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-02-25T06:09:20.916171Z","caller":"traceutil/trace.go:171","msg":"trace[1768481522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:300; }","duration":"216.592465ms","start":"2026-02-25T06:09:20.699558Z","end":"2026-02-25T06:09:20.91615Z","steps":["trace[1768481522] 'range keys from in-memory index tree' (duration: 216.185675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.916981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.059669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T06:09:20.917048Z","caller":"traceutil/trace.go:171","msg":"trace[986356789] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:300; }","duration":"167.14545ms","start":"2026-02-25T06:09:20.749893Z","end":"2026-02-25T06:09:20.917038Z","steps":["trace[986356789] 'range keys from in-memory index tree' (duration: 166.981626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.916104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.712961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-02-25T06:09:20.917257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.747503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T06:09:20.917289Z","caller":"traceutil/trace.go:171","msg":"trace[94693516] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:300; }","duration":"116.816495ms","start":"2026-02-25T06:09:20.800464Z","end":"2026-02-25T06:09:20.91728Z","steps":["trace[94693516] 'range keys from in-memory index tree' (duration: 116.636141ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:20.917261Z","caller":"traceutil/trace.go:171","msg":"trace[1299728001] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:300; }","duration":"415.902819ms","start":"2026-02-25T06:09:20.50134Z","end":"2026-02-25T06:09:20.917243Z","steps":["trace[1299728001] 'range keys from in-memory index tree' (duration: 414.604728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:20.9182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:20.501322Z","time spent":"416.107364ms","remote":"127.0.0.1:57544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-02-25T06:09:51.963929Z","caller":"traceutil/trace.go:171","msg":"trace[192725502] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"180.945829ms","start":"2026-02-25T06:09:51.782958Z","end":"2026-02-25T06:09:51.963904Z","steps":["trace[192725502] 'read index received' (duration: 180.701823ms)","trace[192725502] 'applied index is now lower than readState.Index' (duration: 242.986µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:09:51.964216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.777375ms","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-25T06:09:51.964201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.194894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:09:51.964258Z","caller":"traceutil/trace.go:171","msg":"trace[1181599956] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"143.891209ms","start":"2026-02-25T06:09:51.820352Z","end":"2026-02-25T06:09:51.964244Z","steps":["trace[1181599956] 'agreement among raft nodes before linearized reading' (duration: 143.759136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:51.964254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.999482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:09:51.964277Z","caller":"traceutil/trace.go:171","msg":"trace[1977490061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"181.343568ms","start":"2026-02-25T06:09:51.782915Z","end":"2026-02-25T06:09:51.964259Z","steps":["trace[1977490061] 'agreement among raft nodes before linearized reading' (duration: 181.182984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:51.964269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.680073ms","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-25T06:09:51.964319Z","caller":"traceutil/trace.go:171","msg":"trace[2074753114] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"139.080984ms","start":"2026-02-25T06:09:51.82522Z","end":"2026-02-25T06:09:51.964301Z","steps":["trace[2074753114] 'agreement among raft nodes before linearized reading' (duration: 138.934691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:51.964345Z","caller":"traceutil/trace.go:171","msg":"trace[1567851629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"135.799636ms","start":"2026-02-25T06:09:51.828526Z","end":"2026-02-25T06:09:51.964325Z","steps":["trace[1567851629] 'agreement among raft nodes before linearized reading' (duration: 135.596161ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:51.964059Z","caller":"traceutil/trace.go:171","msg":"trace[1255661789] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"482.400318ms","start":"2026-02-25T06:09:51.481622Z","end":"2026-02-25T06:09:51.964023Z","steps":["trace[1255661789] 'process raft request' (duration: 482.129092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:51.964894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:51.481594Z","time spent":"483.171287ms","remote":"127.0.0.1:57530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:09:52.244433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.324895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:09:52.244539Z","caller":"traceutil/trace.go:171","msg":"trace[1068058251] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"274.570972ms","start":"2026-02-25T06:09:51.96995Z","end":"2026-02-25T06:09:52.244521Z","steps":["trace[1068058251] 'process raft request' (duration: 162.944061ms)","trace[1068058251] 'compare' (duration: 110.595157ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:09:52.325215Z","caller":"traceutil/trace.go:171","msg":"trace[951732396] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"354.990392ms","start":"2026-02-25T06:09:51.970201Z","end":"2026-02-25T06:09:52.325191Z","steps":["trace[951732396] 'process raft request' (duration: 354.951291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:52.325437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:51.970188Z","time spent":"355.169066ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:09:52.325562Z","caller":"traceutil/trace.go:171","msg":"trace[2033082421] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"355.392102ms","start":"2026-02-25T06:09:51.970131Z","end":"2026-02-25T06:09:52.325523Z","steps":["trace[2033082421] 'process raft request' (duration: 354.85617ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:09:52.325585Z","caller":"traceutil/trace.go:171","msg":"trace[420318514] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"355.345581ms","start":"2026-02-25T06:09:51.970201Z","end":"2026-02-25T06:09:52.325547Z","steps":["trace[420318514] 'process raft request' (duration: 354.917661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:52.325798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:51.970105Z","time spent":"355.539885ms","remote":"127.0.0.1:57586","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-25T06:09:52.326023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:51.970185Z","time spent":"355.551655ms","remote":"127.0.0.1:57586","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-25T06:09:56.080201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.385512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:09:56.080292Z","caller":"traceutil/trace.go:171","msg":"trace[1808331039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"355.517795ms","start":"2026-02-25T06:09:55.724754Z","end":"2026-02-25T06:09:56.080272Z","steps":["trace[1808331039] 'range keys from in-memory index tree' (duration: 355.300979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:09:56.080334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:09:55.724732Z","time spent":"355.592876ms","remote":"127.0.0.1:57314","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-25T06:10:05.838929Z","caller":"traceutil/trace.go:171","msg":"trace[155624115] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"115.656838ms","start":"2026-02-25T06:10:05.723221Z","end":"2026-02-25T06:10:05.838878Z","steps":["trace[155624115] 'read index received' (duration: 110.260912ms)","trace[155624115] 'applied index is now lower than readState.Index' (duration: 5.394836ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:05.839132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.899073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:05.83917Z","caller":"traceutil/trace.go:171","msg":"trace[217953550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"115.995796ms","start":"2026-02-25T06:10:05.723163Z","end":"2026-02-25T06:10:05.839159Z","steps":["trace[217953550] 'agreement among raft nodes before linearized reading' (duration: 115.849092ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:05.839159Z","caller":"traceutil/trace.go:171","msg":"trace[651738354] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"365.488976ms","start":"2026-02-25T06:10:05.473609Z","end":"2026-02-25T06:10:05.839098Z","steps":["trace[651738354] 'process raft request' (duration: 360.022659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:05.839589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:05.473585Z","time spent":"365.918747ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:10:12.921233Z","caller":"traceutil/trace.go:171","msg":"trace[497453156] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"470.054047ms","start":"2026-02-25T06:10:12.451163Z","end":"2026-02-25T06:10:12.921217Z","steps":["trace[497453156] 'read index received' (duration: 469.915393ms)","trace[497453156] 'applied index is now lower than readState.Index' (duration: 137.864µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:12.921423Z","caller":"traceutil/trace.go:171","msg":"trace[892314281] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"474.900339ms","start":"2026-02-25T06:10:12.446516Z","end":"2026-02-25T06:10:12.921416Z","steps":["trace[892314281] 'process raft request' (duration: 474.594562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:12.931765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.446481Z","time spent":"474.958101ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:12.932082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.91839ms","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-25T06:10:12.932134Z","caller":"traceutil/trace.go:171","msg":"trace[1514197036] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"481.000411ms","start":"2026-02-25T06:10:12.451126Z","end":"2026-02-25T06:10:12.932127Z","steps":["trace[1514197036] 'agreement among raft nodes before linearized reading' (duration: 480.891098ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:12.932165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.451111Z","time spent":"481.048443ms","remote":"127.0.0.1:57586","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-25T06:10:12.932333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.685981ms","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-25T06:10:12.932374Z","caller":"traceutil/trace.go:171","msg":"trace[491975395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"428.739602ms","start":"2026-02-25T06:10:12.503629Z","end":"2026-02-25T06:10:12.932369Z","steps":["trace[491975395] 'agreement among raft nodes before linearized reading' (duration: 428.671501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:12.9324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.503619Z","time spent":"428.775153ms","remote":"127.0.0.1:57586","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-25T06:10:12.934507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.07142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:10:12.934576Z","caller":"traceutil/trace.go:171","msg":"trace[2080354636] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:690; }","duration":"416.759263ms","start":"2026-02-25T06:10:12.517802Z","end":"2026-02-25T06:10:12.934561Z","steps":["trace[2080354636] 'agreement among raft nodes before linearized reading' (duration: 414.023089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:12.934613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.517785Z","time spent":"416.815734ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:12.934833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.458919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:10:12.934862Z","caller":"traceutil/trace.go:171","msg":"trace[1845775083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:690; }","duration":"433.212796ms","start":"2026-02-25T06:10:12.50164Z","end":"2026-02-25T06:10:12.934853Z","steps":["trace[1845775083] 'agreement among raft nodes before linearized reading' (duration: 423.399388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:12.934877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.501614Z","time spent":"433.258918ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:10:13.428922Z","caller":"traceutil/trace.go:171","msg":"trace[1352879017] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"492.505809ms","start":"2026-02-25T06:10:12.936394Z","end":"2026-02-25T06:10:13.4289Z","steps":["trace[1352879017] 'process raft request' (duration: 492.364926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:13.429083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.936374Z","time spent":"492.615962ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:13.431604Z","caller":"traceutil/trace.go:171","msg":"trace[1251508854] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"495.12513ms","start":"2026-02-25T06:10:12.936446Z","end":"2026-02-25T06:10:13.431571Z","steps":["trace[1251508854] 'process raft request' (duration: 494.937826ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:13.431694Z","caller":"traceutil/trace.go:171","msg":"trace[1775503352] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:714; }","duration":"159.184773ms","start":"2026-02-25T06:10:13.272462Z","end":"2026-02-25T06:10:13.431647Z","steps":["trace[1775503352] 'read index received' (duration: 156.353067ms)","trace[1775503352] 'applied index is now lower than readState.Index' (duration: 2.830856ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:13.431756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.936427Z","time spent":"495.250734ms","remote":"127.0.0.1:57586","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-25T06:10:13.431782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.302907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:10:13.431808Z","caller":"traceutil/trace.go:171","msg":"trace[458993587] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:694; }","duration":"159.384248ms","start":"2026-02-25T06:10:13.272415Z","end":"2026-02-25T06:10:13.431799Z","steps":["trace[458993587] 'agreement among raft nodes before linearized reading' (duration: 159.316736ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:13.431787Z","caller":"traceutil/trace.go:171","msg":"trace[49484156] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"493.544214ms","start":"2026-02-25T06:10:12.938212Z","end":"2026-02-25T06:10:13.431756Z","steps":["trace[49484156] 'process raft request' (duration: 493.292528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:13.431973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.938194Z","time spent":"493.690108ms","remote":"127.0.0.1:57586","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-25T06:10:13.431598Z","caller":"traceutil/trace.go:171","msg":"trace[628675228] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"493.459332ms","start":"2026-02-25T06:10:12.938117Z","end":"2026-02-25T06:10:13.431576Z","steps":["trace[628675228] 'process raft request' (duration: 493.357739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:13.432155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:12.938108Z","time spent":"494.000225ms","remote":"127.0.0.1:57586","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-25T06:10:15.068442Z","caller":"traceutil/trace.go:171","msg":"trace[1196468303] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"130.522964ms","start":"2026-02-25T06:10:14.937893Z","end":"2026-02-25T06:10:15.068416Z","steps":["trace[1196468303] 'process raft request' (duration: 59.719702ms)","trace[1196468303] 'compare' (duration: 70.635028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:15.933203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646665704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:10:16.438312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646665704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:10:16.938871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646665704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:10:17.069838Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.654116679s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T06:10:17.070102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.415602Z","time spent":"1.654495368s","remote":"127.0.0.1:57364","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T06:10:17.070095Z","caller":"traceutil/trace.go:171","msg":"trace[479436618] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"1.636923417s","start":"2026-02-25T06:10:15.43315Z","end":"2026-02-25T06:10:17.070074Z","steps":["trace[479436618] 'read index received' (duration: 1.636789144s)","trace[479436618] 'applied index is now lower than readState.Index' (duration: 132.783µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:17.0703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637141973s","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-25T06:10:17.070326Z","caller":"traceutil/trace.go:171","msg":"trace[179105060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:696; }","duration":"1.637226515s","start":"2026-02-25T06:10:15.433091Z","end":"2026-02-25T06:10:17.070317Z","steps":["trace[179105060] 'agreement among raft nodes before linearized reading' (duration: 1.637102202s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:17.07035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.433066Z","time spent":"1.637279096s","remote":"127.0.0.1:57586","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-25T06:10:17.070473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635060444s","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-25T06:10:17.070502Z","caller":"traceutil/trace.go:171","msg":"trace[881870985] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:696; }","duration":"1.635121785s","start":"2026-02-25T06:10:15.435369Z","end":"2026-02-25T06:10:17.070491Z","steps":["trace[881870985] 'agreement among raft nodes before linearized reading' (duration: 1.635000442s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:17.070524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.435355Z","time spent":"1.635165137s","remote":"127.0.0.1:57586","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-25T06:10:17.070638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346364509s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:17.070656Z","caller":"traceutil/trace.go:171","msg":"trace[958025799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:696; }","duration":"1.34641106s","start":"2026-02-25T06:10:15.724239Z","end":"2026-02-25T06:10:17.07065Z","steps":["trace[958025799] 'agreement among raft nodes before linearized reading' (duration: 1.346382069s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:17.070671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.724215Z","time spent":"1.346453091s","remote":"127.0.0.1:57314","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-25T06:10:17.070786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633714103s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:10:17.070802Z","caller":"traceutil/trace.go:171","msg":"trace[725064945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"1.633755793s","start":"2026-02-25T06:10:15.437042Z","end":"2026-02-25T06:10:17.070797Z","steps":["trace[725064945] 'agreement among raft nodes before linearized reading' (duration: 1.633705762s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:17.070818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.437023Z","time spent":"1.633790955s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:17.070919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633888817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:10:17.070935Z","caller":"traceutil/trace.go:171","msg":"trace[968149969] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"1.633951808s","start":"2026-02-25T06:10:15.436978Z","end":"2026-02-25T06:10:17.07093Z","steps":["trace[968149969] 'agreement among raft nodes before linearized reading' (duration: 1.633907067s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:17.070953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:15.436959Z","time spent":"1.633990349s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:10:18.869705Z","caller":"traceutil/trace.go:171","msg":"trace[4082896] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"146.315143ms","start":"2026-02-25T06:10:18.72337Z","end":"2026-02-25T06:10:18.869685Z","steps":["trace[4082896] 'read index received' (duration: 146.126369ms)","trace[4082896] 'applied index is now lower than readState.Index' (duration: 187.814µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:18.869802Z","caller":"traceutil/trace.go:171","msg":"trace[1071971233] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"151.802591ms","start":"2026-02-25T06:10:18.717992Z","end":"2026-02-25T06:10:18.869794Z","steps":["trace[1071971233] 'process raft request' (duration: 151.566125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:18.877332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.937511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:18.877415Z","caller":"traceutil/trace.go:171","msg":"trace[1433479696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"154.071044ms","start":"2026-02-25T06:10:18.723314Z","end":"2026-02-25T06:10:18.877385Z","steps":["trace[1433479696] 'agreement among raft nodes before linearized reading' (duration: 146.452146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:25.377446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.222888ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:25.377633Z","caller":"traceutil/trace.go:171","msg":"trace[431335349] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"127.463184ms","start":"2026-02-25T06:10:25.250147Z","end":"2026-02-25T06:10:25.37761Z","steps":["trace[431335349] 'process raft request' (duration: 127.392482ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:25.377624Z","caller":"traceutil/trace.go:171","msg":"trace[627485302] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"127.605907ms","start":"2026-02-25T06:10:25.249999Z","end":"2026-02-25T06:10:25.377605Z","steps":["trace[627485302] 'process raft request' (duration: 21.129613ms)","trace[627485302] 'compare' (duration: 106.069555ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:25.408161Z","caller":"traceutil/trace.go:171","msg":"trace[1927331370] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"157.196097ms","start":"2026-02-25T06:10:25.250942Z","end":"2026-02-25T06:10:25.408138Z","steps":["trace[1927331370] 'process raft request' (duration: 157.051444ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:25.408631Z","caller":"traceutil/trace.go:171","msg":"trace[2146578324] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"155.038867ms","start":"2026-02-25T06:10:25.253573Z","end":"2026-02-25T06:10:25.408612Z","steps":["trace[2146578324] 'process raft request' (duration: 154.527265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:37.57737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.900487ms","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-25T06:10:37.577468Z","caller":"traceutil/trace.go:171","msg":"trace[1314210535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:760; }","duration":"117.03654ms","start":"2026-02-25T06:10:37.46041Z","end":"2026-02-25T06:10:37.577446Z","steps":["trace[1314210535] 'range keys from in-memory index tree' (duration: 116.653522ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:39.943443Z","caller":"traceutil/trace.go:171","msg":"trace[975241667] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"309.420049ms","start":"2026-02-25T06:10:39.633999Z","end":"2026-02-25T06:10:39.943419Z","steps":["trace[975241667] 'read index received' (duration: 309.265635ms)","trace[975241667] 'applied index is now lower than readState.Index' (duration: 153.464µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:39.943584Z","caller":"traceutil/trace.go:171","msg":"trace[628900386] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"562.127554ms","start":"2026-02-25T06:10:39.381444Z","end":"2026-02-25T06:10:39.943571Z","steps":["trace[628900386] 'process raft request' (duration: 561.847288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:39.943712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:39.381417Z","time spent":"562.198795ms","remote":"127.0.0.1:57586","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-25T06:10:39.943924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.574202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T06:10:39.943956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.291056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:39.943997Z","caller":"traceutil/trace.go:171","msg":"trace[1597389156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:766; }","duration":"219.356287ms","start":"2026-02-25T06:10:39.724629Z","end":"2026-02-25T06:10:39.943986Z","steps":["trace[1597389156] 'agreement among raft nodes before linearized reading' (duration: 219.293595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:39.944082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.665434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:10:39.944115Z","caller":"traceutil/trace.go:171","msg":"trace[1660173648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"297.711405ms","start":"2026-02-25T06:10:39.646396Z","end":"2026-02-25T06:10:39.944107Z","steps":["trace[1660173648] 'agreement among raft nodes before linearized reading' (duration: 297.629253ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:39.944Z","caller":"traceutil/trace.go:171","msg":"trace[955006676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:766; }","duration":"297.689035ms","start":"2026-02-25T06:10:39.646294Z","end":"2026-02-25T06:10:39.943983Z","steps":["trace[955006676] 'agreement among raft nodes before linearized reading' (duration: 297.462029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:39.944292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.298069ms","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-25T06:10:39.944311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.733982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:39.944408Z","caller":"traceutil/trace.go:171","msg":"trace[1817017034] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:766; }","duration":"146.847305ms","start":"2026-02-25T06:10:39.797542Z","end":"2026-02-25T06:10:39.944389Z","steps":["trace[1817017034] 'agreement among raft nodes before linearized reading' (duration: 146.702282ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:39.944351Z","caller":"traceutil/trace.go:171","msg":"trace[707764617] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:766; }","duration":"310.397061ms","start":"2026-02-25T06:10:39.633944Z","end":"2026-02-25T06:10:39.944341Z","steps":["trace[707764617] 'agreement among raft nodes before linearized reading' (duration: 310.233657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:39.944514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:39.633916Z","time spent":"310.584666ms","remote":"127.0.0.1:57586","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-25T06:10:39.94457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.211877ms","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-25T06:10:39.944646Z","caller":"traceutil/trace.go:171","msg":"trace[576571352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"298.315149ms","start":"2026-02-25T06:10:39.64631Z","end":"2026-02-25T06:10:39.944625Z","steps":["trace[576571352] 'agreement among raft nodes before linearized reading' (duration: 297.761906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:40.070388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.509924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:40.070632Z","caller":"traceutil/trace.go:171","msg":"trace[1135263990] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"121.911623ms","start":"2026-02-25T06:10:39.948692Z","end":"2026-02-25T06:10:40.070604Z","steps":["trace[1135263990] 'process raft request' (duration: 21.108192ms)","trace[1135263990] 'compare' (duration: 100.415122ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:40.070683Z","caller":"traceutil/trace.go:171","msg":"trace[1252102432] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"121.35834ms","start":"2026-02-25T06:10:39.949307Z","end":"2026-02-25T06:10:40.070665Z","steps":["trace[1252102432] 'process raft request' (duration: 121.177316ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:40.070868Z","caller":"traceutil/trace.go:171","msg":"trace[19490668] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"121.32199ms","start":"2026-02-25T06:10:39.949532Z","end":"2026-02-25T06:10:40.070854Z","steps":["trace[19490668] 'process raft request' (duration: 121.068614ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:40.070993Z","caller":"traceutil/trace.go:171","msg":"trace[1533791529] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"121.424592ms","start":"2026-02-25T06:10:39.949559Z","end":"2026-02-25T06:10:40.070984Z","steps":["trace[1533791529] 'process raft request' (duration: 121.232307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:40.736477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.071296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:10:40.736828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.426478ms","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-25T06:10:40.736912Z","caller":"traceutil/trace.go:171","msg":"trace[1449685165] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:770; }","duration":"507.55988ms","start":"2026-02-25T06:10:40.229333Z","end":"2026-02-25T06:10:40.736893Z","steps":["trace[1449685165] 'count revisions from in-memory index tree' (duration: 507.364656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:40.736954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:40.229322Z","time spent":"507.622083ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":81,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:10:40.736965Z","caller":"traceutil/trace.go:171","msg":"trace[586914863] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:770; }","duration":"509.542607ms","start":"2026-02-25T06:10:40.227318Z","end":"2026-02-25T06:10:40.736861Z","steps":["trace[586914863] 'count revisions from in-memory index tree' (duration: 508.937383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:40.737306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:40.227298Z","time spent":"509.983647ms","remote":"127.0.0.1:57566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:10:40.937609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.790803ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-25T06:10:42.190381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.661945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:10:42.190475Z","caller":"traceutil/trace.go:171","msg":"trace[1993880941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:771; }","duration":"108.768247ms","start":"2026-02-25T06:10:42.081686Z","end":"2026-02-25T06:10:42.190454Z","steps":["trace[1993880941] 'range keys from in-memory index tree' (duration: 108.499381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.190506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.653889ms","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-25T06:10:42.190587Z","caller":"traceutil/trace.go:171","msg":"trace[550723413] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:771; }","duration":"112.784552ms","start":"2026-02-25T06:10:42.077782Z","end":"2026-02-25T06:10:42.190566Z","steps":["trace[550723413] 'range keys from in-memory index tree' (duration: 112.508035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.19055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.72001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-25T06:10:42.190506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.391956ms","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-25T06:10:42.190666Z","caller":"traceutil/trace.go:171","msg":"trace[945217255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:771; }","duration":"112.899345ms","start":"2026-02-25T06:10:42.07774Z","end":"2026-02-25T06:10:42.19064Z","steps":["trace[945217255] 'range keys from in-memory index tree' (duration: 112.489745ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:42.190684Z","caller":"traceutil/trace.go:171","msg":"trace[623772399] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"113.618191ms","start":"2026-02-25T06:10:42.077042Z","end":"2026-02-25T06:10:42.190661Z","steps":["trace[623772399] 'range keys from in-memory index tree' (duration: 113.182151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:42.455251Z","caller":"traceutil/trace.go:171","msg":"trace[2093539385] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"259.73424ms","start":"2026-02-25T06:10:42.195483Z","end":"2026-02-25T06:10:42.455217Z","steps":["trace[2093539385] 'process raft request' (duration: 259.528245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.933349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.323872ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:42.933579Z","caller":"traceutil/trace.go:171","msg":"trace[512084700] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"736.720457ms","start":"2026-02-25T06:10:42.196835Z","end":"2026-02-25T06:10:42.933556Z","steps":["trace[512084700] 'process raft request' (duration: 341.095458ms)","trace[512084700] 'compare' (duration: 395.220189ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:42.933598Z","caller":"traceutil/trace.go:171","msg":"trace[1402427394] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"736.714747ms","start":"2026-02-25T06:10:42.196862Z","end":"2026-02-25T06:10:42.933577Z","steps":["trace[1402427394] 'process raft request' (duration: 736.605745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.933682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.196849Z","time spent":"736.794569ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:42.9337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.196806Z","time spent":"736.82956ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:10:42.933701Z","caller":"traceutil/trace.go:171","msg":"trace[2114068040] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:803; }","duration":"108.520272ms","start":"2026-02-25T06:10:42.825151Z","end":"2026-02-25T06:10:42.933671Z","steps":["trace[2114068040] 'read index received' (duration: 44.291µs)","trace[2114068040] 'applied index is now lower than readState.Index' (duration: 108.473321ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:42.933718Z","caller":"traceutil/trace.go:171","msg":"trace[1335375612] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"736.768068ms","start":"2026-02-25T06:10:42.196932Z","end":"2026-02-25T06:10:42.9337Z","steps":["trace[1335375612] 'process raft request' (duration: 736.588574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.933817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.670285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:10:42.933846Z","caller":"traceutil/trace.go:171","msg":"trace[214691646] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:775; }","duration":"108.699986ms","start":"2026-02-25T06:10:42.82514Z","end":"2026-02-25T06:10:42.93384Z","steps":["trace[214691646] 'agreement among raft nodes before linearized reading' (duration: 108.652324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:42.933852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:42.196921Z","time spent":"736.85753ms","remote":"127.0.0.1:57586","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-25T06:10:46.593279Z","caller":"traceutil/trace.go:171","msg":"trace[1517627102] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"122.59422ms","start":"2026-02-25T06:10:46.470666Z","end":"2026-02-25T06:10:46.59326Z","steps":["trace[1517627102] 'process raft request' (duration: 122.491268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:56.680751Z","caller":"traceutil/trace.go:171","msg":"trace[2130474666] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"572.165818ms","start":"2026-02-25T06:10:56.108554Z","end":"2026-02-25T06:10:56.68072Z","steps":["trace[2130474666] 'process raft request' (duration: 571.907462ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:56.680928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.108544Z","time spent":"572.29794ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:10:58.693752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.252124999s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:10:58.69387Z","caller":"traceutil/trace.go:171","msg":"trace[1064267375] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"1.680725108s","start":"2026-02-25T06:10:57.013129Z","end":"2026-02-25T06:10:58.693854Z","steps":["trace[1064267375] 'read index received' (duration: 428.453055ms)","trace[1064267375] 'applied index is now lower than readState.Index' (duration: 1.252271033s)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:10:58.693955Z","caller":"traceutil/trace.go:171","msg":"trace[837978] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"2.009101089s","start":"2026-02-25T06:10:56.684843Z","end":"2026-02-25T06:10:58.693944Z","steps":["trace[837978] 'process raft request' (duration: 756.698693ms)","trace[837978] 'compare' (duration: 1.251984276s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:58.694005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:56.684821Z","time spent":"2.009157191s","remote":"127.0.0.1:57586","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-25T06:10:58.694176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.333809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:10:58.694224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.681097477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T06:10:58.694258Z","caller":"traceutil/trace.go:171","msg":"trace[320989060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.681173348s","start":"2026-02-25T06:10:57.013074Z","end":"2026-02-25T06:10:58.694248Z","steps":["trace[320989060] 'agreement among raft nodes before linearized reading' (duration: 1.681046795s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:58.694254Z","caller":"traceutil/trace.go:171","msg":"trace[1849384418] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:864; }","duration":"511.453592ms","start":"2026-02-25T06:10:58.182782Z","end":"2026-02-25T06:10:58.694236Z","steps":["trace[1849384418] 'agreement among raft nodes before linearized reading' (duration: 511.326629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.694283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.013054Z","time spent":"1.68122365s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:10:58.694261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.151581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:10:58.694295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:58.18276Z","time spent":"511.523793ms","remote":"127.0.0.1:57716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:10:58.694357Z","caller":"traceutil/trace.go:171","msg":"trace[724846446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:864; }","duration":"970.283604ms","start":"2026-02-25T06:10:57.724052Z","end":"2026-02-25T06:10:58.694336Z","steps":["trace[724846446] 'agreement among raft nodes before linearized reading' (duration: 970.144071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.694405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.724035Z","time spent":"970.360386ms","remote":"127.0.0.1:57314","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-25T06:10:58.694413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.878088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:10:58.694421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.645855665s","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-25T06:10:58.694453Z","caller":"traceutil/trace.go:171","msg":"trace[1533845213] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:864; }","duration":"570.95741ms","start":"2026-02-25T06:10:58.123482Z","end":"2026-02-25T06:10:58.69444Z","steps":["trace[1533845213] 'agreement among raft nodes before linearized reading' (duration: 570.880478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.694504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:58.12346Z","time spent":"571.030661ms","remote":"127.0.0.1:57440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:10:58.694505Z","caller":"traceutil/trace.go:171","msg":"trace[487626055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"1.645996218s","start":"2026-02-25T06:10:57.048489Z","end":"2026-02-25T06:10:58.694485Z","steps":["trace[487626055] 'agreement among raft nodes before linearized reading' (duration: 1.645759992s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.694555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.048466Z","time spent":"1.64607937s","remote":"127.0.0.1:57586","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-25T06:10:58.69422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.650693898s","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-25T06:10:58.694687Z","caller":"traceutil/trace.go:171","msg":"trace[1617817217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:864; }","duration":"1.651183629s","start":"2026-02-25T06:10:57.043478Z","end":"2026-02-25T06:10:58.694662Z","steps":["trace[1617817217] 'agreement among raft nodes before linearized reading' (duration: 1.650575225s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:58.694736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:57.043465Z","time spent":"1.651256071s","remote":"127.0.0.1:57586","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-25T06:11:14.880522Z","caller":"traceutil/trace.go:171","msg":"trace[39525749] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"107.853901ms","start":"2026-02-25T06:11:14.772642Z","end":"2026-02-25T06:11:14.880495Z","steps":["trace[39525749] 'process raft request' (duration: 107.716498ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:14.977971Z","caller":"traceutil/trace.go:171","msg":"trace[955290586] linearizableReadLoop","detail":"{readStateIndex:949; appliedIndex:946; }","duration":"185.667455ms","start":"2026-02-25T06:11:14.792281Z","end":"2026-02-25T06:11:14.977948Z","steps":["trace[955290586] 'read index received' (duration: 88.134994ms)","trace[955290586] 'applied index is now lower than readState.Index' (duration: 97.531331ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:14.978105Z","caller":"traceutil/trace.go:171","msg":"trace[411519336] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"204.165322ms","start":"2026-02-25T06:11:14.773924Z","end":"2026-02-25T06:11:14.978089Z","steps":["trace[411519336] 'process raft request' (duration: 203.976768ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:14.978109Z","caller":"traceutil/trace.go:171","msg":"trace[1318561174] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"205.129386ms","start":"2026-02-25T06:11:14.772942Z","end":"2026-02-25T06:11:14.978071Z","steps":["trace[1318561174] 'process raft request' (duration: 154.528821ms)","trace[1318561174] 'compare' (duration: 50.295868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:14.978371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.065045ms","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-25T06:11:14.978418Z","caller":"traceutil/trace.go:171","msg":"trace[1224586888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:910; }","duration":"186.155397ms","start":"2026-02-25T06:11:14.792249Z","end":"2026-02-25T06:11:14.978405Z","steps":["trace[1224586888] 'agreement among raft nodes before linearized reading' (duration: 185.987503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.978511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.086018ms","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-25T06:11:14.978595Z","caller":"traceutil/trace.go:171","msg":"trace[1406531115] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:910; }","duration":"106.217541ms","start":"2026-02-25T06:11:14.872359Z","end":"2026-02-25T06:11:14.978577Z","steps":["trace[1406531115] 'agreement among raft nodes before linearized reading' (duration: 106.004376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:15.558361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.575109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:15.558499Z","caller":"traceutil/trace.go:171","msg":"trace[1446472447] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"575.984563ms","start":"2026-02-25T06:11:14.982498Z","end":"2026-02-25T06:11:15.558483Z","steps":["trace[1446472447] 'process raft request' (duration: 103.183188ms)","trace[1446472447] 'compare' (duration: 472.445696ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:15.558552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.982478Z","time spent":"576.051094ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:15.558893Z","caller":"traceutil/trace.go:171","msg":"trace[1833314632] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"574.730433ms","start":"2026-02-25T06:11:14.984149Z","end":"2026-02-25T06:11:15.558879Z","steps":["trace[1833314632] 'process raft request' (duration: 574.289692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:15.558983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.984118Z","time spent":"574.813415ms","remote":"127.0.0.1:57586","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-25T06:11:15.558817Z","caller":"traceutil/trace.go:171","msg":"trace[2102196273] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:949; }","duration":"305.221519ms","start":"2026-02-25T06:11:15.253574Z","end":"2026-02-25T06:11:15.558796Z","steps":["trace[2102196273] 'read index received' (duration: 37.101µs)","trace[2102196273] 'applied index is now lower than readState.Index' (duration: 305.183518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:15.55913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.545766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:11:15.55918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.480348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:11:15.559227Z","caller":"traceutil/trace.go:171","msg":"trace[1053325338] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:912; }","duration":"182.551799ms","start":"2026-02-25T06:11:15.376655Z","end":"2026-02-25T06:11:15.559207Z","steps":["trace[1053325338] 'agreement among raft nodes before linearized reading' (duration: 182.468327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:15.559292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.981921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:11:15.559332Z","caller":"traceutil/trace.go:171","msg":"trace[1532706451] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:912; }","duration":"143.055562ms","start":"2026-02-25T06:11:15.416266Z","end":"2026-02-25T06:11:15.559322Z","steps":["trace[1532706451] 'agreement among raft nodes before linearized reading' (duration: 142.933049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:15.559187Z","caller":"traceutil/trace.go:171","msg":"trace[302613734] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:912; }","duration":"305.657599ms","start":"2026-02-25T06:11:15.253517Z","end":"2026-02-25T06:11:15.559175Z","steps":["trace[302613734] 'agreement among raft nodes before linearized reading' (duration: 305.511405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:15.559441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:15.253494Z","time spent":"305.938036ms","remote":"127.0.0.1:54164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:15.865515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.451265ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-25T06:11:15.86566Z","caller":"traceutil/trace.go:171","msg":"trace[2064293106] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:951; }","duration":"132.570429ms","start":"2026-02-25T06:11:15.733066Z","end":"2026-02-25T06:11:15.865637Z","steps":["trace[2064293106] 'read index received' (duration: 31.761µs)","trace[2064293106] 'applied index is now lower than readState.Index' (duration: 132.536738ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:15.86567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:15.560237Z","time spent":"305.425062ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T06:11:15.865751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.675481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:15.86579Z","caller":"traceutil/trace.go:171","msg":"trace[1411996165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:912; }","duration":"132.749133ms","start":"2026-02-25T06:11:15.733027Z","end":"2026-02-25T06:11:15.865777Z","steps":["trace[1411996165] 'agreement among raft nodes before linearized reading' (duration: 132.670551ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:17.125899Z","caller":"traceutil/trace.go:171","msg":"trace[1136795481] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"473.711857ms","start":"2026-02-25T06:11:16.652159Z","end":"2026-02-25T06:11:17.125871Z","steps":["trace[1136795481] 'read index received' (duration: 473.495902ms)","trace[1136795481] 'applied index is now lower than readState.Index' (duration: 214.845µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:17.126256Z","caller":"traceutil/trace.go:171","msg":"trace[264821517] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"913.3288ms","start":"2026-02-25T06:11:16.212915Z","end":"2026-02-25T06:11:17.126244Z","steps":["trace[264821517] 'process raft request' (duration: 912.800577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.126386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:16.212886Z","time spent":"913.432192ms","remote":"127.0.0.1:57586","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-25T06:11:17.126572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.421885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:17.126603Z","caller":"traceutil/trace.go:171","msg":"trace[2013097663] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:914; }","duration":"474.498747ms","start":"2026-02-25T06:11:16.652095Z","end":"2026-02-25T06:11:17.126594Z","steps":["trace[2013097663] 'agreement among raft nodes before linearized reading' (duration: 474.448475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.126632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:16.652072Z","time spent":"474.552298ms","remote":"127.0.0.1:57622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:17.126771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.551172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:11:17.126792Z","caller":"traceutil/trace.go:171","msg":"trace[103362842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"242.574252ms","start":"2026-02-25T06:11:16.884212Z","end":"2026-02-25T06:11:17.126786Z","steps":["trace[103362842] 'agreement among raft nodes before linearized reading' (duration: 242.501091ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.126888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.749005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:11:17.126901Z","caller":"traceutil/trace.go:171","msg":"trace[752182261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:914; }","duration":"144.774885ms","start":"2026-02-25T06:11:16.982122Z","end":"2026-02-25T06:11:17.126897Z","steps":["trace[752182261] 'agreement among raft nodes before linearized reading' (duration: 144.735454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.126978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.40107ms","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-25T06:11:17.12699Z","caller":"traceutil/trace.go:171","msg":"trace[14483150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:914; }","duration":"145.442601ms","start":"2026-02-25T06:11:16.981543Z","end":"2026-02-25T06:11:17.126986Z","steps":["trace[14483150] 'agreement among raft nodes before linearized reading' (duration: 145.40683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.809198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.816767ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:17.80936Z","caller":"traceutil/trace.go:171","msg":"trace[1102633832] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"680.115884ms","start":"2026-02-25T06:11:17.129228Z","end":"2026-02-25T06:11:17.809343Z","steps":["trace[1102633832] 'process raft request' (duration: 133.079972ms)","trace[1102633832] 'compare' (duration: 546.648453ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:17.809373Z","caller":"traceutil/trace.go:171","msg":"trace[1695824885] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:954; }","duration":"246.816275ms","start":"2026-02-25T06:11:17.562545Z","end":"2026-02-25T06:11:17.809361Z","steps":["trace[1695824885] 'read index received' (duration: 41.211µs)","trace[1695824885] 'applied index is now lower than readState.Index' (duration: 246.774254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:17.809425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:17.129205Z","time spent":"680.193695ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:17.809515Z","caller":"traceutil/trace.go:171","msg":"trace[246066611] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"680.122804ms","start":"2026-02-25T06:11:17.129382Z","end":"2026-02-25T06:11:17.809505Z","steps":["trace[246066611] 'process raft request' (duration: 679.907409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.809549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.000879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-25T06:11:17.809559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:17.129376Z","time spent":"680.160665ms","remote":"127.0.0.1:57586","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-25T06:11:17.809587Z","caller":"traceutil/trace.go:171","msg":"trace[1069379742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"247.078011ms","start":"2026-02-25T06:11:17.562499Z","end":"2026-02-25T06:11:17.809577Z","steps":["trace[1069379742] 'agreement among raft nodes before linearized reading' (duration: 246.972058ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:17.809664Z","caller":"traceutil/trace.go:171","msg":"trace[840945494] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"680.199906ms","start":"2026-02-25T06:11:17.129457Z","end":"2026-02-25T06:11:17.809657Z","steps":["trace[840945494] 'process raft request' (duration: 679.873918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:17.809713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:17.12944Z","time spent":"680.248957ms","remote":"127.0.0.1:57586","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-25T06:11:17.809665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.732089ms","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-25T06:11:17.809816Z","caller":"traceutil/trace.go:171","msg":"trace[1943948164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:917; }","duration":"245.920443ms","start":"2026-02-25T06:11:17.563888Z","end":"2026-02-25T06:11:17.809808Z","steps":["trace[1943948164] 'agreement among raft nodes before linearized reading' (duration: 245.732209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:18.06546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.844156ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:18.065636Z","caller":"traceutil/trace.go:171","msg":"trace[1569276275] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"251.786215ms","start":"2026-02-25T06:11:17.813831Z","end":"2026-02-25T06:11:18.065618Z","steps":["trace[1569276275] 'process raft request' (duration: 99.707724ms)","trace[1569276275] 'compare' (duration: 151.751863ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:18.06577Z","caller":"traceutil/trace.go:171","msg":"trace[1977381980] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"251.894738ms","start":"2026-02-25T06:11:17.813848Z","end":"2026-02-25T06:11:18.065743Z","steps":["trace[1977381980] 'process raft request' (duration: 251.725304ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:19.557353Z","caller":"traceutil/trace.go:171","msg":"trace[1854933708] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"337.254233ms","start":"2026-02-25T06:11:19.220074Z","end":"2026-02-25T06:11:19.557329Z","steps":["trace[1854933708] 'process raft request' (duration: 337.108469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:19.557506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.220048Z","time spent":"337.379856ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:20.313529Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646666289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:20.475042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.939307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:20.475131Z","caller":"traceutil/trace.go:171","msg":"trace[1845830101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:920; }","duration":"753.08216ms","start":"2026-02-25T06:11:19.722031Z","end":"2026-02-25T06:11:20.475113Z","steps":["trace[1845830101] 'range keys from in-memory index tree' (duration: 752.860645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:20.475178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.722012Z","time spent":"753.150442ms","remote":"127.0.0.1:57322","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-25T06:11:20.814632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646666289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:11:20.947892Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.178363096s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:11:20.948173Z","caller":"traceutil/trace.go:171","msg":"trace[769647943] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"1.178693054s","start":"2026-02-25T06:11:19.769464Z","end":"2026-02-25T06:11:20.948157Z","steps":["trace[769647943] 'process raft request' (duration: 1.178571481s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:20.948276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.769444Z","time spent":"1.178786606s","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":814,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:11:22.543172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.714854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T06:11:22.543382Z","caller":"traceutil/trace.go:171","msg":"trace[1077527096] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:960; }","duration":"2.730210082s","start":"2026-02-25T06:11:19.813145Z","end":"2026-02-25T06:11:22.543355Z","steps":["trace[1077527096] 'read index received' (duration: 1.134913654s)","trace[1077527096] 'applied index is now lower than readState.Index' (duration: 1.595294598s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:22.543696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.73053344s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:11:22.543779Z","caller":"traceutil/trace.go:171","msg":"trace[1941470886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"2.730673873s","start":"2026-02-25T06:11:19.81309Z","end":"2026-02-25T06:11:22.543764Z","steps":["trace[1941470886] 'agreement among raft nodes before linearized reading' (duration: 2.730462368s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.543808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.813061Z","time spent":"2.730741685s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:22.544014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.701330672s","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-25T06:11:22.544041Z","caller":"traceutil/trace.go:171","msg":"trace[1867013524] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:921; }","duration":"2.701387984s","start":"2026-02-25T06:11:19.842645Z","end":"2026-02-25T06:11:22.544033Z","steps":["trace[1867013524] 'agreement among raft nodes before linearized reading' (duration: 2.701344713s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.544064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.84263Z","time spent":"2.701428515s","remote":"127.0.0.1:57550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:11:22.544167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.474841179s","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-25T06:11:22.544252Z","caller":"traceutil/trace.go:171","msg":"trace[1824977043] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:921; }","duration":"2.474953723s","start":"2026-02-25T06:11:20.069285Z","end":"2026-02-25T06:11:22.544238Z","steps":["trace[1824977043] 'agreement among raft nodes before linearized reading' (duration: 2.474664496s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.544253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.819953907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:11:22.544201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.475699931s","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-25T06:11:22.544353Z","caller":"traceutil/trace.go:171","msg":"trace[1976307364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"2.475881555s","start":"2026-02-25T06:11:20.068455Z","end":"2026-02-25T06:11:22.544337Z","steps":["trace[1976307364] 'agreement among raft nodes before linearized reading' (duration: 2.475700701s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.54439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.068432Z","time spent":"2.475950466s","remote":"127.0.0.1:57586","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-25T06:11:22.54436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.069279Z","time spent":"2.475030574s","remote":"127.0.0.1:57586","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-25T06:11:22.544569Z","caller":"traceutil/trace.go:171","msg":"trace[1371595531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"1.82006218s","start":"2026-02-25T06:11:20.724255Z","end":"2026-02-25T06:11:22.544317Z","steps":["trace[1371595531] 'agreement among raft nodes before linearized reading' (duration: 1.819892245s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.544594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:20.72424Z","time spent":"1.820349796s","remote":"127.0.0.1:57314","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-25T06:11:22.544911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.730766006s","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-25T06:11:22.545021Z","caller":"traceutil/trace.go:171","msg":"trace[768637160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"2.730872748s","start":"2026-02-25T06:11:19.814107Z","end":"2026-02-25T06:11:22.544979Z","steps":["trace[768637160] 'agreement among raft nodes before linearized reading' (duration: 2.729814722s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.544947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.729796372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:11:22.545192Z","caller":"traceutil/trace.go:171","msg":"trace[223182168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"2.730042808s","start":"2026-02-25T06:11:19.8151Z","end":"2026-02-25T06:11:22.545143Z","steps":["trace[223182168] 'agreement among raft nodes before linearized reading' (duration: 2.729077584s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:22.545095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.814089Z","time spent":"2.730994251s","remote":"127.0.0.1:57586","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-25T06:11:22.545267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:19.815095Z","time spent":"2.73015852s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:22.706067Z","caller":"traceutil/trace.go:171","msg":"trace[1211918279] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"157.839411ms","start":"2026-02-25T06:11:22.548205Z","end":"2026-02-25T06:11:22.706045Z","steps":["trace[1211918279] 'process raft request' (duration: 157.699487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:23.146552Z","caller":"traceutil/trace.go:171","msg":"trace[599570437] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:962; }","duration":"594.275715ms","start":"2026-02-25T06:11:22.552254Z","end":"2026-02-25T06:11:23.14653Z","steps":["trace[599570437] 'read index received' (duration: 153.741721ms)","trace[599570437] 'applied index is now lower than readState.Index' (duration: 440.533074ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:23.146648Z","caller":"traceutil/trace.go:171","msg":"trace[95242215] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"597.197636ms","start":"2026-02-25T06:11:22.549415Z","end":"2026-02-25T06:11:23.146612Z","steps":["trace[95242215] 'process raft request' (duration: 597.016281ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:23.146718Z","caller":"traceutil/trace.go:171","msg":"trace[1288833351] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"598.309732ms","start":"2026-02-25T06:11:22.548368Z","end":"2026-02-25T06:11:23.146677Z","steps":["trace[1288833351] 'process raft request' (duration: 597.920253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.14677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.500861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:11:23.146859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.462541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:23.14689Z","caller":"traceutil/trace.go:171","msg":"trace[432410883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:926; }","duration":"594.645714ms","start":"2026-02-25T06:11:22.552214Z","end":"2026-02-25T06:11:23.146859Z","steps":["trace[432410883] 'agreement among raft nodes before linearized reading' (duration: 594.48343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.146877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.549398Z","time spent":"597.34031ms","remote":"127.0.0.1:57586","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-25T06:11:23.14689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.548337Z","time spent":"598.438706ms","remote":"127.0.0.1:57586","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-25T06:11:23.146694Z","caller":"traceutil/trace.go:171","msg":"trace[89906389] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"596.701763ms","start":"2026-02-25T06:11:22.549979Z","end":"2026-02-25T06:11:23.146681Z","steps":["trace[89906389] 'process raft request' (duration: 596.485948ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.146974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.552163Z","time spent":"594.794678ms","remote":"127.0.0.1:57314","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-25T06:11:23.146945Z","caller":"traceutil/trace.go:171","msg":"trace[1085474852] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:926; }","duration":"102.622944ms","start":"2026-02-25T06:11:23.044293Z","end":"2026-02-25T06:11:23.146916Z","steps":["trace[1085474852] 'agreement among raft nodes before linearized reading' (duration: 102.430229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.147113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.549968Z","time spent":"597.050913ms","remote":"127.0.0.1:57586","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-25T06:11:23.147262Z","caller":"traceutil/trace.go:171","msg":"trace[1843378762] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"596.105059ms","start":"2026-02-25T06:11:22.551136Z","end":"2026-02-25T06:11:23.147241Z","steps":["trace[1843378762] 'process raft request' (duration: 595.357901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:23.147369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:22.551115Z","time spent":"596.202312ms","remote":"127.0.0.1:57586","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-25T06:11:24.991122Z","caller":"traceutil/trace.go:171","msg":"trace[277043442] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"279.169488ms","start":"2026-02-25T06:11:24.71193Z","end":"2026-02-25T06:11:24.9911Z","steps":["trace[277043442] 'process raft request' (duration: 279.019634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.796791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.499641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:11:25.796856Z","caller":"traceutil/trace.go:171","msg":"trace[360151637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:927; }","duration":"644.597364ms","start":"2026-02-25T06:11:25.152244Z","end":"2026-02-25T06:11:25.796842Z","steps":["trace[360151637] 'range keys from in-memory index tree' (duration: 644.365708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.79689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.152238Z","time spent":"644.645345ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:11:25.796953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.318626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:11:25.797025Z","caller":"traceutil/trace.go:171","msg":"trace[1146707889] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:927; }","duration":"380.391558ms","start":"2026-02-25T06:11:25.416587Z","end":"2026-02-25T06:11:25.796979Z","steps":["trace[1146707889] 'range keys from in-memory index tree' (duration: 380.212484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.797062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.416566Z","time spent":"380.48797ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-02-25T06:11:25.797181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.29431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:11:25.797208Z","caller":"traceutil/trace.go:171","msg":"trace[1334654096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:927; }","duration":"645.323011ms","start":"2026-02-25T06:11:25.151878Z","end":"2026-02-25T06:11:25.797201Z","steps":["trace[1334654096] 'range keys from in-memory index tree' (duration: 645.220369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.797195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.346051ms","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-25T06:11:25.797226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.151834Z","time spent":"645.387742ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T06:11:25.797246Z","caller":"traceutil/trace.go:171","msg":"trace[1753003343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:927; }","duration":"645.426464ms","start":"2026-02-25T06:11:25.151806Z","end":"2026-02-25T06:11:25.797232Z","steps":["trace[1753003343] 'range keys from in-memory index tree' (duration: 645.236399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.796864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.191134ms","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-25T06:11:25.79732Z","caller":"traceutil/trace.go:171","msg":"trace[1841578290] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"644.695796ms","start":"2026-02-25T06:11:25.152617Z","end":"2026-02-25T06:11:25.797313Z","steps":["trace[1841578290] 'range keys from in-memory index tree' (duration: 644.05458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:25.797344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.152602Z","time spent":"644.736477ms","remote":"127.0.0.1:57586","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-25T06:11:25.797288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.151791Z","time spent":"645.488335ms","remote":"127.0.0.1:57586","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-25T06:11:26.574362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646666314,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:11:26.695787Z","caller":"traceutil/trace.go:171","msg":"trace[1130430844] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"891.813288ms","start":"2026-02-25T06:11:25.802909Z","end":"2026-02-25T06:11:26.694722Z","steps":["trace[1130430844] 'process raft request' (duration: 891.178203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:26.695953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.802887Z","time spent":"892.981606ms","remote":"127.0.0.1:57586","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-25T06:11:26.69606Z","caller":"traceutil/trace.go:171","msg":"trace[1035853029] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"892.995116ms","start":"2026-02-25T06:11:25.80305Z","end":"2026-02-25T06:11:26.696045Z","steps":["trace[1035853029] 'process raft request' (duration: 891.51494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:26.696164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.803042Z","time spent":"893.062778ms","remote":"127.0.0.1:57586","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-25T06:11:26.696578Z","caller":"traceutil/trace.go:171","msg":"trace[1570831284] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"891.270524ms","start":"2026-02-25T06:11:25.805283Z","end":"2026-02-25T06:11:26.696553Z","steps":["trace[1570831284] 'process raft request' (duration: 891.116681ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:26.696583Z","caller":"traceutil/trace.go:171","msg":"trace[651006336] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"892.32111ms","start":"2026-02-25T06:11:25.804227Z","end":"2026-02-25T06:11:26.696548Z","steps":["trace[651006336] 'process raft request' (duration: 891.756657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:26.696687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.80525Z","time spent":"891.390857ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:26.696724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.804218Z","time spent":"892.435033ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:26.719195Z","caller":"traceutil/trace.go:171","msg":"trace[539936059] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:969; }","duration":"645.524045ms","start":"2026-02-25T06:11:26.073654Z","end":"2026-02-25T06:11:26.719178Z","steps":["trace[539936059] 'read index received' (duration: 620.633023ms)","trace[539936059] 'applied index is now lower than readState.Index' (duration: 24.890132ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:26.719415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.763011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-02-25T06:11:26.719455Z","caller":"traceutil/trace.go:171","msg":"trace[922108754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:933; }","duration":"645.847313ms","start":"2026-02-25T06:11:26.073596Z","end":"2026-02-25T06:11:26.719444Z","steps":["trace[922108754] 'agreement among raft nodes before linearized reading' (duration: 645.644618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:26.71949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:26.073573Z","time spent":"645.909634ms","remote":"127.0.0.1:57524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4764,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T06:11:26.7195Z","caller":"traceutil/trace.go:171","msg":"trace[1260257398] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"867.653492ms","start":"2026-02-25T06:11:25.851798Z","end":"2026-02-25T06:11:26.719452Z","steps":["trace[1260257398] 'process raft request' (duration: 867.266403ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:26.719656Z","caller":"traceutil/trace.go:171","msg":"trace[419455484] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"755.727473ms","start":"2026-02-25T06:11:25.963918Z","end":"2026-02-25T06:11:26.719646Z","steps":["trace[419455484] 'process raft request' (duration: 755.219121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:26.719711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.96389Z","time spent":"755.793065ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":750,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:11:26.719747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:25.851762Z","time spent":"867.878638ms","remote":"127.0.0.1:57364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:27.13529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.284156ms","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-25T06:11:27.135388Z","caller":"traceutil/trace.go:171","msg":"trace[1568136958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"140.44099ms","start":"2026-02-25T06:11:26.994929Z","end":"2026-02-25T06:11:27.13537Z","steps":["trace[1568136958] 'range keys from in-memory index tree' (duration: 140.082542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.882476Z","caller":"traceutil/trace.go:171","msg":"trace[277290040] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"168.247172ms","start":"2026-02-25T06:11:30.714204Z","end":"2026-02-25T06:11:30.882451Z","steps":["trace[277290040] 'process raft request' (duration: 168.082278ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.882496Z","caller":"traceutil/trace.go:171","msg":"trace[510070116] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"166.993071ms","start":"2026-02-25T06:11:30.715455Z","end":"2026-02-25T06:11:30.882448Z","steps":["trace[510070116] 'read index received' (duration: 166.97293ms)","trace[510070116] 'applied index is now lower than readState.Index' (duration: 16.861µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:30.882768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.282758ms","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-25T06:11:30.882829Z","caller":"traceutil/trace.go:171","msg":"trace[1642674925] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"167.374921ms","start":"2026-02-25T06:11:30.715439Z","end":"2026-02-25T06:11:30.882814Z","steps":["trace[1642674925] 'agreement among raft nodes before linearized reading' (duration: 167.155325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:30.884057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.241498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:30.884114Z","caller":"traceutil/trace.go:171","msg":"trace[13230381] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"160.32509ms","start":"2026-02-25T06:11:30.723775Z","end":"2026-02-25T06:11:30.8841Z","steps":["trace[13230381] 'agreement among raft nodes before linearized reading' (duration: 160.156276ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.884651Z","caller":"traceutil/trace.go:171","msg":"trace[1849552470] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"160.669798ms","start":"2026-02-25T06:11:30.723969Z","end":"2026-02-25T06:11:30.884639Z","steps":["trace[1849552470] 'process raft request' (duration: 159.853618ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:30.885229Z","caller":"traceutil/trace.go:171","msg":"trace[716781336] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"161.191241ms","start":"2026-02-25T06:11:30.724023Z","end":"2026-02-25T06:11:30.885215Z","steps":["trace[716781336] 'process raft request' (duration: 159.873379ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:43.359199Z","caller":"traceutil/trace.go:171","msg":"trace[630491229] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"102.104691ms","start":"2026-02-25T06:11:43.257064Z","end":"2026-02-25T06:11:43.359169Z","steps":["trace[630491229] 'process raft request' (duration: 101.938677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:44.375214Z","caller":"traceutil/trace.go:171","msg":"trace[1592775006] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"436.100339ms","start":"2026-02-25T06:11:43.939085Z","end":"2026-02-25T06:11:44.375185Z","steps":["trace[1592775006] 'process raft request' (duration: 435.920054ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:44.375382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:43.939065Z","time spent":"436.238932ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:46.1646Z","caller":"traceutil/trace.go:171","msg":"trace[303726601] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"196.820735ms","start":"2026-02-25T06:11:45.967757Z","end":"2026-02-25T06:11:46.164578Z","steps":["trace[303726601] 'process raft request' (duration: 196.670912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.314051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.346853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T06:12:19.314117Z","caller":"traceutil/trace.go:171","msg":"trace[800218984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"181.453756ms","start":"2026-02-25T06:12:19.132648Z","end":"2026-02-25T06:12:19.314101Z","steps":["trace[800218984] 'range keys from in-memory index tree' (duration: 181.21156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.31407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.948343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:19.314184Z","caller":"traceutil/trace.go:171","msg":"trace[1363783546] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1178; }","duration":"281.092006ms","start":"2026-02-25T06:12:19.033072Z","end":"2026-02-25T06:12:19.314164Z","steps":["trace[1363783546] 'count revisions from in-memory index tree' (duration: 280.86653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.314258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.673428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-02-25T06:12:19.314309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.130038ms","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-25T06:12:19.314306Z","caller":"traceutil/trace.go:171","msg":"trace[1566931069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"178.73085ms","start":"2026-02-25T06:12:19.13556Z","end":"2026-02-25T06:12:19.314291Z","steps":["trace[1566931069] 'range keys from in-memory index tree' (duration: 178.570906ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:19.31433Z","caller":"traceutil/trace.go:171","msg":"trace[168019314] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"190.193569ms","start":"2026-02-25T06:12:19.124131Z","end":"2026-02-25T06:12:19.314325Z","steps":["trace[168019314] 'range keys from in-memory index tree' (duration: 190.028735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:19.314512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.29005ms","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-25T06:12:19.314551Z","caller":"traceutil/trace.go:171","msg":"trace[1660841344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"176.357222ms","start":"2026-02-25T06:12:19.138183Z","end":"2026-02-25T06:12:19.314541Z","steps":["trace[1660841344] 'range keys from in-memory index tree' (duration: 176.179637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:20.93803Z","caller":"traceutil/trace.go:171","msg":"trace[883588241] linearizableReadLoop","detail":"{readStateIndex:1237; appliedIndex:1236; }","duration":"213.432446ms","start":"2026-02-25T06:12:20.724579Z","end":"2026-02-25T06:12:20.938012Z","steps":["trace[883588241] 'read index received' (duration: 206.296182ms)","trace[883588241] 'applied index is now lower than readState.Index' (duration: 7.135414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:20.938229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.293746ms","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-25T06:12:20.938257Z","caller":"traceutil/trace.go:171","msg":"trace[47734481] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1184; }","duration":"125.369188ms","start":"2026-02-25T06:12:20.812881Z","end":"2026-02-25T06:12:20.93825Z","steps":["trace[47734481] 'agreement among raft nodes before linearized reading' (duration: 125.239665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:20.938354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.552469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:20.938433Z","caller":"traceutil/trace.go:171","msg":"trace[1283983607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1184; }","duration":"213.881457ms","start":"2026-02-25T06:12:20.72453Z","end":"2026-02-25T06:12:20.938412Z","steps":["trace[1283983607] 'agreement among raft nodes before linearized reading' (duration: 213.539689ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:21.559303Z","caller":"traceutil/trace.go:171","msg":"trace[402117260] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"179.19409ms","start":"2026-02-25T06:12:21.380092Z","end":"2026-02-25T06:12:21.559286Z","steps":["trace[402117260] 'process raft request' (duration: 176.596317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:21.564256Z","caller":"traceutil/trace.go:171","msg":"trace[1433107289] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"183.702631ms","start":"2026-02-25T06:12:21.380525Z","end":"2026-02-25T06:12:21.564228Z","steps":["trace[1433107289] 'process raft request' (duration: 183.023245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.354881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646667570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:13:24.85508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530391616646667570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:13:24.982779Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129400507s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:13:24.983215Z","caller":"traceutil/trace.go:171","msg":"trace[706559591] transaction","detail":"{read_only:false; response_revision:1442; number_of_response:1; }","duration":"1.129886068s","start":"2026-02-25T06:13:23.853309Z","end":"2026-02-25T06:13:24.983195Z","steps":["trace[706559591] 'process raft request' (duration: 1.129766395s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.983331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:23.853297Z","time spent":"1.12996997s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:13:24.984069Z","caller":"traceutil/trace.go:171","msg":"trace[1904762517] linearizableReadLoop","detail":"{readStateIndex:1508; appliedIndex:1506; }","duration":"1.129877258s","start":"2026-02-25T06:13:23.854175Z","end":"2026-02-25T06:13:24.984052Z","steps":["trace[1904762517] 'read index received' (duration: 1.128999088s)","trace[1904762517] 'applied index is now lower than readState.Index' (duration: 877.23µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:13:24.984218Z","caller":"traceutil/trace.go:171","msg":"trace[1555058631] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"1.129826297s","start":"2026-02-25T06:13:23.854384Z","end":"2026-02-25T06:13:24.98421Z","steps":["trace[1555058631] 'process raft request' (duration: 1.129587301s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.984294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:23.85437Z","time spent":"1.129868538s","remote":"127.0.0.1:57586","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-25T06:13:24.984453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130278228s","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-25T06:13:24.984476Z","caller":"traceutil/trace.go:171","msg":"trace[1136540563] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1443; }","duration":"1.130300448s","start":"2026-02-25T06:13:23.854169Z","end":"2026-02-25T06:13:24.98447Z","steps":["trace[1136540563] 'agreement among raft nodes before linearized reading' (duration: 1.130223116s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.98449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:23.854101Z","time spent":"1.13038591s","remote":"127.0.0.1:57586","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-25T06:13:24.984593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128370073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T06:13:24.98461Z","caller":"traceutil/trace.go:171","msg":"trace[1842987363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1443; }","duration":"1.128412023s","start":"2026-02-25T06:13:23.856195Z","end":"2026-02-25T06:13:24.984607Z","steps":["trace[1842987363] 'agreement among raft nodes before linearized reading' (duration: 1.128359882s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.984624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:23.856182Z","time spent":"1.128437025s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T06:13:24.984721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.355231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:13:24.984738Z","caller":"traceutil/trace.go:171","msg":"trace[1897053195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1443; }","duration":"261.400842ms","start":"2026-02-25T06:13:24.723333Z","end":"2026-02-25T06:13:24.984734Z","steps":["trace[1897053195] 'agreement among raft nodes before linearized reading' (duration: 261.375052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.98485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.308983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-25T06:13:24.984868Z","caller":"traceutil/trace.go:171","msg":"trace[1408643663] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1443; }","duration":"428.361814ms","start":"2026-02-25T06:13:24.556501Z","end":"2026-02-25T06:13:24.984863Z","steps":["trace[1408643663] 'agreement among raft nodes before linearized reading' (duration: 428.315023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.984881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:24.556483Z","time spent":"428.394795ms","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T06:13:24.985096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127938843s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:13:24.985115Z","caller":"traceutil/trace.go:171","msg":"trace[2095624172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1443; }","duration":"1.127991264s","start":"2026-02-25T06:13:23.857119Z","end":"2026-02-25T06:13:24.985111Z","steps":["trace[2095624172] 'agreement among raft nodes before linearized reading' (duration: 1.127946973s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.985128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:23.857104Z","time spent":"1.128021504s","remote":"127.0.0.1:57586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T06:13:24.985327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.503414ms","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-25T06:13:24.985373Z","caller":"traceutil/trace.go:171","msg":"trace[849714243] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1443; }","duration":"767.597205ms","start":"2026-02-25T06:13:24.217765Z","end":"2026-02-25T06:13:24.985362Z","steps":["trace[849714243] 'agreement among raft nodes before linearized reading' (duration: 767.179765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:13:24.9854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:13:24.217743Z","time spent":"767.648837ms","remote":"127.0.0.1:57766","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-25T06:14:43.968853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.136896ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:14:43.969075Z","caller":"traceutil/trace.go:171","msg":"trace[1645830243] linearizableReadLoop","detail":"{readStateIndex:1882; appliedIndex:1881; }","duration":"173.48564ms","start":"2026-02-25T06:14:43.795554Z","end":"2026-02-25T06:14:43.96904Z","steps":["trace[1645830243] 'read index received' (duration: 45.984456ms)","trace[1645830243] 'applied index is now lower than readState.Index' (duration: 127.499374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:14:43.969177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.622753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:14:43.969211Z","caller":"traceutil/trace.go:171","msg":"trace[1148516375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1800; }","duration":"173.697395ms","start":"2026-02-25T06:14:43.795502Z","end":"2026-02-25T06:14:43.9692Z","steps":["trace[1148516375] 'agreement among raft nodes before linearized reading' (duration: 173.636844ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:14:43.969257Z","caller":"traceutil/trace.go:171","msg":"trace[134053489] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"210.844165ms","start":"2026-02-25T06:14:43.758385Z","end":"2026-02-25T06:14:43.969229Z","steps":["trace[134053489] 'process raft request' (duration: 83.228538ms)","trace[134053489] 'compare' (duration: 127.017403ms)"],"step_count":2}