{"level":"warn","ts":"2026-01-29T16:11:39.96026Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T16:11:39.960341Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-01-29T16:11:39.961677Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T16:11:39.96169Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-01-29T16:11:39.961715Z","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-01-29T16:11:39.962206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-01-29T16:11:39.96227Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-01-29T16:11:39.966431Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.993357ms"} {"level":"info","ts":"2026-01-29T16:11:39.986876Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-01-29T16:11:39.986969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T16:11:39.986995Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-01-29T16:11:39.987003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T16:11:39.987009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-01-29T16:11:39.987039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-01-29T16:11:39.996399Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T16:11:40.006305Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T16:11:40.009938Z","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-01-29T16:11:40.020501Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T16:11:40.023269Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T16:11:40.023524Z","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-01-29T16:11:40.023835Z","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-01-29T16:11:40.023885Z","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-01-29T16:11:40.024544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-01-29T16:11:40.02466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-01-29T16:11:40.036085Z","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-01-29T16:11:40.036416Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-01-29T16:11:40.0365Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-01-29T16:11:40.050977Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T16:11:40.051191Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T16:11:40.188152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T16:11:40.188287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T16:11:40.188348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-01-29T16:11:40.188416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-01-29T16:11:40.188471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-01-29T16:11:40.188555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-01-29T16:11:40.188607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-01-29T16:11:40.190348Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:11:40.192531Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T16:11:40.192677Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T16:11:40.192984Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T16:11:40.193186Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T16:11:40.193224Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T16:11:40.193493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:11:40.193645Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:11:40.193785Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T16:11:40.194842Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T16:11:40.196959Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-01-29T16:11:56.495039Z","caller":"traceutil/trace.go:171","msg":"trace[438149273] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"286.671724ms","start":"2026-01-29T16:11:56.208339Z","end":"2026-01-29T16:11:56.495011Z","steps":["trace[438149273] 'read index received' (duration: 286.47341ms)","trace[438149273] 'applied index is now lower than readState.Index' (duration: 197.094µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:11:56.495316Z","caller":"traceutil/trace.go:171","msg":"trace[936257658] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"569.929382ms","start":"2026-01-29T16:11:55.925374Z","end":"2026-01-29T16:11:56.495303Z","steps":["trace[936257658] 'process raft request' (duration: 569.485463ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:11:56.495538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.196636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-01-29T16:11:56.495589Z","caller":"traceutil/trace.go:171","msg":"trace[121378859] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:363; }","duration":"287.261168ms","start":"2026-01-29T16:11:56.208312Z","end":"2026-01-29T16:11:56.495573Z","steps":["trace[121378859] 'agreement among raft nodes before linearized reading' (duration: 287.201327ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:11:56.496127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:11:55.925357Z","time spent":"570.20858ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:11:56.495471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.865214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-01-29T16:11:56.49792Z","caller":"traceutil/trace.go:171","msg":"trace[20735985] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:363; }","duration":"208.367218ms","start":"2026-01-29T16:11:56.289537Z","end":"2026-01-29T16:11:56.497904Z","steps":["trace[20735985] 'agreement among raft nodes before linearized reading' (duration: 205.846082ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:02.856725Z","caller":"traceutil/trace.go:171","msg":"trace[1410083018] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"198.727785ms","start":"2026-01-29T16:12:02.657979Z","end":"2026-01-29T16:12:02.856707Z","steps":["trace[1410083018] 'process raft request' (duration: 198.638193ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:10.916916Z","caller":"traceutil/trace.go:171","msg":"trace[1953336583] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"235.88139ms","start":"2026-01-29T16:12:10.681012Z","end":"2026-01-29T16:12:10.916893Z","steps":["trace[1953336583] 'process raft request' (duration: 235.655664ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:13.137583Z","caller":"traceutil/trace.go:171","msg":"trace[1441069534] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"209.264227ms","start":"2026-01-29T16:12:12.928302Z","end":"2026-01-29T16:12:13.137567Z","steps":["trace[1441069534] 'read index received' (duration: 209.117544ms)","trace[1441069534] 'applied index is now lower than readState.Index' (duration: 145.943µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:13.137797Z","caller":"traceutil/trace.go:171","msg":"trace[638189323] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"214.125645ms","start":"2026-01-29T16:12:12.923664Z","end":"2026-01-29T16:12:13.13779Z","steps":["trace[638189323] 'process raft request' (duration: 213.799438ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:13.138661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.342852ms","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-01-29T16:12:13.138706Z","caller":"traceutil/trace.go:171","msg":"trace[1489741599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"210.411464ms","start":"2026-01-29T16:12:12.928284Z","end":"2026-01-29T16:12:13.138695Z","steps":["trace[1489741599] 'agreement among raft nodes before linearized reading' (duration: 210.295272ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:13.138917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.03285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-29T16:12:13.138968Z","caller":"traceutil/trace.go:171","msg":"trace[2132367615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"124.119592ms","start":"2026-01-29T16:12:13.014835Z","end":"2026-01-29T16:12:13.138954Z","steps":["trace[2132367615] 'agreement among raft nodes before linearized reading' (duration: 124.014799ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:13.37112Z","caller":"traceutil/trace.go:171","msg":"trace[1300017214] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"225.042769ms","start":"2026-01-29T16:12:13.145903Z","end":"2026-01-29T16:12:13.370946Z","steps":["trace[1300017214] 'process raft request' (duration: 224.962417ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:13.373084Z","caller":"traceutil/trace.go:171","msg":"trace[239362836] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"225.056097ms","start":"2026-01-29T16:12:13.148021Z","end":"2026-01-29T16:12:13.373077Z","steps":["trace[239362836] 'process raft request' (duration: 224.914754ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:13.373823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.732387ms","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-01-29T16:12:13.373873Z","caller":"traceutil/trace.go:171","msg":"trace[1761214904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"120.824859ms","start":"2026-01-29T16:12:13.253036Z","end":"2026-01-29T16:12:13.373861Z","steps":["trace[1761214904] 'agreement among raft nodes before linearized reading' (duration: 120.722247ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:13.375222Z","caller":"traceutil/trace.go:171","msg":"trace[1077719719] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"119.952739ms","start":"2026-01-29T16:12:13.253089Z","end":"2026-01-29T16:12:13.373042Z","steps":["trace[1077719719] 'read index received' (duration: 117.884474ms)","trace[1077719719] 'applied index is now lower than readState.Index' (duration: 2.067775ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:17.892037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420189193197370,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:12:17.913007Z","caller":"traceutil/trace.go:171","msg":"trace[1128071426] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"525.11604ms","start":"2026-01-29T16:12:17.387867Z","end":"2026-01-29T16:12:17.912983Z","steps":["trace[1128071426] 'process raft request' (duration: 524.973236ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:17.913136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.387843Z","time spent":"525.229472ms","remote":"127.0.0.1:46726","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-01-29T16:12:17.913489Z","caller":"traceutil/trace.go:171","msg":"trace[429404022] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:501; }","duration":"522.47411ms","start":"2026-01-29T16:12:17.391005Z","end":"2026-01-29T16:12:17.913479Z","steps":["trace[429404022] 'read index received' (duration: 522.46695ms)","trace[429404022] 'applied index is now lower than readState.Index' (duration: 3.52µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:17.913667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.975354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-29T16:12:17.913689Z","caller":"traceutil/trace.go:171","msg":"trace[53257779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:484; }","duration":"520.008935ms","start":"2026-01-29T16:12:17.393674Z","end":"2026-01-29T16:12:17.913683Z","steps":["trace[53257779] 'agreement among raft nodes before linearized reading' (duration: 519.917223ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:17.913709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.393668Z","time spent":"520.035556ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T16:12:17.913821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.619917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rn6bg\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-29T16:12:17.91384Z","caller":"traceutil/trace.go:171","msg":"trace[597886734] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rn6bg; range_end:; response_count:1; response_revision:484; }","duration":"154.674368ms","start":"2026-01-29T16:12:17.759161Z","end":"2026-01-29T16:12:17.913835Z","steps":["trace[597886734] 'agreement among raft nodes before linearized reading' (duration: 154.618527ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:17.913936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.395903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:12:17.913952Z","caller":"traceutil/trace.go:171","msg":"trace[1039115458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"353.433924ms","start":"2026-01-29T16:12:17.560513Z","end":"2026-01-29T16:12:17.913947Z","steps":["trace[1039115458] 'agreement among raft nodes before linearized reading' (duration: 353.409063ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:17.91397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.560499Z","time spent":"353.467474ms","remote":"127.0.0.1:46466","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-01-29T16:12:17.914261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.262507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-29T16:12:17.914281Z","caller":"traceutil/trace.go:171","msg":"trace[436249504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:484; }","duration":"523.311409ms","start":"2026-01-29T16:12:17.390964Z","end":"2026-01-29T16:12:17.914275Z","steps":["trace[436249504] 'agreement among raft nodes before linearized reading' (duration: 522.591423ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:17.914297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.39095Z","time spent":"523.343849ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T16:12:18.850636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.748649ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:12:18.850753Z","caller":"traceutil/trace.go:171","msg":"trace[1607180654] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"928.497621ms","start":"2026-01-29T16:12:17.922238Z","end":"2026-01-29T16:12:18.850736Z","steps":["trace[1607180654] 'process raft request' (duration: 598.439115ms)","trace[1607180654] 'compare' (duration: 329.359299ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:18.85081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.922223Z","time spent":"928.560792ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:12:19.219727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.900912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:12:19.219891Z","caller":"traceutil/trace.go:171","msg":"trace[1455616585] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:502; }","duration":"654.426575ms","start":"2026-01-29T16:12:18.565449Z","end":"2026-01-29T16:12:19.219875Z","steps":["trace[1455616585] 'read index received' (duration: 486.251427ms)","trace[1455616585] 'applied index is now lower than readState.Index' (duration: 168.174238ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:19.220126Z","caller":"traceutil/trace.go:171","msg":"trace[331382962] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.293587161s","start":"2026-01-29T16:12:17.926527Z","end":"2026-01-29T16:12:19.220114Z","steps":["trace[331382962] 'process raft request' (duration: 1.12523011s)","trace[331382962] 'compare' (duration: 167.703877ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:19.220195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.92652Z","time spent":"1.293643783s","remote":"127.0.0.1:46726","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-01-29T16:12:19.220424Z","caller":"traceutil/trace.go:171","msg":"trace[50399283] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"1.290379822s","start":"2026-01-29T16:12:17.930035Z","end":"2026-01-29T16:12:19.220414Z","steps":["trace[50399283] 'process raft request' (duration: 1.289785888s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:19.220475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:17.930028Z","time spent":"1.290418963s","remote":"127.0.0.1:46640","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-01-29T16:12:19.220553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.102651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:12:19.220577Z","caller":"traceutil/trace.go:171","msg":"trace[953534383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"655.153721ms","start":"2026-01-29T16:12:18.565416Z","end":"2026-01-29T16:12:19.220569Z","steps":["trace[953534383] 'agreement among raft nodes before linearized reading' (duration: 655.11667ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:19.220602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:18.565401Z","time spent":"655.195903ms","remote":"127.0.0.1:46466","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-01-29T16:12:21.500985Z","caller":"traceutil/trace.go:171","msg":"trace[656647894] linearizableReadLoop","detail":"{readStateIndex:517; appliedIndex:516; }","duration":"228.497696ms","start":"2026-01-29T16:12:21.272469Z","end":"2026-01-29T16:12:21.500966Z","steps":["trace[656647894] 'read index received' (duration: 228.314462ms)","trace[656647894] 'applied index is now lower than readState.Index' (duration: 182.563µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:21.501155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.676639ms","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-01-29T16:12:21.501178Z","caller":"traceutil/trace.go:171","msg":"trace[602131156] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"228.759941ms","start":"2026-01-29T16:12:21.27241Z","end":"2026-01-29T16:12:21.50117Z","steps":["trace[602131156] 'agreement among raft nodes before linearized reading' (duration: 228.631368ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:21.501419Z","caller":"traceutil/trace.go:171","msg":"trace[1832599513] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"268.478271ms","start":"2026-01-29T16:12:21.232933Z","end":"2026-01-29T16:12:21.501412Z","steps":["trace[1832599513] 'process raft request' (duration: 267.899419ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:24.156207Z","caller":"traceutil/trace.go:171","msg":"trace[376433391] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"124.242725ms","start":"2026-01-29T16:12:24.031933Z","end":"2026-01-29T16:12:24.156176Z","steps":["trace[376433391] 'read index received' (duration: 123.905086ms)","trace[376433391] 'applied index is now lower than readState.Index' (duration: 336.369µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:24.156442Z","caller":"traceutil/trace.go:171","msg":"trace[1240162154] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"192.788803ms","start":"2026-01-29T16:12:23.963641Z","end":"2026-01-29T16:12:24.15643Z","steps":["trace[1240162154] 'process raft request' (duration: 192.284011ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:24.157322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.366959ms","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-01-29T16:12:24.157392Z","caller":"traceutil/trace.go:171","msg":"trace[850767758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:558; }","duration":"125.505172ms","start":"2026-01-29T16:12:24.031876Z","end":"2026-01-29T16:12:24.157381Z","steps":["trace[850767758] 'agreement among raft nodes before linearized reading' (duration: 125.282137ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:32.83512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.793219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:12:32.835223Z","caller":"traceutil/trace.go:171","msg":"trace[1493096640] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"323.114962ms","start":"2026-01-29T16:12:32.512091Z","end":"2026-01-29T16:12:32.835206Z","steps":["trace[1493096640] 'read index received' (duration: 216.148279ms)","trace[1493096640] 'applied index is now lower than readState.Index' (duration: 106.965653ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:32.835395Z","caller":"traceutil/trace.go:171","msg":"trace[278301390] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"579.104895ms","start":"2026-01-29T16:12:32.25628Z","end":"2026-01-29T16:12:32.835385Z","steps":["trace[278301390] 'process raft request' (duration: 471.933148ms)","trace[278301390] 'compare' (duration: 106.583004ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:32.835439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:32.256269Z","time spent":"579.148136ms","remote":"127.0.0.1:46726","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-01-29T16:12:32.836569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.473991ms","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-01-29T16:12:32.836591Z","caller":"traceutil/trace.go:171","msg":"trace[1601070206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"324.547743ms","start":"2026-01-29T16:12:32.512035Z","end":"2026-01-29T16:12:32.836583Z","steps":["trace[1601070206] 'agreement among raft nodes before linearized reading' (duration: 324.39019ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:32.836608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:32.512016Z","time spent":"324.588854ms","remote":"127.0.0.1:46726","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-01-29T16:12:32.836722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.167989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:12:32.836736Z","caller":"traceutil/trace.go:171","msg":"trace[587997773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"252.23461ms","start":"2026-01-29T16:12:32.584497Z","end":"2026-01-29T16:12:32.836732Z","steps":["trace[587997773] 'agreement among raft nodes before linearized reading' (duration: 252.196269ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:33.281606Z","caller":"traceutil/trace.go:171","msg":"trace[1565089858] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"430.776199ms","start":"2026-01-29T16:12:32.850816Z","end":"2026-01-29T16:12:33.281592Z","steps":["trace[1565089858] 'process raft request' (duration: 337.018112ms)","trace[1565089858] 'compare' (duration: 92.995881ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:33.281737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:32.850804Z","time spent":"430.855221ms","remote":"127.0.0.1:46726","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-01-29T16:12:33.281377Z","caller":"traceutil/trace.go:171","msg":"trace[212604931] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"388.677155ms","start":"2026-01-29T16:12:32.892674Z","end":"2026-01-29T16:12:33.281352Z","steps":["trace[212604931] 'read index received' (duration: 295.084281ms)","trace[212604931] 'applied index is now lower than readState.Index' (duration: 93.591984ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:33.282218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.539434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b4snd\" ","response":"range_response_count:1 size:5001"} {"level":"info","ts":"2026-01-29T16:12:33.282242Z","caller":"traceutil/trace.go:171","msg":"trace[1826055186] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-b4snd; range_end:; response_count:1; response_revision:655; }","duration":"389.599985ms","start":"2026-01-29T16:12:32.892634Z","end":"2026-01-29T16:12:33.282234Z","steps":["trace[1826055186] 'agreement among raft nodes before linearized reading' (duration: 389.493823ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:33.282262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:32.892613Z","time spent":"389.644837ms","remote":"127.0.0.1:46640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5024,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-b4snd\" "} {"level":"warn","ts":"2026-01-29T16:12:33.282412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.309195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-01-29T16:12:33.282429Z","caller":"traceutil/trace.go:171","msg":"trace[1824538712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:655; }","duration":"212.372237ms","start":"2026-01-29T16:12:33.070052Z","end":"2026-01-29T16:12:33.282424Z","steps":["trace[1824538712] 'agreement among raft nodes before linearized reading' (duration: 212.318525ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:33.978836Z","caller":"traceutil/trace.go:171","msg":"trace[1469212003] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"342.43949ms","start":"2026-01-29T16:12:33.636369Z","end":"2026-01-29T16:12:33.978809Z","steps":["trace[1469212003] 'process raft request' (duration: 342.271986ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:33.979014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:33.636353Z","time spent":"342.563692ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:12:33.979266Z","caller":"traceutil/trace.go:171","msg":"trace[491285603] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:680; }","duration":"341.282704ms","start":"2026-01-29T16:12:33.637964Z","end":"2026-01-29T16:12:33.979246Z","steps":["trace[491285603] 'read index received' (duration: 341.276944ms)","trace[491285603] 'applied index is now lower than readState.Index' (duration: 4.66µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:33.97944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.470779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-29T16:12:33.979475Z","caller":"traceutil/trace.go:171","msg":"trace[858169096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:659; }","duration":"341.515199ms","start":"2026-01-29T16:12:33.63795Z","end":"2026-01-29T16:12:33.979465Z","steps":["trace[858169096] 'agreement among raft nodes before linearized reading' (duration: 341.353886ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:33.979499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:33.637944Z","time spent":"341.54998ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T16:12:34.029894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.110012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T16:12:34.029969Z","caller":"traceutil/trace.go:171","msg":"trace[942766377] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:661; }","duration":"254.232415ms","start":"2026-01-29T16:12:33.775717Z","end":"2026-01-29T16:12:34.02995Z","steps":["trace[942766377] 'agreement among raft nodes before linearized reading' (duration: 250.785749ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:41.024061Z","caller":"traceutil/trace.go:171","msg":"trace[1495449740] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"953.258118ms","start":"2026-01-29T16:12:40.070786Z","end":"2026-01-29T16:12:41.024045Z","steps":["trace[1495449740] 'process raft request' (duration: 952.496171ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:41.024318Z","caller":"traceutil/trace.go:171","msg":"trace[971671770] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:701; }","duration":"464.213369ms","start":"2026-01-29T16:12:40.560051Z","end":"2026-01-29T16:12:41.024264Z","steps":["trace[971671770] 'read index received' (duration: 463.299709ms)","trace[971671770] 'applied index is now lower than readState.Index' (duration: 912.95µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:41.024441Z","caller":"traceutil/trace.go:171","msg":"trace[354006250] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"947.530921ms","start":"2026-01-29T16:12:40.076904Z","end":"2026-01-29T16:12:41.024435Z","steps":["trace[354006250] 'process raft request' (duration: 947.096901ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:41.024916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:40.076887Z","time spent":"947.573972ms","remote":"127.0.0.1:46726","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-01-29T16:12:41.025053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.001816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:12:41.025075Z","caller":"traceutil/trace.go:171","msg":"trace[1576850582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"465.048957ms","start":"2026-01-29T16:12:40.560019Z","end":"2026-01-29T16:12:41.025068Z","steps":["trace[1576850582] 'agreement among raft nodes before linearized reading' (duration: 465.011907ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:41.025094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:40.560004Z","time spent":"465.085188ms","remote":"127.0.0.1:46466","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-01-29T16:12:41.03093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:40.070774Z","time spent":"953.38003ms","remote":"127.0.0.1:46726","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-01-29T16:12:54.821603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.560178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:12:54.82187Z","caller":"traceutil/trace.go:171","msg":"trace[400091196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"262.848475ms","start":"2026-01-29T16:12:54.558994Z","end":"2026-01-29T16:12:54.821843Z","steps":["trace[400091196] 'range keys from in-memory index tree' (duration: 262.452626ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:59.474407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.674251ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-01-29T16:12:59.474483Z","caller":"traceutil/trace.go:171","msg":"trace[1274841262] linearizableReadLoop","detail":"{readStateIndex:763; appliedIndex:762; }","duration":"301.917715ms","start":"2026-01-29T16:12:59.17255Z","end":"2026-01-29T16:12:59.474468Z","steps":["trace[1274841262] 'read index received' (duration: 72.855931ms)","trace[1274841262] 'applied index is now lower than readState.Index' (duration: 229.060804ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:12:59.474655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.982563ms","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-01-29T16:12:59.474675Z","caller":"traceutil/trace.go:171","msg":"trace[1698336977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"301.021254ms","start":"2026-01-29T16:12:59.173648Z","end":"2026-01-29T16:12:59.474669Z","steps":["trace[1698336977] 'agreement among raft nodes before linearized reading' (duration: 300.933632ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:59.474699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:59.173642Z","time spent":"301.052654ms","remote":"127.0.0.1:46726","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-01-29T16:12:59.475022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.469346ms","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-01-29T16:12:59.475041Z","caller":"traceutil/trace.go:171","msg":"trace[908888234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"302.500867ms","start":"2026-01-29T16:12:59.172535Z","end":"2026-01-29T16:12:59.475036Z","steps":["trace[908888234] 'agreement among raft nodes before linearized reading' (duration: 302.017586ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:59.475054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:12:59.172528Z","time spent":"302.522478ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-29T16:12:59.612897Z","caller":"traceutil/trace.go:171","msg":"trace[1587723032] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"135.124988ms","start":"2026-01-29T16:12:59.477755Z","end":"2026-01-29T16:12:59.61288Z","steps":["trace[1587723032] 'process raft request' (duration: 135.024486ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:59.6213Z","caller":"traceutil/trace.go:171","msg":"trace[693025869] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:763; }","duration":"120.731263ms","start":"2026-01-29T16:12:59.500556Z","end":"2026-01-29T16:12:59.621288Z","steps":["trace[693025869] 'read index received' (duration: 112.181665ms)","trace[693025869] 'applied index is now lower than readState.Index' (duration: 8.548888ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:12:59.621363Z","caller":"traceutil/trace.go:171","msg":"trace[1264849389] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"142.289235ms","start":"2026-01-29T16:12:59.479067Z","end":"2026-01-29T16:12:59.621356Z","steps":["trace[1264849389] 'process raft request' (duration: 142.072571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:12:59.621471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.902286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-29T16:12:59.6215Z","caller":"traceutil/trace.go:171","msg":"trace[1385632511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:739; }","duration":"120.966138ms","start":"2026-01-29T16:12:59.500524Z","end":"2026-01-29T16:12:59.621491Z","steps":["trace[1385632511] 'agreement among raft nodes before linearized reading' (duration: 120.872016ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:12:59.621601Z","caller":"traceutil/trace.go:171","msg":"trace[783234282] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"140.949907ms","start":"2026-01-29T16:12:59.480644Z","end":"2026-01-29T16:12:59.621594Z","steps":["trace[783234282] 'process raft request' (duration: 140.615429ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:03.708936Z","caller":"traceutil/trace.go:171","msg":"trace[936662511] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"147.201795ms","start":"2026-01-29T16:13:03.561713Z","end":"2026-01-29T16:13:03.708915Z","steps":["trace[936662511] 'read index received' (duration: 146.99202ms)","trace[936662511] 'applied index is now lower than readState.Index' (duration: 208.815µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:03.709252Z","caller":"traceutil/trace.go:171","msg":"trace[1380893734] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"450.863437ms","start":"2026-01-29T16:13:03.258359Z","end":"2026-01-29T16:13:03.709222Z","steps":["trace[1380893734] 'process raft request' (duration: 450.399727ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:03.710437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.258343Z","time spent":"451.941591ms","remote":"127.0.0.1:46726","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-01-29T16:13:03.709379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.629384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:03.710677Z","caller":"traceutil/trace.go:171","msg":"trace[818374110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:746; }","duration":"148.954613ms","start":"2026-01-29T16:13:03.561707Z","end":"2026-01-29T16:13:03.710662Z","steps":["trace[818374110] 'agreement among raft nodes before linearized reading' (duration: 147.604064ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:03.739681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.598806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T16:13:03.739756Z","caller":"traceutil/trace.go:171","msg":"trace[595256285] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:746; }","duration":"118.737869ms","start":"2026-01-29T16:13:03.621001Z","end":"2026-01-29T16:13:03.739739Z","steps":["trace[595256285] 'agreement among raft nodes before linearized reading' (duration: 118.515585ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:04.223869Z","caller":"traceutil/trace.go:171","msg":"trace[1944042079] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"472.218874ms","start":"2026-01-29T16:13:03.751621Z","end":"2026-01-29T16:13:04.22384Z","steps":["trace[1944042079] 'process raft request' (duration: 431.248585ms)","trace[1944042079] 'compare' (duration: 39.802424ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:04.224005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.75159Z","time spent":"472.345986ms","remote":"127.0.0.1:46726","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-01-29T16:13:04.850552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.29742ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:04.851038Z","caller":"traceutil/trace.go:171","msg":"trace[1315479902] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:747; }","duration":"494.77284ms","start":"2026-01-29T16:13:04.356223Z","end":"2026-01-29T16:13:04.850996Z","steps":["trace[1315479902] 'range keys from in-memory index tree' (duration: 494.28314ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:04.872412Z","caller":"traceutil/trace.go:171","msg":"trace[1777965115] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:773; }","duration":"1.095365546s","start":"2026-01-29T16:13:03.77703Z","end":"2026-01-29T16:13:04.872396Z","steps":["trace[1777965115] 'read index received' (duration: 405.849987ms)","trace[1777965115] 'applied index is now lower than readState.Index' (duration: 689.514749ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:04.873922Z","caller":"traceutil/trace.go:171","msg":"trace[931268303] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.122084592s","start":"2026-01-29T16:13:03.751826Z","end":"2026-01-29T16:13:04.873911Z","steps":["trace[931268303] 'process raft request' (duration: 1.120421416s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.874077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.751815Z","time spent":"1.122180054s","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:04.874384Z","caller":"traceutil/trace.go:171","msg":"trace[965765512] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.122465022s","start":"2026-01-29T16:13:03.75189Z","end":"2026-01-29T16:13:04.874355Z","steps":["trace[965765512] 'process raft request' (duration: 1.120452577s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.874484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.751883Z","time spent":"1.122549604s","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:04.874785Z","caller":"traceutil/trace.go:171","msg":"trace[1066250574] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.120772734s","start":"2026-01-29T16:13:03.753962Z","end":"2026-01-29T16:13:04.874734Z","steps":["trace[1066250574] 'process raft request' (duration: 1.118407632s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.874881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.753943Z","time spent":"1.120912787s","remote":"127.0.0.1:46726","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-01-29T16:13:04.87525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098216399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T16:13:04.875322Z","caller":"traceutil/trace.go:171","msg":"trace[417985597] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:750; }","duration":"1.098367943s","start":"2026-01-29T16:13:03.776946Z","end":"2026-01-29T16:13:04.875314Z","steps":["trace[417985597] 'agreement among raft nodes before linearized reading' (duration: 1.098208989s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.875383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:03.776927Z","time spent":"1.098448924s","remote":"127.0.0.1:46476","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.25\" "} {"level":"info","ts":"2026-01-29T16:13:04.930113Z","caller":"traceutil/trace.go:171","msg":"trace[1425922114] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"173.803269ms","start":"2026-01-29T16:13:04.756288Z","end":"2026-01-29T16:13:04.930091Z","steps":["trace[1425922114] 'process raft request' (duration: 173.687517ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.930269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.374362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T16:13:04.930358Z","caller":"traceutil/trace.go:171","msg":"trace[1956252346] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:751; }","duration":"509.496934ms","start":"2026-01-29T16:13:04.420823Z","end":"2026-01-29T16:13:04.93032Z","steps":["trace[1956252346] 'agreement among raft nodes before linearized reading' (duration: 509.330101ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.9304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:04.420774Z","time spent":"509.614897ms","remote":"127.0.0.1:46712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:13:04.930555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.810084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:04.930594Z","caller":"traceutil/trace.go:171","msg":"trace[393692925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"368.880825ms","start":"2026-01-29T16:13:04.561702Z","end":"2026-01-29T16:13:04.930583Z","steps":["trace[393692925] 'agreement among raft nodes before linearized reading' (duration: 368.817324ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:04.930626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:04.561684Z","time spent":"368.934297ms","remote":"127.0.0.1:46466","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-01-29T16:13:05.191173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.287976ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-01-29T16:13:05.191261Z","caller":"traceutil/trace.go:171","msg":"trace[1646012832] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"258.024299ms","start":"2026-01-29T16:13:04.93322Z","end":"2026-01-29T16:13:05.191245Z","steps":["trace[1646012832] 'read index received' (duration: 141.619431ms)","trace[1646012832] 'applied index is now lower than readState.Index' (duration: 116.402738ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:05.191577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.344766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:05.191645Z","caller":"traceutil/trace.go:171","msg":"trace[763308757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"258.447418ms","start":"2026-01-29T16:13:04.933186Z","end":"2026-01-29T16:13:05.191633Z","steps":["trace[763308757] 'agreement among raft nodes before linearized reading' (duration: 258.269594ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:05.192295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:04.87734Z","time spent":"314.951279ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T16:13:16.569405Z","caller":"traceutil/trace.go:171","msg":"trace[1326014233] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"292.075956ms","start":"2026-01-29T16:13:16.27731Z","end":"2026-01-29T16:13:16.569386Z","steps":["trace[1326014233] 'process raft request' (duration: 291.966554ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.345236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420189193198155,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-29T16:13:19.375988Z","caller":"traceutil/trace.go:171","msg":"trace[968598851] linearizableReadLoop","detail":"{readStateIndex:872; appliedIndex:871; }","duration":"531.689541ms","start":"2026-01-29T16:13:18.844281Z","end":"2026-01-29T16:13:19.375971Z","steps":["trace[968598851] 'read index received' (duration: 531.438345ms)","trace[968598851] 'applied index is now lower than readState.Index' (duration: 250.646µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:19.376286Z","caller":"traceutil/trace.go:171","msg":"trace[454068299] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"792.503851ms","start":"2026-01-29T16:13:18.583776Z","end":"2026-01-29T16:13:19.37628Z","steps":["trace[454068299] 'process raft request' (duration: 792.00241ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.376387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:18.583767Z","time spent":"792.543852ms","remote":"127.0.0.1:46726","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-01-29T16:13:19.376524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.242213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:19.376553Z","caller":"traceutil/trace.go:171","msg":"trace[475983776] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:840; }","duration":"532.321254ms","start":"2026-01-29T16:13:18.844222Z","end":"2026-01-29T16:13:19.376544Z","steps":["trace[475983776] 'agreement among raft nodes before linearized reading' (duration: 532.279243ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.376572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:18.8442Z","time spent":"532.368346ms","remote":"127.0.0.1:46760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:13:19.376707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.496684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T16:13:19.376727Z","caller":"traceutil/trace.go:171","msg":"trace[987049952] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:840; }","duration":"423.555665ms","start":"2026-01-29T16:13:18.953167Z","end":"2026-01-29T16:13:19.376722Z","steps":["trace[987049952] 'agreement among raft nodes before linearized reading' (duration: 423.492224ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.37674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:18.953144Z","time spent":"423.592845ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T16:13:19.376924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.404317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-29T16:13:19.376945Z","caller":"traceutil/trace.go:171","msg":"trace[693726234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:840; }","duration":"415.434978ms","start":"2026-01-29T16:13:18.961506Z","end":"2026-01-29T16:13:19.376941Z","steps":["trace[693726234] 'agreement among raft nodes before linearized reading' (duration: 415.384417ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.376959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:18.961498Z","time spent":"415.457668ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T16:13:19.37706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.15439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-29T16:13:19.377078Z","caller":"traceutil/trace.go:171","msg":"trace[358572019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:840; }","duration":"420.183482ms","start":"2026-01-29T16:13:18.95689Z","end":"2026-01-29T16:13:19.377074Z","steps":["trace[358572019] 'agreement among raft nodes before linearized reading' (duration: 420.137561ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:19.377096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:18.956882Z","time spent":"420.211022ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T16:13:27.535Z","caller":"traceutil/trace.go:171","msg":"trace[186434230] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"125.005818ms","start":"2026-01-29T16:13:27.409881Z","end":"2026-01-29T16:13:27.534887Z","steps":["trace[186434230] 'process raft request' (duration: 105.036268ms)","trace[186434230] 'store kv pair into bolt db' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 18.20676ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:27.536756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.254913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T16:13:27.536887Z","caller":"traceutil/trace.go:171","msg":"trace[1435028258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:862; }","duration":"110.393236ms","start":"2026-01-29T16:13:27.42644Z","end":"2026-01-29T16:13:27.536833Z","steps":["trace[1435028258] 'agreement among raft nodes before linearized reading' (duration: 110.083539ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:27.537488Z","caller":"traceutil/trace.go:171","msg":"trace[1844898538] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"108.370503ms","start":"2026-01-29T16:13:27.426465Z","end":"2026-01-29T16:13:27.534835Z","steps":["trace[1844898538] 'read index received' (duration: 88.367222ms)","trace[1844898538] 'applied index is now lower than readState.Index' (duration: 20.000731ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:27.950543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.715216ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:13:27.950693Z","caller":"traceutil/trace.go:171","msg":"trace[729295912] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"301.811872ms","start":"2026-01-29T16:13:27.64887Z","end":"2026-01-29T16:13:27.950681Z","steps":["trace[729295912] 'process raft request' (duration: 301.783941ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:27.950733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:27.648863Z","time spent":"301.852252ms","remote":"127.0.0.1:46726","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-01-29T16:13:27.950942Z","caller":"traceutil/trace.go:171","msg":"trace[369106145] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"410.962297ms","start":"2026-01-29T16:13:27.539971Z","end":"2026-01-29T16:13:27.950934Z","steps":["trace[369106145] 'process raft request' (duration: 206.783341ms)","trace[369106145] 'compare' (duration: 203.588103ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:27.950972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:27.539948Z","time spent":"411.007809ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:27.951054Z","caller":"traceutil/trace.go:171","msg":"trace[819058177] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"393.031834ms","start":"2026-01-29T16:13:27.558016Z","end":"2026-01-29T16:13:27.951048Z","steps":["trace[819058177] 'read index received' (duration: 188.751276ms)","trace[819058177] 'applied index is now lower than readState.Index' (duration: 204.279838ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:27.951149Z","caller":"traceutil/trace.go:171","msg":"trace[2114928406] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"303.271323ms","start":"2026-01-29T16:13:27.647873Z","end":"2026-01-29T16:13:27.951144Z","steps":["trace[2114928406] 'process raft request' (duration: 302.752931ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:27.951175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:27.64784Z","time spent":"303.319274ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:13:27.951299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.28623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:27.951317Z","caller":"traceutil/trace.go:171","msg":"trace[217819199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"393.317011ms","start":"2026-01-29T16:13:27.557995Z","end":"2026-01-29T16:13:27.951312Z","steps":["trace[217819199] 'agreement among raft nodes before linearized reading' (duration: 393.28514ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:27.951333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:27.557986Z","time spent":"393.341912ms","remote":"127.0.0.1:46466","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-01-29T16:13:28.584753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.183933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:13:28.585114Z","caller":"traceutil/trace.go:171","msg":"trace[114713467] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"588.723035ms","start":"2026-01-29T16:13:27.996374Z","end":"2026-01-29T16:13:28.585097Z","steps":["trace[114713467] 'process raft request' (duration: 314.101172ms)","trace[114713467] 'compare' (duration: 268.713993ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:28.58526Z","caller":"traceutil/trace.go:171","msg":"trace[485206385] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"425.710503ms","start":"2026-01-29T16:13:28.159524Z","end":"2026-01-29T16:13:28.585234Z","steps":["trace[485206385] 'process raft request' (duration: 425.331904ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:28.585423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:28.159491Z","time spent":"425.834335ms","remote":"127.0.0.1:46726","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-01-29T16:13:28.585474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:27.99636Z","time spent":"588.775646ms","remote":"127.0.0.1:46726","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-01-29T16:13:34.180737Z","caller":"traceutil/trace.go:171","msg":"trace[156458384] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"403.26281ms","start":"2026-01-29T16:13:33.777452Z","end":"2026-01-29T16:13:34.180715Z","steps":["trace[156458384] 'read index received' (duration: 403.101567ms)","trace[156458384] 'applied index is now lower than readState.Index' (duration: 160.353µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:34.180986Z","caller":"traceutil/trace.go:171","msg":"trace[762922676] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"605.245408ms","start":"2026-01-29T16:13:33.575727Z","end":"2026-01-29T16:13:34.180973Z","steps":["trace[762922676] 'process raft request' (duration: 604.87889ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.181096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:33.575718Z","time spent":"605.296289ms","remote":"127.0.0.1:46726","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-01-29T16:13:34.18134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.883714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T16:13:34.181373Z","caller":"traceutil/trace.go:171","msg":"trace[1039860108] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:878; }","duration":"403.937364ms","start":"2026-01-29T16:13:33.777425Z","end":"2026-01-29T16:13:34.181362Z","steps":["trace[1039860108] 'agreement among raft nodes before linearized reading' (duration: 403.823072ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.181402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:33.777414Z","time spent":"403.981345ms","remote":"127.0.0.1:46476","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.25\" "} {"level":"warn","ts":"2026-01-29T16:13:34.181614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.94673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-29T16:13:34.181642Z","caller":"traceutil/trace.go:171","msg":"trace[890479016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"193.00034ms","start":"2026-01-29T16:13:33.988634Z","end":"2026-01-29T16:13:34.181634Z","steps":["trace[890479016] 'agreement among raft nodes before linearized reading' (duration: 192.922199ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.182138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.027912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T16:13:34.182182Z","caller":"traceutil/trace.go:171","msg":"trace[800820970] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:878; }","duration":"158.103324ms","start":"2026-01-29T16:13:34.024065Z","end":"2026-01-29T16:13:34.182168Z","steps":["trace[800820970] 'agreement among raft nodes before linearized reading' (duration: 157.764466ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.182451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.877164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T16:13:34.182484Z","caller":"traceutil/trace.go:171","msg":"trace[246078347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:878; }","duration":"190.956096ms","start":"2026-01-29T16:13:33.991518Z","end":"2026-01-29T16:13:34.182474Z","steps":["trace[246078347] 'agreement among raft nodes before linearized reading' (duration: 190.821683ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.182665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.250557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-29T16:13:34.182691Z","caller":"traceutil/trace.go:171","msg":"trace[582171160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"193.290027ms","start":"2026-01-29T16:13:33.989394Z","end":"2026-01-29T16:13:34.182684Z","steps":["trace[582171160] 'agreement among raft nodes before linearized reading' (duration: 193.210985ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.896401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.508273ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:34.896496Z","caller":"traceutil/trace.go:171","msg":"trace[263834679] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:878; }","duration":"539.608535ms","start":"2026-01-29T16:13:34.356872Z","end":"2026-01-29T16:13:34.896481Z","steps":["trace[263834679] 'range keys from in-memory index tree' (duration: 539.496993ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.896857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.940049ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:13:34.896891Z","caller":"traceutil/trace.go:171","msg":"trace[544797391] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"707.574086ms","start":"2026-01-29T16:13:34.189311Z","end":"2026-01-29T16:13:34.896885Z","steps":["trace[544797391] 'process raft request' (duration: 382.568055ms)","trace[544797391] 'compare' (duration: 324.781256ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:34.896919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.189298Z","time spent":"707.606646ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:34.917855Z","caller":"traceutil/trace.go:171","msg":"trace[1770490890] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:913; }","duration":"644.492659ms","start":"2026-01-29T16:13:34.273348Z","end":"2026-01-29T16:13:34.917841Z","steps":["trace[1770490890] 'read index received' (duration: 298.62965ms)","trace[1770490890] 'applied index is now lower than readState.Index' (duration: 345.862329ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:34.917927Z","caller":"traceutil/trace.go:171","msg":"trace[221505081] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"725.792435ms","start":"2026-01-29T16:13:34.19213Z","end":"2026-01-29T16:13:34.917922Z","steps":["trace[221505081] 'process raft request' (duration: 725.633932ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.917987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.192122Z","time spent":"725.818436ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:34.918106Z","caller":"traceutil/trace.go:171","msg":"trace[1167205849] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"725.902828ms","start":"2026-01-29T16:13:34.192199Z","end":"2026-01-29T16:13:34.918102Z","steps":["trace[1167205849] 'process raft request' (duration: 725.622552ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.918132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.192194Z","time spent":"725.923819ms","remote":"127.0.0.1:46726","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-01-29T16:13:34.918109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.189453Z","time spent":"728.647088ms","remote":"127.0.0.1:46476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-29T16:13:34.918227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.876218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T16:13:34.918247Z","caller":"traceutil/trace.go:171","msg":"trace[712157157] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:881; }","duration":"644.921858ms","start":"2026-01-29T16:13:34.27332Z","end":"2026-01-29T16:13:34.918241Z","steps":["trace[712157157] 'agreement among raft nodes before linearized reading' (duration: 644.864797ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.918263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.273307Z","time spent":"644.952929ms","remote":"127.0.0.1:46798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-01-29T16:13:34.918352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.032706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:34.918365Z","caller":"traceutil/trace.go:171","msg":"trace[2091517266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:881; }","duration":"348.078447ms","start":"2026-01-29T16:13:34.570282Z","end":"2026-01-29T16:13:34.918361Z","steps":["trace[2091517266] 'agreement among raft nodes before linearized reading' (duration: 348.059037ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.918376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.570264Z","time spent":"348.109398ms","remote":"127.0.0.1:46466","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-01-29T16:13:34.918515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.775069ms","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-01-29T16:13:34.918531Z","caller":"traceutil/trace.go:171","msg":"trace[1862877110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:881; }","duration":"316.829241ms","start":"2026-01-29T16:13:34.601695Z","end":"2026-01-29T16:13:34.918524Z","steps":["trace[1862877110] 'agreement among raft nodes before linearized reading' (duration: 316.763369ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:34.918544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.601666Z","time spent":"316.874532ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-29T16:13:35.136592Z","caller":"traceutil/trace.go:171","msg":"trace[984253514] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"214.840309ms","start":"2026-01-29T16:13:34.921723Z","end":"2026-01-29T16:13:35.136563Z","steps":["trace[984253514] 'process raft request' (duration: 214.668405ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:35.289671Z","caller":"traceutil/trace.go:171","msg":"trace[2013384075] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"150.897985ms","start":"2026-01-29T16:13:35.13874Z","end":"2026-01-29T16:13:35.289638Z","steps":["trace[2013384075] 'read index received' (duration: 123.151616ms)","trace[2013384075] 'applied index is now lower than readState.Index' (duration: 27.745059ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:35.289832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.077909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-01-29T16:13:35.289868Z","caller":"traceutil/trace.go:171","msg":"trace[1356761577] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:883; }","duration":"151.178391ms","start":"2026-01-29T16:13:35.138677Z","end":"2026-01-29T16:13:35.289856Z","steps":["trace[1356761577] 'agreement among raft nodes before linearized reading' (duration: 151.076479ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:35.289861Z","caller":"traceutil/trace.go:171","msg":"trace[1260516133] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"366.695766ms","start":"2026-01-29T16:13:34.923135Z","end":"2026-01-29T16:13:35.289831Z","steps":["trace[1260516133] 'process raft request' (duration: 338.885705ms)","trace[1260516133] 'compare' (duration: 27.455724ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:35.289987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:34.923063Z","time spent":"366.854709ms","remote":"127.0.0.1:46726","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-01-29T16:13:36.355997Z","caller":"traceutil/trace.go:171","msg":"trace[1458022941] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"132.049552ms","start":"2026-01-29T16:13:36.223923Z","end":"2026-01-29T16:13:36.355973Z","steps":["trace[1458022941] 'read index received' (duration: 131.863787ms)","trace[1458022941] 'applied index is now lower than readState.Index' (duration: 184.805µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:36.356115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.170804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T16:13:36.356163Z","caller":"traceutil/trace.go:171","msg":"trace[563352991] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:885; }","duration":"132.280217ms","start":"2026-01-29T16:13:36.223875Z","end":"2026-01-29T16:13:36.356155Z","steps":["trace[563352991] 'agreement among raft nodes before linearized reading' (duration: 132.182705ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:36.356258Z","caller":"traceutil/trace.go:171","msg":"trace[1979243766] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"162.173724ms","start":"2026-01-29T16:13:36.194052Z","end":"2026-01-29T16:13:36.356225Z","steps":["trace[1979243766] 'process raft request' (duration: 161.769154ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:36.710007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.244031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:36.710088Z","caller":"traceutil/trace.go:171","msg":"trace[266044298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:885; }","duration":"145.403994ms","start":"2026-01-29T16:13:36.564663Z","end":"2026-01-29T16:13:36.710067Z","steps":["trace[266044298] 'range keys from in-memory index tree' (duration: 145.146599ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:54.687804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.291066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-01-29T16:13:54.688306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.051419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:54.68836Z","caller":"traceutil/trace.go:171","msg":"trace[2135409331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"126.11488ms","start":"2026-01-29T16:13:54.562225Z","end":"2026-01-29T16:13:54.68834Z","steps":["trace[2135409331] 'range keys from in-memory index tree' (duration: 125.986778ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:54.715972Z","caller":"traceutil/trace.go:171","msg":"trace[543804813] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:981; }","duration":"188.44759ms","start":"2026-01-29T16:13:54.49943Z","end":"2026-01-29T16:13:54.687877Z","steps":["trace[543804813] 'count revisions from in-memory index tree' (duration: 188.206014ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:13:57.160153Z","caller":"traceutil/trace.go:171","msg":"trace[1064165510] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"135.723251ms","start":"2026-01-29T16:13:57.024393Z","end":"2026-01-29T16:13:57.160116Z","steps":["trace[1064165510] 'process raft request' (duration: 134.964675ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.110169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.96999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:13:58.110338Z","caller":"traceutil/trace.go:171","msg":"trace[794680203] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"721.990148ms","start":"2026-01-29T16:13:57.388327Z","end":"2026-01-29T16:13:58.110317Z","steps":["trace[794680203] 'read index received' (duration: 300.723992ms)","trace[794680203] 'applied index is now lower than readState.Index' (duration: 421.264986ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:58.110526Z","caller":"traceutil/trace.go:171","msg":"trace[659621845] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"943.417674ms","start":"2026-01-29T16:13:57.167098Z","end":"2026-01-29T16:13:58.110515Z","steps":["trace[659621845] 'process raft request' (duration: 522.009604ms)","trace[659621845] 'compare' (duration: 420.661193ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:58.110573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:57.167031Z","time spent":"943.517775ms","remote":"127.0.0.1:46726","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-01-29T16:13:58.11071Z","caller":"traceutil/trace.go:171","msg":"trace[1256199708] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"941.766067ms","start":"2026-01-29T16:13:57.168937Z","end":"2026-01-29T16:13:58.110703Z","steps":["trace[1256199708] 'process raft request' (duration: 941.335987ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.110745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:57.168926Z","time spent":"941.799417ms","remote":"127.0.0.1:46726","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-01-29T16:13:58.110967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.647823ms","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-01-29T16:13:58.110994Z","caller":"traceutil/trace.go:171","msg":"trace[529905528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"722.712764ms","start":"2026-01-29T16:13:57.388272Z","end":"2026-01-29T16:13:58.110985Z","steps":["trace[529905528] 'agreement among raft nodes before linearized reading' (duration: 722.595502ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.111019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:57.388248Z","time spent":"722.765396ms","remote":"127.0.0.1:46726","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-01-29T16:13:58.111497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.575142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-01-29T16:13:58.111568Z","caller":"traceutil/trace.go:171","msg":"trace[236014634] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1010; }","duration":"256.696605ms","start":"2026-01-29T16:13:57.854854Z","end":"2026-01-29T16:13:58.111551Z","steps":["trace[236014634] 'agreement among raft nodes before linearized reading' (duration: 256.48236ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.111722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.703339ms","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-01-29T16:13:58.11174Z","caller":"traceutil/trace.go:171","msg":"trace[492196292] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1010; }","duration":"414.73327ms","start":"2026-01-29T16:13:57.697001Z","end":"2026-01-29T16:13:58.111734Z","steps":["trace[492196292] 'agreement among raft nodes before linearized reading' (duration: 414.680099ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.11176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:57.696994Z","time spent":"414.76062ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-01-29T16:13:58.11183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.808435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T16:13:58.111848Z","caller":"traceutil/trace.go:171","msg":"trace[527864593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1010; }","duration":"415.857166ms","start":"2026-01-29T16:13:57.695985Z","end":"2026-01-29T16:13:58.111842Z","steps":["trace[527864593] 'agreement among raft nodes before linearized reading' (duration: 415.262242ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.111864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:57.695968Z","time spent":"415.891747ms","remote":"127.0.0.1:46466","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-01-29T16:13:58.832609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.903241ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T16:13:58.832842Z","caller":"traceutil/trace.go:171","msg":"trace[54544454] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1050; }","duration":"355.283613ms","start":"2026-01-29T16:13:58.477539Z","end":"2026-01-29T16:13:58.832823Z","steps":["trace[54544454] 'read index received' (duration: 1.212638ms)","trace[54544454] 'applied index is now lower than readState.Index' (duration: 354.069835ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:13:58.833176Z","caller":"traceutil/trace.go:171","msg":"trace[2011160811] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"689.772007ms","start":"2026-01-29T16:13:58.143379Z","end":"2026-01-29T16:13:58.833151Z","steps":["trace[2011160811] 'process raft request' (duration: 99.222142ms)","trace[2011160811] 'compare' (duration: 589.666066ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:13:58.833259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:58.143353Z","time spent":"689.864139ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:58.8339Z","caller":"traceutil/trace.go:171","msg":"trace[1806912033] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"679.097627ms","start":"2026-01-29T16:13:58.154787Z","end":"2026-01-29T16:13:58.833885Z","steps":["trace[1806912033] 'process raft request' (duration: 677.938671ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.833963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:58.154772Z","time spent":"679.156668ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T16:13:58.834211Z","caller":"traceutil/trace.go:171","msg":"trace[1203108421] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"576.354496ms","start":"2026-01-29T16:13:58.257846Z","end":"2026-01-29T16:13:58.8342Z","steps":["trace[1203108421] 'process raft request' (duration: 574.936133ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.834267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:58.25781Z","time spent":"576.425367ms","remote":"127.0.0.1:46726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T16:13:58.835157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.609366ms","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-01-29T16:13:58.83534Z","caller":"traceutil/trace.go:171","msg":"trace[655186298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1013; }","duration":"357.844601ms","start":"2026-01-29T16:13:58.477483Z","end":"2026-01-29T16:13:58.835328Z","steps":["trace[655186298] 'agreement among raft nodes before linearized reading' (duration: 355.392695ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:13:58.835481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T16:13:58.477462Z","time spent":"358.009144ms","remote":"127.0.0.1:46726","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-01-29T16:14:24.229497Z","caller":"traceutil/trace.go:171","msg":"trace[1562908028] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"105.890513ms","start":"2026-01-29T16:14:24.123578Z","end":"2026-01-29T16:14:24.229469Z","steps":["trace[1562908028] 'process raft request' (duration: 105.731939ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:14:50.629885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.530771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-01-29T16:14:50.629946Z","caller":"traceutil/trace.go:171","msg":"trace[2059219040] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"144.640224ms","start":"2026-01-29T16:14:50.485293Z","end":"2026-01-29T16:14:50.629934Z","steps":["trace[2059219040] 'range keys from in-memory index tree' (duration: 144.354778ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:14:50.630058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.161993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-01-29T16:14:50.630071Z","caller":"traceutil/trace.go:171","msg":"trace[1797605358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"140.223694ms","start":"2026-01-29T16:14:50.489843Z","end":"2026-01-29T16:14:50.630067Z","steps":["trace[1797605358] 'range keys from in-memory index tree' (duration: 140.04669ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:14:50.831948Z","caller":"traceutil/trace.go:171","msg":"trace[122856885] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1275; }","duration":"135.215071ms","start":"2026-01-29T16:14:50.696714Z","end":"2026-01-29T16:14:50.831929Z","steps":["trace[122856885] 'read index received' (duration: 57.939282ms)","trace[122856885] 'applied index is now lower than readState.Index' (duration: 77.275149ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T16:14:50.832158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.416966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-01-29T16:14:50.832179Z","caller":"traceutil/trace.go:171","msg":"trace[491316920] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1225; }","duration":"135.488478ms","start":"2026-01-29T16:14:50.696683Z","end":"2026-01-29T16:14:50.832171Z","steps":["trace[491316920] 'agreement among raft nodes before linearized reading' (duration: 135.317314ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:14:50.83228Z","caller":"traceutil/trace.go:171","msg":"trace[2122301141] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"193.598785ms","start":"2026-01-29T16:14:50.638675Z","end":"2026-01-29T16:14:50.832273Z","steps":["trace[2122301141] 'process raft request' (duration: 116.076762ms)","trace[2122301141] 'compare' (duration: 77.077923ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T16:15:45.115873Z","caller":"traceutil/trace.go:171","msg":"trace[1737185196] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"133.752989ms","start":"2026-01-29T16:15:44.982045Z","end":"2026-01-29T16:15:45.115798Z","steps":["trace[1737185196] 'process raft request' (duration: 132.615713ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:17:23.35537Z","caller":"traceutil/trace.go:171","msg":"trace[2013968296] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"125.78173ms","start":"2026-01-29T16:17:23.229564Z","end":"2026-01-29T16:17:23.355346Z","steps":["trace[2013968296] 'process raft request' (duration: 125.645447ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T16:17:40.85634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.098327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:1 size:613"} {"level":"info","ts":"2026-01-29T16:17:40.856397Z","caller":"traceutil/trace.go:171","msg":"trace[1404723878] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:1; response_revision:1958; }","duration":"136.185478ms","start":"2026-01-29T16:17:40.720197Z","end":"2026-01-29T16:17:40.856383Z","steps":["trace[1404723878] 'range keys from in-memory index tree' (duration: 136.046615ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T16:17:42.048254Z","caller":"traceutil/trace.go:171","msg":"trace[1434583375] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"105.199101ms","start":"2026-01-29T16:17:41.943033Z","end":"2026-01-29T16:17:42.048232Z","steps":["trace[1434583375] 'process raft request' (duration: 105.100149ms)"],"step_count":1}