{"level":"warn","ts":"2026-02-05T22:13:21.457779Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T22:13:21.45791Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-05T22:13:21.45801Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-05T22:13:21.458046Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-05T22:13:21.458147Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T22:13:21.458849Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-05T22:13:21.45899Z","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://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-05T22:13:21.535648Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"76.244154ms"} {"level":"info","ts":"2026-02-05T22:13:21.658698Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-05T22:13:21.658892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-05T22:13:21.658966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-05T22:13:21.659018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-05T22:13:21.659059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-05T22:13:21.659137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-05T22:13:21.804748Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-05T22:13:21.811852Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-05T22:13:21.858863Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-05T22:13:21.890345Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-05T22:13:21.890824Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-05T22:13:21.890996Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T22:13:21.891039Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T22:13:21.89113Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-05T22:13:21.892764Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-05T22:13:21.892927Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-05T22:13:21.893185Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-05T22:13:21.893334Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-05T22:13:21.893377Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-05T22:13:21.893545Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-05T22:13:21.893592Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-05T22:13:22.672176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-05T22:13:22.672219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-05T22:13:22.672247Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-05T22:13:22.672258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-05T22:13:22.672264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-05T22:13:22.672272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-05T22:13:22.672278Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-05T22:13:22.676644Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:22.682349Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-05T22:13:22.682551Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:13:22.682923Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-05T22:13:22.684086Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:22.684143Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:22.68416Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-05T22:13:22.685398Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-05T22:13:22.686634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-05T22:13:22.697532Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-05T22:13:22.697551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-05T22:13:38.739043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528981378041178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:13:38.893119Z","caller":"traceutil/trace.go:171","msg":"trace[1287363475] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:388; }","duration":"654.525602ms","start":"2026-02-05T22:13:38.23858Z","end":"2026-02-05T22:13:38.893105Z","steps":["trace[1287363475] 'read index received' (duration: 654.417559ms)","trace[1287363475] 'applied index is now lower than readState.Index' (duration: 107.513µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:38.893325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.713347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:38.893421Z","caller":"traceutil/trace.go:171","msg":"trace[8906059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"654.85328ms","start":"2026-02-05T22:13:38.238549Z","end":"2026-02-05T22:13:38.893402Z","steps":["trace[8906059] 'agreement among raft nodes before linearized reading' (duration: 654.649374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.893465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.238534Z","time spent":"654.923083ms","remote":"127.0.0.1:51858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T22:13:38.893688Z","caller":"traceutil/trace.go:171","msg":"trace[1428281927] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"771.432864ms","start":"2026-02-05T22:13:38.122244Z","end":"2026-02-05T22:13:38.893677Z","steps":["trace[1428281927] 'process raft request' (duration: 770.798126ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.542184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T22:13:38.894167Z","caller":"traceutil/trace.go:171","msg":"trace[11472951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:379; }","duration":"644.590905ms","start":"2026-02-05T22:13:38.249569Z","end":"2026-02-05T22:13:38.89416Z","steps":["trace[11472951] 'agreement among raft nodes before linearized reading' (duration: 644.529123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.122227Z","time spent":"771.484685ms","remote":"127.0.0.1:52322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:13:38.894201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.249564Z","time spent":"644.618405ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-05T22:13:38.89458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.38293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:13:38.894617Z","caller":"traceutil/trace.go:171","msg":"trace[1084719334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:379; }","duration":"537.418731ms","start":"2026-02-05T22:13:38.357187Z","end":"2026-02-05T22:13:38.894606Z","steps":["trace[1084719334] 'agreement among raft nodes before linearized reading' (duration: 537.348379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.35714Z","time spent":"537.503664ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:13:38.894809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.223346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T22:13:38.894837Z","caller":"traceutil/trace.go:171","msg":"trace[1892591865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:379; }","duration":"611.276068ms","start":"2026-02-05T22:13:38.283552Z","end":"2026-02-05T22:13:38.894828Z","steps":["trace[1892591865] 'agreement among raft nodes before linearized reading' (duration: 611.228396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.283538Z","time spent":"611.319088ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:13:38.894945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.937654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"} {"level":"info","ts":"2026-02-05T22:13:38.894969Z","caller":"traceutil/trace.go:171","msg":"trace[338955516] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:379; }","duration":"580.988745ms","start":"2026-02-05T22:13:38.313973Z","end":"2026-02-05T22:13:38.894961Z","steps":["trace[338955516] 'agreement among raft nodes before linearized reading' (duration: 580.943174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:38.894993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:38.313954Z","time spent":"581.033287ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7252,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-02-05T22:13:53.332633Z","caller":"traceutil/trace.go:171","msg":"trace[1838318448] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"182.885812ms","start":"2026-02-05T22:13:53.149726Z","end":"2026-02-05T22:13:53.332612Z","steps":["trace[1838318448] 'process raft request' (duration: 181.309767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:54.48264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.744179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:54.482726Z","caller":"traceutil/trace.go:171","msg":"trace[1142872958] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"537.632987ms","start":"2026-02-05T22:13:53.945076Z","end":"2026-02-05T22:13:54.482709Z","steps":["trace[1142872958] 'process raft request' (duration: 396.655552ms)","trace[1142872958] 'compare' (duration: 140.602395ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:54.482774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:53.945055Z","time spent":"537.69808ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-05T22:13:55.2266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.319153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:13:55.226857Z","caller":"traceutil/trace.go:171","msg":"trace[1285309689] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"654.001687ms","start":"2026-02-05T22:13:54.572844Z","end":"2026-02-05T22:13:55.226846Z","steps":["trace[1285309689] 'process raft request' (duration: 653.937265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.226915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.572828Z","time spent":"654.052098ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:13:55.227227Z","caller":"traceutil/trace.go:171","msg":"trace[864121050] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"1.074932036s","start":"2026-02-05T22:13:54.15227Z","end":"2026-02-05T22:13:55.227202Z","steps":["trace[864121050] 'process raft request' (duration: 621.946753ms)","trace[864121050] 'compare' (duration: 452.083386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:55.22726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.152252Z","time spent":"1.074993508s","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:13:55.227389Z","caller":"traceutil/trace.go:171","msg":"trace[1895803407] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"882.346345ms","start":"2026-02-05T22:13:54.344999Z","end":"2026-02-05T22:13:55.227346Z","steps":["trace[1895803407] 'read index received' (duration: 429.225778ms)","trace[1895803407] 'applied index is now lower than readState.Index' (duration: 453.119817ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:13:55.227424Z","caller":"traceutil/trace.go:171","msg":"trace[2047505092] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"734.974406ms","start":"2026-02-05T22:13:54.492445Z","end":"2026-02-05T22:13:55.22742Z","steps":["trace[2047505092] 'process raft request' (duration: 734.254784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.227603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.492429Z","time spent":"735.006466ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:55.227812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.809969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:13:55.227836Z","caller":"traceutil/trace.go:171","msg":"trace[1628122364] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:516; }","duration":"882.86475ms","start":"2026-02-05T22:13:54.344963Z","end":"2026-02-05T22:13:55.227827Z","steps":["trace[1628122364] 'agreement among raft nodes before linearized reading' (duration: 882.828659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.227871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.344946Z","time spent":"882.904481ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-05T22:13:55.228148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.617452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:13:55.22822Z","caller":"traceutil/trace.go:171","msg":"trace[1482033271] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"794.729085ms","start":"2026-02-05T22:13:54.433473Z","end":"2026-02-05T22:13:55.228202Z","steps":["trace[1482033271] 'agreement among raft nodes before linearized reading' (duration: 794.512269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.22826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.433458Z","time spent":"794.793686ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-05T22:13:55.22858Z","caller":"traceutil/trace.go:171","msg":"trace[10253903] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"426.065587ms","start":"2026-02-05T22:13:54.802463Z","end":"2026-02-05T22:13:55.228528Z","steps":["trace[10253903] 'process raft request' (duration: 425.417739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.228679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.802445Z","time spent":"426.163111ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:13:55.22903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.497344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-02-05T22:13:55.229039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.027225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-05T22:13:55.229076Z","caller":"traceutil/trace.go:171","msg":"trace[461696822] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"485.082496ms","start":"2026-02-05T22:13:54.743981Z","end":"2026-02-05T22:13:55.229063Z","steps":["trace[461696822] 'agreement among raft nodes before linearized reading' (duration: 484.996493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.229103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:13:54.743969Z","time spent":"485.127617ms","remote":"127.0.0.1:51906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-05T22:13:55.229136Z","caller":"traceutil/trace.go:171","msg":"trace[141395676] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:517; }","duration":"284.565636ms","start":"2026-02-05T22:13:54.944492Z","end":"2026-02-05T22:13:55.229058Z","steps":["trace[141395676] 'agreement among raft nodes before linearized reading' (duration: 284.398042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.229335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.45607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:13:55.229364Z","caller":"traceutil/trace.go:171","msg":"trace[1366474696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"157.493381ms","start":"2026-02-05T22:13:55.071862Z","end":"2026-02-05T22:13:55.229355Z","steps":["trace[1366474696] 'agreement among raft nodes before linearized reading' (duration: 157.417109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.229422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.529499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T22:13:55.229453Z","caller":"traceutil/trace.go:171","msg":"trace[898993069] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"215.581111ms","start":"2026-02-05T22:13:55.013863Z","end":"2026-02-05T22:13:55.229444Z","steps":["trace[898993069] 'agreement among raft nodes before linearized reading' (duration: 215.52732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.229546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.701538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T22:13:55.229568Z","caller":"traceutil/trace.go:171","msg":"trace[1869979237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"157.743089ms","start":"2026-02-05T22:13:55.071818Z","end":"2026-02-05T22:13:55.229561Z","steps":["trace[1869979237] 'agreement among raft nodes before linearized reading' (duration: 157.704488ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.657015Z","caller":"traceutil/trace.go:171","msg":"trace[959447357] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"288.167131ms","start":"2026-02-05T22:13:55.368822Z","end":"2026-02-05T22:13:55.656989Z","steps":["trace[959447357] 'process raft request' (duration: 288.066788ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.657272Z","caller":"traceutil/trace.go:171","msg":"trace[1416563733] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:540; }","duration":"266.005983ms","start":"2026-02-05T22:13:55.391235Z","end":"2026-02-05T22:13:55.657241Z","steps":["trace[1416563733] 'read index received' (duration: 265.999523ms)","trace[1416563733] 'applied index is now lower than readState.Index' (duration: 5.26µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:55.657424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.175388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-05T22:13:55.657444Z","caller":"traceutil/trace.go:171","msg":"trace[1944757096] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:524; }","duration":"266.24687ms","start":"2026-02-05T22:13:55.39119Z","end":"2026-02-05T22:13:55.657437Z","steps":["trace[1944757096] 'agreement among raft nodes before linearized reading' (duration: 266.134687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.664427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.42097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:13:55.664461Z","caller":"traceutil/trace.go:171","msg":"trace[1012340796] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"189.487352ms","start":"2026-02-05T22:13:55.474966Z","end":"2026-02-05T22:13:55.664453Z","steps":["trace[1012340796] 'agreement among raft nodes before linearized reading' (duration: 189.381379ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.664804Z","caller":"traceutil/trace.go:171","msg":"trace[1854290835] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"149.218973ms","start":"2026-02-05T22:13:55.515579Z","end":"2026-02-05T22:13:55.664798Z","steps":["trace[1854290835] 'process raft request' (duration: 148.623196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.66504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.328501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-05T22:13:55.665073Z","caller":"traceutil/trace.go:171","msg":"trace[357386781] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:525; }","duration":"120.368952ms","start":"2026-02-05T22:13:55.544694Z","end":"2026-02-05T22:13:55.665063Z","steps":["trace[357386781] 'agreement among raft nodes before linearized reading' (duration: 120.259819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:13:55.665143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.265918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T22:13:55.665161Z","caller":"traceutil/trace.go:171","msg":"trace[1104790986] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:525; }","duration":"138.312549ms","start":"2026-02-05T22:13:55.526842Z","end":"2026-02-05T22:13:55.665154Z","steps":["trace[1104790986] 'agreement among raft nodes before linearized reading' (duration: 138.283108ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.885723Z","caller":"traceutil/trace.go:171","msg":"trace[750509182] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"204.104843ms","start":"2026-02-05T22:13:55.681594Z","end":"2026-02-05T22:13:55.885699Z","steps":["trace[750509182] 'process raft request' (duration: 197.205834ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.88623Z","caller":"traceutil/trace.go:171","msg":"trace[2112275266] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"140.372178ms","start":"2026-02-05T22:13:55.745842Z","end":"2026-02-05T22:13:55.886214Z","steps":["trace[2112275266] 'read index received' (duration: 133.032567ms)","trace[2112275266] 'applied index is now lower than readState.Index' (duration: 7.338921ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:13:55.886382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.522272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-05T22:13:55.886404Z","caller":"traceutil/trace.go:171","msg":"trace[1969749593] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:528; }","duration":"140.584294ms","start":"2026-02-05T22:13:55.745813Z","end":"2026-02-05T22:13:55.886397Z","steps":["trace[1969749593] 'agreement among raft nodes before linearized reading' (duration: 140.455451ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.886881Z","caller":"traceutil/trace.go:171","msg":"trace[261872909] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"204.664868ms","start":"2026-02-05T22:13:55.681786Z","end":"2026-02-05T22:13:55.886451Z","steps":["trace[261872909] 'process raft request' (duration: 204.0238ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:13:55.88691Z","caller":"traceutil/trace.go:171","msg":"trace[1538988838] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"105.738733ms","start":"2026-02-05T22:13:55.781163Z","end":"2026-02-05T22:13:55.886902Z","steps":["trace[1538988838] 'process raft request' (duration: 104.818697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.760273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.783186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:14:01.761583Z","caller":"traceutil/trace.go:171","msg":"trace[1129194043] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"435.397146ms","start":"2026-02-05T22:14:01.326166Z","end":"2026-02-05T22:14:01.761563Z","steps":["trace[1129194043] 'process raft request' (duration: 159.125546ms)","trace[1129194043] 'compare' (duration: 274.496319ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:01.761756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:01.32615Z","time spent":"435.481398ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:01.76244Z","caller":"traceutil/trace.go:171","msg":"trace[999767192] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:563; }","duration":"116.46926ms","start":"2026-02-05T22:14:01.645932Z","end":"2026-02-05T22:14:01.762401Z","steps":["trace[999767192] 'read index received' (duration: 112.488307ms)","trace[999767192] 'applied index is now lower than readState.Index' (duration: 3.980143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:01.762613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.658225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:01.76356Z","caller":"traceutil/trace.go:171","msg":"trace[1590973823] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:548; }","duration":"117.33881ms","start":"2026-02-05T22:14:01.645912Z","end":"2026-02-05T22:14:01.763251Z","steps":["trace[1590973823] 'agreement among raft nodes before linearized reading' (duration: 116.628874ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.764106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.061487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:01.764151Z","caller":"traceutil/trace.go:171","msg":"trace[804716844] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:548; }","duration":"118.129768ms","start":"2026-02-05T22:14:01.646011Z","end":"2026-02-05T22:14:01.764141Z","steps":["trace[804716844] 'agreement among raft nodes before linearized reading' (duration: 118.047647ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:01.762698Z","caller":"traceutil/trace.go:171","msg":"trace[2025322723] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"432.938819ms","start":"2026-02-05T22:14:01.32975Z","end":"2026-02-05T22:14:01.762689Z","steps":["trace[2025322723] 'process raft request' (duration: 431.687629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.764799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:01.329732Z","time spent":"434.983277ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:01.764913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.64467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:01.762759Z","caller":"traceutil/trace.go:171","msg":"trace[2053608046] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"429.739385ms","start":"2026-02-05T22:14:01.33298Z","end":"2026-02-05T22:14:01.762719Z","steps":["trace[2053608046] 'process raft request' (duration: 429.340995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:01.7655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:01.332971Z","time spent":"432.500699ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:01.764965Z","caller":"traceutil/trace.go:171","msg":"trace[2085224815] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:548; }","duration":"118.682191ms","start":"2026-02-05T22:14:01.646256Z","end":"2026-02-05T22:14:01.764938Z","steps":["trace[2085224815] 'agreement among raft nodes before linearized reading' (duration: 118.62935ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:02.996123Z","caller":"traceutil/trace.go:171","msg":"trace[1626049914] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"586.71967ms","start":"2026-02-05T22:14:02.409377Z","end":"2026-02-05T22:14:02.996096Z","steps":["trace[1626049914] 'process raft request' (duration: 586.517906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:02.997125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:02.409352Z","time spent":"587.406327ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:03.620616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.522996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T22:14:03.620701Z","caller":"traceutil/trace.go:171","msg":"trace[1601388239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"294.691171ms","start":"2026-02-05T22:14:03.325989Z","end":"2026-02-05T22:14:03.62068Z","steps":["trace[1601388239] 'range keys from in-memory index tree' (duration: 294.368873ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:03.996521Z","caller":"traceutil/trace.go:171","msg":"trace[1225467026] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"371.315791ms","start":"2026-02-05T22:14:03.625183Z","end":"2026-02-05T22:14:03.996499Z","steps":["trace[1225467026] 'process raft request' (duration: 371.174338ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:03.996624Z","caller":"traceutil/trace.go:171","msg":"trace[638655720] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:572; }","duration":"222.784652ms","start":"2026-02-05T22:14:03.773828Z","end":"2026-02-05T22:14:03.996612Z","steps":["trace[638655720] 'read index received' (duration: 222.779392ms)","trace[638655720] 'applied index is now lower than readState.Index' (duration: 3.95µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:03.996698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:03.625138Z","time spent":"371.482355ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:03.996794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.567367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:14:03.996823Z","caller":"traceutil/trace.go:171","msg":"trace[102778531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"222.666079ms","start":"2026-02-05T22:14:03.774148Z","end":"2026-02-05T22:14:03.996814Z","steps":["trace[102778531] 'agreement among raft nodes before linearized reading' (duration: 222.542016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:03.996947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.113059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T22:14:03.996995Z","caller":"traceutil/trace.go:171","msg":"trace[634802110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:554; }","duration":"223.16192ms","start":"2026-02-05T22:14:03.773824Z","end":"2026-02-05T22:14:03.996986Z","steps":["trace[634802110] 'agreement among raft nodes before linearized reading' (duration: 223.069718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:03.997115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.470181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:14:03.997141Z","caller":"traceutil/trace.go:171","msg":"trace[964715081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:554; }","duration":"221.510593ms","start":"2026-02-05T22:14:03.775622Z","end":"2026-02-05T22:14:03.997133Z","steps":["trace[964715081] 'agreement among raft nodes before linearized reading' (duration: 221.439011ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:04.108574Z","caller":"traceutil/trace.go:171","msg":"trace[1902556544] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"106.492286ms","start":"2026-02-05T22:14:04.002065Z","end":"2026-02-05T22:14:04.108557Z","steps":["trace[1902556544] 'process raft request' (duration: 106.315162ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:04.112154Z","caller":"traceutil/trace.go:171","msg":"trace[1443896002] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"107.958731ms","start":"2026-02-05T22:14:04.004182Z","end":"2026-02-05T22:14:04.112141Z","steps":["trace[1443896002] 'process raft request' (duration: 107.655374ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:04.115757Z","caller":"traceutil/trace.go:171","msg":"trace[2106633279] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"109.765614ms","start":"2026-02-05T22:14:04.005978Z","end":"2026-02-05T22:14:04.115744Z","steps":["trace[2106633279] 'process raft request' (duration: 105.947694ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:04.117396Z","caller":"traceutil/trace.go:171","msg":"trace[373869089] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"110.598302ms","start":"2026-02-05T22:14:04.006767Z","end":"2026-02-05T22:14:04.117365Z","steps":["trace[373869089] 'process raft request' (duration: 108.929733ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:04.761918Z","caller":"traceutil/trace.go:171","msg":"trace[1169711853] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"155.205434ms","start":"2026-02-05T22:14:04.606687Z","end":"2026-02-05T22:14:04.761893Z","steps":["trace[1169711853] 'process raft request' (duration: 127.143689ms)","trace[1169711853] 'compare' (duration: 27.960563ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:14.444328Z","caller":"traceutil/trace.go:171","msg":"trace[1105898528] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"281.123565ms","start":"2026-02-05T22:14:14.163184Z","end":"2026-02-05T22:14:14.444308Z","steps":["trace[1105898528] 'read index received' (duration: 280.998682ms)","trace[1105898528] 'applied index is now lower than readState.Index' (duration: 124.273µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:14.444573Z","caller":"traceutil/trace.go:171","msg":"trace[1830074003] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"394.840399ms","start":"2026-02-05T22:14:14.049727Z","end":"2026-02-05T22:14:14.444568Z","steps":["trace[1830074003] 'process raft request' (duration: 394.480701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.444638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.049718Z","time spent":"394.870459ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:14.444779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.594736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T22:14:14.444797Z","caller":"traceutil/trace.go:171","msg":"trace[985421558] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"281.641007ms","start":"2026-02-05T22:14:14.163149Z","end":"2026-02-05T22:14:14.44479Z","steps":["trace[985421558] 'agreement among raft nodes before linearized reading' (duration: 281.578706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.444894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.406107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:14:14.444911Z","caller":"traceutil/trace.go:171","msg":"trace[1706293265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:616; }","duration":"274.436158ms","start":"2026-02-05T22:14:14.170471Z","end":"2026-02-05T22:14:14.444907Z","steps":["trace[1706293265] 'agreement among raft nodes before linearized reading' (duration: 274.383797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.445123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.948433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:14.445144Z","caller":"traceutil/trace.go:171","msg":"trace[1505718910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"200.997293ms","start":"2026-02-05T22:14:14.244141Z","end":"2026-02-05T22:14:14.445138Z","steps":["trace[1505718910] 'agreement among raft nodes before linearized reading' (duration: 200.967402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.445273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.365312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T22:14:14.445298Z","caller":"traceutil/trace.go:171","msg":"trace[1718490002] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:616; }","duration":"264.390912ms","start":"2026-02-05T22:14:14.180895Z","end":"2026-02-05T22:14:14.445286Z","steps":["trace[1718490002] 'agreement among raft nodes before linearized reading' (duration: 264.351672ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:14.445113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.483463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T22:14:14.446313Z","caller":"traceutil/trace.go:171","msg":"trace[1695767680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:616; }","duration":"270.715291ms","start":"2026-02-05T22:14:14.175575Z","end":"2026-02-05T22:14:14.44629Z","steps":["trace[1695767680] 'agreement among raft nodes before linearized reading' (duration: 269.40375ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:15.023687Z","caller":"traceutil/trace.go:171","msg":"trace[355296077] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"572.938768ms","start":"2026-02-05T22:14:14.450717Z","end":"2026-02-05T22:14:15.023656Z","steps":["trace[355296077] 'process raft request' (duration: 540.618853ms)","trace[355296077] 'compare' (duration: 31.548227ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:15.023838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.450703Z","time spent":"573.05522ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:15.295901Z","caller":"traceutil/trace.go:171","msg":"trace[1060457740] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"845.127624ms","start":"2026-02-05T22:14:14.450754Z","end":"2026-02-05T22:14:15.295881Z","steps":["trace[1060457740] 'process raft request' (duration: 844.856726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:15.296032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.450741Z","time spent":"845.227716ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:15.296226Z","caller":"traceutil/trace.go:171","msg":"trace[1120201461] linearizableReadLoop","detail":"{readStateIndex:641; appliedIndex:637; }","duration":"718.905336ms","start":"2026-02-05T22:14:14.577313Z","end":"2026-02-05T22:14:15.296218Z","steps":["trace[1120201461] 'read index received' (duration: 414.030838ms)","trace[1120201461] 'applied index is now lower than readState.Index' (duration: 304.873838ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:15.296273Z","caller":"traceutil/trace.go:171","msg":"trace[1177894626] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"845.471062ms","start":"2026-02-05T22:14:14.450798Z","end":"2026-02-05T22:14:15.296269Z","steps":["trace[1177894626] 'process raft request' (duration: 844.898377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:15.296301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.450794Z","time spent":"845.492492ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:15.296412Z","caller":"traceutil/trace.go:171","msg":"trace[685546562] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"845.567724ms","start":"2026-02-05T22:14:14.450838Z","end":"2026-02-05T22:14:15.296406Z","steps":["trace[685546562] 'process raft request' (duration: 844.880467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:15.296442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.450835Z","time spent":"845.592284ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:15.296609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.296445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8dqtp\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-05T22:14:15.29663Z","caller":"traceutil/trace.go:171","msg":"trace[951691414] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8dqtp; range_end:; response_count:1; response_revision:620; }","duration":"719.344607ms","start":"2026-02-05T22:14:14.57728Z","end":"2026-02-05T22:14:15.296625Z","steps":["trace[951691414] 'agreement among raft nodes before linearized reading' (duration: 718.963688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:15.296653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:14.57726Z","time spent":"719.389827ms","remote":"127.0.0.1:52028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18270,"request content":"key:\"/registry/pods/kube-system/cilium-8dqtp\" "} {"level":"info","ts":"2026-02-05T22:14:21.071339Z","caller":"traceutil/trace.go:171","msg":"trace[1681105936] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"524.433515ms","start":"2026-02-05T22:14:20.546885Z","end":"2026-02-05T22:14:21.071319Z","steps":["trace[1681105936] 'process raft request' (duration: 524.336143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:21.071452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:20.546871Z","time spent":"524.524018ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:22.495605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.994166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T22:14:22.495679Z","caller":"traceutil/trace.go:171","msg":"trace[172833318] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:716; }","duration":"1.446434171s","start":"2026-02-05T22:14:21.049233Z","end":"2026-02-05T22:14:22.495667Z","steps":["trace[172833318] 'read index received' (duration: 22.535256ms)","trace[172833318] 'applied index is now lower than readState.Index' (duration: 1.423897985s)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:22.495799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.446564275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:14:22.495816Z","caller":"traceutil/trace.go:171","msg":"trace[663316386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.446613096s","start":"2026-02-05T22:14:21.049197Z","end":"2026-02-05T22:14:22.49581Z","steps":["trace[663316386] 'agreement among raft nodes before linearized reading' (duration: 1.446524293s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:22.495831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:21.049182Z","time spent":"1.446646107s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:22.496221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153527827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T22:14:22.496237Z","caller":"traceutil/trace.go:171","msg":"trace[1810106567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.153577529s","start":"2026-02-05T22:14:21.342654Z","end":"2026-02-05T22:14:22.496232Z","steps":["trace[1810106567] 'agreement among raft nodes before linearized reading' (duration: 1.153531737s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:22.49625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:21.342621Z","time spent":"1.153625999s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:22.496407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.966316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:22.496428Z","caller":"traceutil/trace.go:171","msg":"trace[2022771521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:694; }","duration":"255.013387ms","start":"2026-02-05T22:14:22.24141Z","end":"2026-02-05T22:14:22.496424Z","steps":["trace[2022771521] 'agreement among raft nodes before linearized reading' (duration: 254.907364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:22.496704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153968499s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:14:22.496727Z","caller":"traceutil/trace.go:171","msg":"trace[1351634667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"1.154002431s","start":"2026-02-05T22:14:21.34272Z","end":"2026-02-05T22:14:22.496723Z","steps":["trace[1351634667] 'agreement among raft nodes before linearized reading' (duration: 1.153950569s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:22.496741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:21.342699Z","time spent":"1.154037501s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:22.496894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154187655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:14:22.496914Z","caller":"traceutil/trace.go:171","msg":"trace[1760757526] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"1.154236377s","start":"2026-02-05T22:14:21.342668Z","end":"2026-02-05T22:14:22.496905Z","steps":["trace[1760757526] 'agreement among raft nodes before linearized reading' (duration: 1.154184265s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:22.496933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:21.342648Z","time spent":"1.154281998s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:26.739897Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528981378041817,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:14:27.040242Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.510960202s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T22:14:27.040777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:25.529187Z","time spent":"1.51158461s","remote":"127.0.0.1:51902","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-05T22:14:27.069833Z","caller":"traceutil/trace.go:171","msg":"trace[443917824] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:728; }","duration":"830.2295ms","start":"2026-02-05T22:14:26.239581Z","end":"2026-02-05T22:14:27.06981Z","steps":["trace[443917824] 'read index received' (duration: 802.366381ms)","trace[443917824] 'applied index is now lower than readState.Index' (duration: 27.862469ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:27.069903Z","caller":"traceutil/trace.go:171","msg":"trace[1486331757] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"1.381584699s","start":"2026-02-05T22:14:25.688312Z","end":"2026-02-05T22:14:27.069897Z","steps":["trace[1486331757] 'process raft request' (duration: 1.381332321s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.069992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:25.688298Z","time spent":"1.38162295s","remote":"127.0.0.1:51944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-05T22:14:27.070269Z","caller":"traceutil/trace.go:171","msg":"trace[1739516952] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"1.359581649s","start":"2026-02-05T22:14:25.710679Z","end":"2026-02-05T22:14:27.070261Z","steps":["trace[1739516952] 'process raft request' (duration: 1.359085954s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.070313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:25.71067Z","time spent":"1.35961587s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:14:27.07041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"830.828128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:27.070446Z","caller":"traceutil/trace.go:171","msg":"trace[105882863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"830.862729ms","start":"2026-02-05T22:14:26.239577Z","end":"2026-02-05T22:14:27.07044Z","steps":["trace[105882863] 'agreement among raft nodes before linearized reading' (duration: 830.815398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.070463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.23953Z","time spent":"830.929091ms","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T22:14:27.305496Z","caller":"traceutil/trace.go:171","msg":"trace[1783111393] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"235.58676ms","start":"2026-02-05T22:14:27.069877Z","end":"2026-02-05T22:14:27.305464Z","steps":["trace[1783111393] 'read index received' (duration: 235.369703ms)","trace[1783111393] 'applied index is now lower than readState.Index' (duration: 216.417µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:27.305649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.325059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-05T22:14:27.305686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.756592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:14:27.305702Z","caller":"traceutil/trace.go:171","msg":"trace[63748127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:707; }","duration":"780.411542ms","start":"2026-02-05T22:14:26.525277Z","end":"2026-02-05T22:14:27.305689Z","steps":["trace[63748127] 'agreement among raft nodes before linearized reading' (duration: 780.198766ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.30571Z","caller":"traceutil/trace.go:171","msg":"trace[1077759411] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:707; }","duration":"780.811263ms","start":"2026-02-05T22:14:26.524892Z","end":"2026-02-05T22:14:27.305703Z","steps":["trace[1077759411] 'agreement among raft nodes before linearized reading' (duration: 780.685739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.305733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.525273Z","time spent":"780.455033ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:27.305736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.524883Z","time spent":"780.847614ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:27.305865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.078041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:14:27.305883Z","caller":"traceutil/trace.go:171","msg":"trace[1975536994] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"263.618673ms","start":"2026-02-05T22:14:27.042255Z","end":"2026-02-05T22:14:27.305874Z","steps":["trace[1975536994] 'process raft request' (duration: 263.079558ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.305884Z","caller":"traceutil/trace.go:171","msg":"trace[400504448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:707; }","duration":"788.113803ms","start":"2026-02-05T22:14:26.517763Z","end":"2026-02-05T22:14:27.305877Z","steps":["trace[400504448] 'agreement among raft nodes before linearized reading' (duration: 788.054701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.305901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.517758Z","time spent":"788.139444ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:27.306043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.80874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:14:27.30606Z","caller":"traceutil/trace.go:171","msg":"trace[2081480886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:707; }","duration":"790.859661ms","start":"2026-02-05T22:14:26.515195Z","end":"2026-02-05T22:14:27.306055Z","steps":["trace[2081480886] 'agreement among raft nodes before linearized reading' (duration: 790.81291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.306075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:26.515182Z","time spent":"790.889362ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:27.306101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.655794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:27.306117Z","caller":"traceutil/trace.go:171","msg":"trace[1938101760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"233.687136ms","start":"2026-02-05T22:14:27.072425Z","end":"2026-02-05T22:14:27.306112Z","steps":["trace[1938101760] 'agreement among raft nodes before linearized reading' (duration: 233.662435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.306231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.876029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-05T22:14:27.306246Z","caller":"traceutil/trace.go:171","msg":"trace[1376961296] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:707; }","duration":"135.90774ms","start":"2026-02-05T22:14:27.170332Z","end":"2026-02-05T22:14:27.30624Z","steps":["trace[1376961296] 'agreement among raft nodes before linearized reading' (duration: 135.858639ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.429447Z","caller":"traceutil/trace.go:171","msg":"trace[2142594981] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:731; }","duration":"114.30042ms","start":"2026-02-05T22:14:27.315127Z","end":"2026-02-05T22:14:27.429428Z","steps":["trace[2142594981] 'read index received' (duration: 64.47408ms)","trace[2142594981] 'applied index is now lower than readState.Index' (duration: 49.82558ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:27.429652Z","caller":"traceutil/trace.go:171","msg":"trace[1143750730] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"115.853204ms","start":"2026-02-05T22:14:27.31379Z","end":"2026-02-05T22:14:27.429643Z","steps":["trace[1143750730] 'process raft request' (duration: 65.738536ms)","trace[1143750730] 'compare' (duration: 49.603513ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:27.429776Z","caller":"traceutil/trace.go:171","msg":"trace[1449131605] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"115.540065ms","start":"2026-02-05T22:14:27.31423Z","end":"2026-02-05T22:14:27.42977Z","steps":["trace[1449131605] 'process raft request' (duration: 115.105422ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.42985Z","caller":"traceutil/trace.go:171","msg":"trace[66097811] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"115.549055ms","start":"2026-02-05T22:14:27.314296Z","end":"2026-02-05T22:14:27.429845Z","steps":["trace[66097811] 'process raft request' (duration: 115.065461ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.429924Z","caller":"traceutil/trace.go:171","msg":"trace[1530733728] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"114.840995ms","start":"2026-02-05T22:14:27.315078Z","end":"2026-02-05T22:14:27.429919Z","steps":["trace[1530733728] 'process raft request' (duration: 114.3078ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:27.429999Z","caller":"traceutil/trace.go:171","msg":"trace[1018416368] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"111.535811ms","start":"2026-02-05T22:14:27.318454Z","end":"2026-02-05T22:14:27.42999Z","steps":["trace[1018416368] 'process raft request' (duration: 110.953174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.430141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.997479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-05T22:14:27.430166Z","caller":"traceutil/trace.go:171","msg":"trace[1327417735] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:712; }","duration":"115.036231ms","start":"2026-02-05T22:14:27.315124Z","end":"2026-02-05T22:14:27.43016Z","steps":["trace[1327417735] 'agreement among raft nodes before linearized reading' (duration: 114.937308ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:27.430255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.497308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:27.430275Z","caller":"traceutil/trace.go:171","msg":"trace[1494369562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"112.527009ms","start":"2026-02-05T22:14:27.317744Z","end":"2026-02-05T22:14:27.430271Z","steps":["trace[1494369562] 'agreement among raft nodes before linearized reading' (duration: 112.498448ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:39.675678Z","caller":"traceutil/trace.go:171","msg":"trace[1011256630] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"161.511393ms","start":"2026-02-05T22:14:39.514152Z","end":"2026-02-05T22:14:39.675663Z","steps":["trace[1011256630] 'read index received' (duration: 161.487553ms)","trace[1011256630] 'applied index is now lower than readState.Index' (duration: 23.31µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:14:39.675777Z","caller":"traceutil/trace.go:171","msg":"trace[1663321491] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"168.581627ms","start":"2026-02-05T22:14:39.507189Z","end":"2026-02-05T22:14:39.675771Z","steps":["trace[1663321491] 'process raft request' (duration: 168.382351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:39.675903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.73573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:14:39.675933Z","caller":"traceutil/trace.go:171","msg":"trace[1504743786] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:747; }","duration":"161.784021ms","start":"2026-02-05T22:14:39.51414Z","end":"2026-02-05T22:14:39.675924Z","steps":["trace[1504743786] 'agreement among raft nodes before linearized reading' (duration: 161.686058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:39.683585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.324717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T22:14:39.683623Z","caller":"traceutil/trace.go:171","msg":"trace[914950221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"154.38021ms","start":"2026-02-05T22:14:39.529233Z","end":"2026-02-05T22:14:39.683613Z","steps":["trace[914950221] 'agreement among raft nodes before linearized reading' (duration: 154.259876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:39.683749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.471321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:14:39.683773Z","caller":"traceutil/trace.go:171","msg":"trace[137002054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"139.508353ms","start":"2026-02-05T22:14:39.544257Z","end":"2026-02-05T22:14:39.683766Z","steps":["trace[137002054] 'agreement among raft nodes before linearized reading' (duration: 139.431601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:39.683824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.798064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:14:39.683842Z","caller":"traceutil/trace.go:171","msg":"trace[241397722] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"113.832855ms","start":"2026-02-05T22:14:39.570004Z","end":"2026-02-05T22:14:39.683837Z","steps":["trace[241397722] 'agreement among raft nodes before linearized reading' (duration: 113.781644ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:14:44.108147Z","caller":"traceutil/trace.go:171","msg":"trace[143235917] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"416.676935ms","start":"2026-02-05T22:14:43.69146Z","end":"2026-02-05T22:14:44.108137Z","steps":["trace[143235917] 'process raft request' (duration: 416.428987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.108933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.69144Z","time spent":"417.441697ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:44.108017Z","caller":"traceutil/trace.go:171","msg":"trace[229447076] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:784; }","duration":"381.963679ms","start":"2026-02-05T22:14:43.726039Z","end":"2026-02-05T22:14:44.108002Z","steps":["trace[229447076] 'read index received' (duration: 381.790534ms)","trace[229447076] 'applied index is now lower than readState.Index' (duration: 172.555µs)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:44.108838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.787963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:44.109173Z","caller":"traceutil/trace.go:171","msg":"trace[1488041559] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:758; }","duration":"383.161464ms","start":"2026-02-05T22:14:43.726Z","end":"2026-02-05T22:14:44.109162Z","steps":["trace[1488041559] 'agreement among raft nodes before linearized reading' (duration: 382.774503ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.109206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.725968Z","time spent":"383.231116ms","remote":"127.0.0.1:52138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:14:44.109388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.929491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:14:44.109412Z","caller":"traceutil/trace.go:171","msg":"trace[1203088016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"333.985023ms","start":"2026-02-05T22:14:43.775421Z","end":"2026-02-05T22:14:44.109406Z","steps":["trace[1203088016] 'agreement among raft nodes before linearized reading' (duration: 333.89473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.109433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.775404Z","time spent":"334.024434ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:14:44.109622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.725321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:14:44.109645Z","caller":"traceutil/trace.go:171","msg":"trace[209600507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"329.755652ms","start":"2026-02-05T22:14:43.779883Z","end":"2026-02-05T22:14:44.109639Z","steps":["trace[209600507] 'agreement among raft nodes before linearized reading' (duration: 329.69984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.109664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.77988Z","time spent":"329.781272ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:44.10977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.897985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-05T22:14:44.10979Z","caller":"traceutil/trace.go:171","msg":"trace[649452897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"329.934247ms","start":"2026-02-05T22:14:43.779851Z","end":"2026-02-05T22:14:44.109785Z","steps":["trace[649452897] 'agreement among raft nodes before linearized reading' (duration: 329.885405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.10981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.779843Z","time spent":"329.963267ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:44.109914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.346425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:14:44.109936Z","caller":"traceutil/trace.go:171","msg":"trace[790079364] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"332.386176ms","start":"2026-02-05T22:14:43.777543Z","end":"2026-02-05T22:14:44.10993Z","steps":["trace[790079364] 'agreement among raft nodes before linearized reading' (duration: 332.333174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:44.109956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:43.777533Z","time spent":"332.419007ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T22:14:44.742585Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528981378041959,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-05T22:14:45.093982Z","caller":"traceutil/trace.go:171","msg":"trace[1696175542] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"980.295125ms","start":"2026-02-05T22:14:44.113662Z","end":"2026-02-05T22:14:45.093957Z","steps":["trace[1696175542] 'process raft request' (duration: 980.168172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:45.09413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:44.113647Z","time spent":"980.401109ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:45.118036Z","caller":"traceutil/trace.go:171","msg":"trace[1994201579] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:786; }","duration":"876.022595ms","start":"2026-02-05T22:14:44.24198Z","end":"2026-02-05T22:14:45.118002Z","steps":["trace[1994201579] 'read index received' (duration: 852.563192ms)","trace[1994201579] 'applied index is now lower than readState.Index' (duration: 23.458573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:14:45.11814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.153948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:14:45.118177Z","caller":"traceutil/trace.go:171","msg":"trace[1813158691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"876.24008ms","start":"2026-02-05T22:14:44.241926Z","end":"2026-02-05T22:14:45.118166Z","steps":["trace[1813158691] 'agreement among raft nodes before linearized reading' (duration: 876.158538ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:45.118213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:44.241903Z","time spent":"876.302112ms","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-05T22:14:45.118473Z","caller":"traceutil/trace.go:171","msg":"trace[1269998704] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.004124509s","start":"2026-02-05T22:14:44.114337Z","end":"2026-02-05T22:14:45.118461Z","steps":["trace[1269998704] 'process raft request' (duration: 1.003523952s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:45.118551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:44.114329Z","time spent":"1.004176641s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:45.119213Z","caller":"traceutil/trace.go:171","msg":"trace[624821790] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.004001475s","start":"2026-02-05T22:14:44.115195Z","end":"2026-02-05T22:14:45.119197Z","steps":["trace[624821790] 'process raft request' (duration: 1.00274304s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:45.119309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:44.115185Z","time spent":"1.004082607s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:45.119819Z","caller":"traceutil/trace.go:171","msg":"trace[529523771] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.004074969s","start":"2026-02-05T22:14:44.115674Z","end":"2026-02-05T22:14:45.119749Z","steps":["trace[529523771] 'process raft request' (duration: 1.002299388s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:45.119896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:44.115666Z","time spent":"1.004192012s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:14:58.532668Z","caller":"traceutil/trace.go:171","msg":"trace[2062143396] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"309.617014ms","start":"2026-02-05T22:14:58.223034Z","end":"2026-02-05T22:14:58.532651Z","steps":["trace[2062143396] 'process raft request' (duration: 309.528322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:14:58.532775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:14:58.223014Z","time spent":"309.701416ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:11.491179Z","caller":"traceutil/trace.go:171","msg":"trace[207257681] linearizableReadLoop","detail":"{readStateIndex:923; appliedIndex:922; }","duration":"234.80897ms","start":"2026-02-05T22:15:11.256354Z","end":"2026-02-05T22:15:11.491163Z","steps":["trace[207257681] 'read index received' (duration: 234.681527ms)","trace[207257681] 'applied index is now lower than readState.Index' (duration: 126.893µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:11.49126Z","caller":"traceutil/trace.go:171","msg":"trace[282426229] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"249.658847ms","start":"2026-02-05T22:15:11.241595Z","end":"2026-02-05T22:15:11.491254Z","steps":["trace[282426229] 'process raft request' (duration: 249.463362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:11.491408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.035466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T22:15:11.491424Z","caller":"traceutil/trace.go:171","msg":"trace[642761979] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:888; }","duration":"235.095627ms","start":"2026-02-05T22:15:11.256323Z","end":"2026-02-05T22:15:11.491419Z","steps":["trace[642761979] 'agreement among raft nodes before linearized reading' (duration: 235.012465ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:11.49158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.081252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T22:15:11.491631Z","caller":"traceutil/trace.go:171","msg":"trace[1799546675] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:888; }","duration":"223.147574ms","start":"2026-02-05T22:15:11.268464Z","end":"2026-02-05T22:15:11.491611Z","steps":["trace[1799546675] 'agreement among raft nodes before linearized reading' (duration: 223.03119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:11.49166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.782319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:15:11.491676Z","caller":"traceutil/trace.go:171","msg":"trace[702150701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:888; }","duration":"197.821491ms","start":"2026-02-05T22:15:11.29385Z","end":"2026-02-05T22:15:11.491672Z","steps":["trace[702150701] 'agreement among raft nodes before linearized reading' (duration: 197.77968ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:11.794257Z","caller":"traceutil/trace.go:171","msg":"trace[1983276599] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"299.895624ms","start":"2026-02-05T22:15:11.494347Z","end":"2026-02-05T22:15:11.794242Z","steps":["trace[1983276599] 'process raft request' (duration: 290.395479ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:11.846335Z","caller":"traceutil/trace.go:171","msg":"trace[1245851751] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"347.950455ms","start":"2026-02-05T22:15:11.49837Z","end":"2026-02-05T22:15:11.84632Z","steps":["trace[1245851751] 'process raft request' (duration: 347.924974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:11.84643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.498363Z","time spent":"348.016807ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:11.846645Z","caller":"traceutil/trace.go:171","msg":"trace[1763590709] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"352.044465ms","start":"2026-02-05T22:15:11.494594Z","end":"2026-02-05T22:15:11.846639Z","steps":["trace[1763590709] 'process raft request' (duration: 351.619545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:11.846676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:11.494581Z","time spent":"352.075956ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:13.33824Z","caller":"traceutil/trace.go:171","msg":"trace[1859957536] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"307.074551ms","start":"2026-02-05T22:15:13.031142Z","end":"2026-02-05T22:15:13.338217Z","steps":["trace[1859957536] 'read index received' (duration: 306.874846ms)","trace[1859957536] 'applied index is now lower than readState.Index' (duration: 198.475µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:13.338574Z","caller":"traceutil/trace.go:171","msg":"trace[1645120828] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"644.363765ms","start":"2026-02-05T22:15:12.694199Z","end":"2026-02-05T22:15:13.338563Z","steps":["trace[1645120828] 'process raft request' (duration: 643.904303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.338706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:12.694189Z","time spent":"644.431726ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:13.338904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.759728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:13.338991Z","caller":"traceutil/trace.go:171","msg":"trace[1260315634] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:892; }","duration":"307.897561ms","start":"2026-02-05T22:15:13.031057Z","end":"2026-02-05T22:15:13.338955Z","steps":["trace[1260315634] 'agreement among raft nodes before linearized reading' (duration: 307.813659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:13.339023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.031021Z","time spent":"307.993413ms","remote":"127.0.0.1:33634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:14.479155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.606441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T22:15:14.479213Z","caller":"traceutil/trace.go:171","msg":"trace[2142448411] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:892; }","duration":"681.719204ms","start":"2026-02-05T22:15:13.797481Z","end":"2026-02-05T22:15:14.4792Z","steps":["trace[2142448411] 'range keys from in-memory index tree' (duration: 681.483748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.479238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.797464Z","time spent":"681.768955ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-05T22:15:14.479341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.405817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-05T22:15:14.479387Z","caller":"traceutil/trace.go:171","msg":"trace[1723145003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:892; }","duration":"629.487749ms","start":"2026-02-05T22:15:13.849888Z","end":"2026-02-05T22:15:14.479375Z","steps":["trace[1723145003] 'range keys from in-memory index tree' (duration: 629.292084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.479408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.849872Z","time spent":"629.53147ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-05T22:15:14.47943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.538761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T22:15:14.479447Z","caller":"traceutil/trace.go:171","msg":"trace[659403954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:892; }","duration":"982.577072ms","start":"2026-02-05T22:15:13.496864Z","end":"2026-02-05T22:15:14.479441Z","steps":["trace[659403954] 'range keys from in-memory index tree' (duration: 982.475309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.47946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.496848Z","time spent":"982.609173ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-05T22:15:14.479499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.291407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:14.479513Z","caller":"traceutil/trace.go:171","msg":"trace[1730797107] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:892; }","duration":"926.330068ms","start":"2026-02-05T22:15:13.553176Z","end":"2026-02-05T22:15:14.479506Z","steps":["trace[1730797107] 'count revisions from in-memory index tree' (duration: 926.229605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.479531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.553162Z","time spent":"926.365028ms","remote":"127.0.0.1:52214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:14.479552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.400142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-05T22:15:14.479569Z","caller":"traceutil/trace.go:171","msg":"trace[559857823] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:892; }","duration":"628.435362ms","start":"2026-02-05T22:15:13.851129Z","end":"2026-02-05T22:15:14.479564Z","steps":["trace[559857823] 'range keys from in-memory index tree' (duration: 628.33697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.479582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:13.851119Z","time spent":"628.459823ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-05T22:15:14.479794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.41986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:14.47981Z","caller":"traceutil/trace.go:171","msg":"trace[37082268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:892; }","duration":"240.46599ms","start":"2026-02-05T22:15:14.239338Z","end":"2026-02-05T22:15:14.479804Z","steps":["trace[37082268] 'range keys from in-memory index tree' (duration: 240.379568ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:14.987819Z","caller":"traceutil/trace.go:171","msg":"trace[191434521] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"491.48406ms","start":"2026-02-05T22:15:14.496316Z","end":"2026-02-05T22:15:14.9878Z","steps":["trace[191434521] 'process raft request' (duration: 491.331105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:14.987931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:14.496298Z","time spent":"491.576173ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:15.153839Z","caller":"traceutil/trace.go:171","msg":"trace[844913920] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"654.435072ms","start":"2026-02-05T22:15:14.499378Z","end":"2026-02-05T22:15:15.153813Z","steps":["trace[844913920] 'process raft request' (duration: 650.3013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:15.154323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:14.49937Z","time spent":"654.898303ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:15.154435Z","caller":"traceutil/trace.go:171","msg":"trace[489224275] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"655.011466ms","start":"2026-02-05T22:15:14.499416Z","end":"2026-02-05T22:15:15.154428Z","steps":["trace[489224275] 'process raft request' (duration: 654.276128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:15.154467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:14.499412Z","time spent":"655.037877ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:15.155343Z","caller":"traceutil/trace.go:171","msg":"trace[801919891] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"655.893987ms","start":"2026-02-05T22:15:14.499441Z","end":"2026-02-05T22:15:15.155335Z","steps":["trace[801919891] 'process raft request' (duration: 654.33785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:15.158915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:14.499438Z","time spent":"659.448075ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:17.328312Z","caller":"traceutil/trace.go:171","msg":"trace[1866307372] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"158.877223ms","start":"2026-02-05T22:15:17.169421Z","end":"2026-02-05T22:15:17.328298Z","steps":["trace[1866307372] 'process raft request' (duration: 158.77929ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:17.348676Z","caller":"traceutil/trace.go:171","msg":"trace[1670078984] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"177.812788ms","start":"2026-02-05T22:15:17.170852Z","end":"2026-02-05T22:15:17.348665Z","steps":["trace[1670078984] 'process raft request' (duration: 177.529841ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:17.34885Z","caller":"traceutil/trace.go:171","msg":"trace[771143021] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"177.364166ms","start":"2026-02-05T22:15:17.17148Z","end":"2026-02-05T22:15:17.348844Z","steps":["trace[771143021] 'process raft request' (duration: 176.955066ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:17.465378Z","caller":"traceutil/trace.go:171","msg":"trace[1310664666] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"111.468014ms","start":"2026-02-05T22:15:17.353895Z","end":"2026-02-05T22:15:17.465363Z","steps":["trace[1310664666] 'process raft request' (duration: 67.617304ms)","trace[1310664666] 'compare' (duration: 43.602164ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:19.470697Z","caller":"traceutil/trace.go:171","msg":"trace[1559892885] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"417.629992ms","start":"2026-02-05T22:15:19.053051Z","end":"2026-02-05T22:15:19.470681Z","steps":["trace[1559892885] 'read index received' (duration: 417.469398ms)","trace[1559892885] 'applied index is now lower than readState.Index' (duration: 159.894µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:19.470976Z","caller":"traceutil/trace.go:171","msg":"trace[239759457] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"454.10663ms","start":"2026-02-05T22:15:19.016858Z","end":"2026-02-05T22:15:19.470965Z","steps":["trace[239759457] 'process raft request' (duration: 453.70561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.471058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:19.016825Z","time spent":"454.178642ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:19.47124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.183905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:19.47127Z","caller":"traceutil/trace.go:171","msg":"trace[26840675] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:905; }","duration":"418.213386ms","start":"2026-02-05T22:15:19.053047Z","end":"2026-02-05T22:15:19.47126Z","steps":["trace[26840675] 'agreement among raft nodes before linearized reading' (duration: 418.156425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.471297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:19.053009Z","time spent":"418.280638ms","remote":"127.0.0.1:52160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:19.471429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.315316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:19.471454Z","caller":"traceutil/trace.go:171","msg":"trace[1199653858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"231.371917ms","start":"2026-02-05T22:15:19.240075Z","end":"2026-02-05T22:15:19.471446Z","steps":["trace[1199653858] 'agreement among raft nodes before linearized reading' (duration: 231.336866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.471804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.097408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T22:15:19.471831Z","caller":"traceutil/trace.go:171","msg":"trace[590306039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:905; }","duration":"116.219901ms","start":"2026-02-05T22:15:19.355603Z","end":"2026-02-05T22:15:19.471823Z","steps":["trace[590306039] 'agreement among raft nodes before linearized reading' (duration: 116.154909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.471997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.681892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:15:19.472107Z","caller":"traceutil/trace.go:171","msg":"trace[2141378923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:905; }","duration":"116.790545ms","start":"2026-02-05T22:15:19.355308Z","end":"2026-02-05T22:15:19.472099Z","steps":["trace[2141378923] 'agreement among raft nodes before linearized reading' (duration: 116.62309ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.472266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.080203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-05T22:15:19.472289Z","caller":"traceutil/trace.go:171","msg":"trace[2100134501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"141.137815ms","start":"2026-02-05T22:15:19.331145Z","end":"2026-02-05T22:15:19.472282Z","steps":["trace[2100134501] 'agreement among raft nodes before linearized reading' (duration: 141.071713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:19.759508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.264704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:19.75965Z","caller":"traceutil/trace.go:171","msg":"trace[436315353] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"278.041385ms","start":"2026-02-05T22:15:19.481598Z","end":"2026-02-05T22:15:19.759639Z","steps":["trace[436315353] 'process raft request' (duration: 278.015664ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:19.759805Z","caller":"traceutil/trace.go:171","msg":"trace[1626937699] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"281.912841ms","start":"2026-02-05T22:15:19.477887Z","end":"2026-02-05T22:15:19.7598Z","steps":["trace[1626937699] 'process raft request' (duration: 104.280858ms)","trace[1626937699] 'compare' (duration: 177.017848ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:19.75986Z","caller":"traceutil/trace.go:171","msg":"trace[1326419432] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"281.88724ms","start":"2026-02-05T22:15:19.477969Z","end":"2026-02-05T22:15:19.759856Z","steps":["trace[1326419432] 'process raft request' (duration: 281.589863ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:19.759936Z","caller":"traceutil/trace.go:171","msg":"trace[1884145503] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"278.429334ms","start":"2026-02-05T22:15:19.481503Z","end":"2026-02-05T22:15:19.759932Z","steps":["trace[1884145503] 'process raft request' (duration: 278.080326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:21.089698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.655259ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-05T22:15:21.089789Z","caller":"traceutil/trace.go:171","msg":"trace[1666244140] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"469.340446ms","start":"2026-02-05T22:15:20.620432Z","end":"2026-02-05T22:15:21.089773Z","steps":["trace[1666244140] 'read index received' (duration: 173.534113ms)","trace[1666244140] 'applied index is now lower than readState.Index' (duration: 295.803272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:21.089898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.450738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-05T22:15:21.089936Z","caller":"traceutil/trace.go:171","msg":"trace[411231710] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:910; }","duration":"469.497789ms","start":"2026-02-05T22:15:20.620427Z","end":"2026-02-05T22:15:21.089925Z","steps":["trace[411231710] 'agreement among raft nodes before linearized reading' (duration: 469.391207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:21.089972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:20.620373Z","time spent":"469.590231ms","remote":"127.0.0.1:52296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:22.440715Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528981378042349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-05T22:15:22.884605Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096918196s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-05T22:15:23.110254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:21.966743Z","time spent":"1.143506214s","remote":"127.0.0.1:46202","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-05T22:15:23.111034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.203179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-05T22:15:23.111073Z","caller":"traceutil/trace.go:171","msg":"trace[2025325416] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"1.323419952s","start":"2026-02-05T22:15:21.787643Z","end":"2026-02-05T22:15:23.111063Z","steps":["trace[2025325416] 'process raft request' (duration: 1.097143081s)","trace[2025325416] 'compare' (duration: 225.977454ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:23.111105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:21.787633Z","time spent":"1.323455453s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:23.113765Z","caller":"traceutil/trace.go:171","msg":"trace[1161579199] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:950; }","duration":"1.173706217s","start":"2026-02-05T22:15:21.940051Z","end":"2026-02-05T22:15:23.113757Z","steps":["trace[1161579199] 'read index received' (duration: 944.744349ms)","trace[1161579199] 'applied index is now lower than readState.Index' (duration: 228.961328ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:23.114108Z","caller":"traceutil/trace.go:171","msg":"trace[559496180] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"1.326387975s","start":"2026-02-05T22:15:21.787714Z","end":"2026-02-05T22:15:23.114102Z","steps":["trace[559496180] 'process raft request' (duration: 1.326003016s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.114168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:21.78771Z","time spent":"1.326417657s","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:23.114248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174201829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:23.114265Z","caller":"traceutil/trace.go:171","msg":"trace[411935338] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:915; }","duration":"1.174217349s","start":"2026-02-05T22:15:21.940041Z","end":"2026-02-05T22:15:23.114259Z","steps":["trace[411935338] 'agreement among raft nodes before linearized reading' (duration: 1.174189318s)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.114313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:21.940007Z","time spent":"1.174302661s","remote":"127.0.0.1:52068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-05T22:15:23.11447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.426583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:23.114489Z","caller":"traceutil/trace.go:171","msg":"trace[416073524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"875.466613ms","start":"2026-02-05T22:15:22.239017Z","end":"2026-02-05T22:15:23.114484Z","steps":["trace[416073524] 'agreement among raft nodes before linearized reading' (duration: 875.438623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.114501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:22.239003Z","time spent":"875.495705ms","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T22:15:23.68516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.826276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:23.685207Z","caller":"traceutil/trace.go:171","msg":"trace[2119332936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"443.911338ms","start":"2026-02-05T22:15:23.241286Z","end":"2026-02-05T22:15:23.685197Z","steps":["trace[2119332936] 'range keys from in-memory index tree' (duration: 443.725264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.685228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:23.241271Z","time spent":"443.953019ms","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-05T22:15:23.685433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.633325ms","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-02-05T22:15:23.685449Z","caller":"traceutil/trace.go:171","msg":"trace[1964729235] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:915; }","duration":"212.649565ms","start":"2026-02-05T22:15:23.472794Z","end":"2026-02-05T22:15:23.685443Z","steps":["trace[1964729235] 'count revisions from in-memory index tree' (duration: 212.590934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:23.685595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.621684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-05T22:15:23.685631Z","caller":"traceutil/trace.go:171","msg":"trace[809053624] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"186.663835ms","start":"2026-02-05T22:15:23.49894Z","end":"2026-02-05T22:15:23.685604Z","steps":["trace[809053624] 'range keys from in-memory index tree' (duration: 186.532582ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:26.078022Z","caller":"traceutil/trace.go:171","msg":"trace[2071802397] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"199.765092ms","start":"2026-02-05T22:15:25.878242Z","end":"2026-02-05T22:15:26.078007Z","steps":["trace[2071802397] 'read index received' (duration: 199.65602ms)","trace[2071802397] 'applied index is now lower than readState.Index' (duration: 108.352µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:26.078102Z","caller":"traceutil/trace.go:171","msg":"trace[2118103405] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"307.104298ms","start":"2026-02-05T22:15:25.770992Z","end":"2026-02-05T22:15:26.078097Z","steps":["trace[2118103405] 'process raft request' (duration: 306.912524ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:26.078173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:25.770982Z","time spent":"307.134389ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:15:26.078353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.971178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-05T22:15:26.078396Z","caller":"traceutil/trace.go:171","msg":"trace[1121320169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:930; }","duration":"159.0306ms","start":"2026-02-05T22:15:25.919355Z","end":"2026-02-05T22:15:26.078385Z","steps":["trace[1121320169] 'agreement among raft nodes before linearized reading' (duration: 158.909117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:26.078448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.203273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-05T22:15:26.07847Z","caller":"traceutil/trace.go:171","msg":"trace[189522836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"200.224323ms","start":"2026-02-05T22:15:25.878239Z","end":"2026-02-05T22:15:26.078463Z","steps":["trace[189522836] 'agreement among raft nodes before linearized reading' (duration: 200.142991ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:28.136172Z","caller":"traceutil/trace.go:171","msg":"trace[1287912289] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"298.834816ms","start":"2026-02-05T22:15:27.837324Z","end":"2026-02-05T22:15:28.136158Z","steps":["trace[1287912289] 'process raft request' (duration: 298.757264ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:28.13771Z","caller":"traceutil/trace.go:171","msg":"trace[1540065338] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"153.247662ms","start":"2026-02-05T22:15:27.984448Z","end":"2026-02-05T22:15:28.137695Z","steps":["trace[1540065338] 'process raft request' (duration: 153.038988ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:28.664093Z","caller":"traceutil/trace.go:171","msg":"trace[1176411383] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"204.543389ms","start":"2026-02-05T22:15:28.459532Z","end":"2026-02-05T22:15:28.664075Z","steps":["trace[1176411383] 'process raft request' (duration: 204.442446ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:34.303939Z","caller":"traceutil/trace.go:171","msg":"trace[371647662] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"109.033894ms","start":"2026-02-05T22:15:34.194885Z","end":"2026-02-05T22:15:34.303919Z","steps":["trace[371647662] 'process raft request' (duration: 73.888746ms)","trace[371647662] 'compare' (duration: 34.966654ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:34.304395Z","caller":"traceutil/trace.go:171","msg":"trace[607008668] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1007; }","duration":"105.458045ms","start":"2026-02-05T22:15:34.198917Z","end":"2026-02-05T22:15:34.304375Z","steps":["trace[607008668] 'read index received' (duration: 69.818696ms)","trace[607008668] 'applied index is now lower than readState.Index' (duration: 35.638399ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:15:34.304568Z","caller":"traceutil/trace.go:171","msg":"trace[1049882044] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"109.32252ms","start":"2026-02-05T22:15:34.195128Z","end":"2026-02-05T22:15:34.30445Z","steps":["trace[1049882044] 'process raft request' (duration: 109.107395ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:34.304668Z","caller":"traceutil/trace.go:171","msg":"trace[1469919605] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"108.721797ms","start":"2026-02-05T22:15:34.195932Z","end":"2026-02-05T22:15:34.304654Z","steps":["trace[1469919605] 'process raft request' (duration: 108.40758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:34.305015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.087159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:34.306624Z","caller":"traceutil/trace.go:171","msg":"trace[1708415571] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:971; }","duration":"107.703095ms","start":"2026-02-05T22:15:34.198908Z","end":"2026-02-05T22:15:34.306611Z","steps":["trace[1708415571] 'agreement among raft nodes before linearized reading' (duration: 106.073888ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:37.719738Z","caller":"traceutil/trace.go:171","msg":"trace[1275161963] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"375.498962ms","start":"2026-02-05T22:15:37.344214Z","end":"2026-02-05T22:15:37.719713Z","steps":["trace[1275161963] 'process raft request' (duration: 375.352179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:37.719878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:37.344206Z","time spent":"375.599094ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:15:37.744739Z","caller":"traceutil/trace.go:171","msg":"trace[1704532403] linearizableReadLoop","detail":"{readStateIndex:1022; appliedIndex:1021; }","duration":"221.637447ms","start":"2026-02-05T22:15:37.523092Z","end":"2026-02-05T22:15:37.744729Z","steps":["trace[1704532403] 'read index received' (duration: 197.337768ms)","trace[1704532403] 'applied index is now lower than readState.Index' (duration: 24.299149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:15:37.744815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.715388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-05T22:15:37.744836Z","caller":"traceutil/trace.go:171","msg":"trace[1155108864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"221.769179ms","start":"2026-02-05T22:15:37.523059Z","end":"2026-02-05T22:15:37.744828Z","steps":["trace[1155108864] 'agreement among raft nodes before linearized reading' (duration: 221.710407ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:15:37.744978Z","caller":"traceutil/trace.go:171","msg":"trace[1658571253] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"306.945774ms","start":"2026-02-05T22:15:37.438025Z","end":"2026-02-05T22:15:37.744971Z","steps":["trace[1658571253] 'process raft request' (duration: 306.625427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:15:37.745025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:15:37.438006Z","time spent":"306.988515ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:16:54.290602Z","caller":"traceutil/trace.go:171","msg":"trace[1108340077] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"182.421819ms","start":"2026-02-05T22:16:54.108159Z","end":"2026-02-05T22:16:54.290581Z","steps":["trace[1108340077] 'read index received' (duration: 182.249055ms)","trace[1108340077] 'applied index is now lower than readState.Index' (duration: 171.954µs)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:16:54.291485Z","caller":"traceutil/trace.go:171","msg":"trace[1517495336] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"242.853038ms","start":"2026-02-05T22:16:54.0486Z","end":"2026-02-05T22:16:54.291453Z","steps":["trace[1517495336] 'process raft request' (duration: 241.865845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:16:54.292381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.20386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-05T22:16:54.29264Z","caller":"traceutil/trace.go:171","msg":"trace[1574131271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1317; }","duration":"184.315772ms","start":"2026-02-05T22:16:54.108116Z","end":"2026-02-05T22:16:54.292431Z","steps":["trace[1574131271] 'agreement among raft nodes before linearized reading' (duration: 183.554695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:16:54.292939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.67464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-05T22:16:54.29303Z","caller":"traceutil/trace.go:171","msg":"trace[836999196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1317; }","duration":"184.810383ms","start":"2026-02-05T22:16:54.108202Z","end":"2026-02-05T22:16:54.293013Z","steps":["trace[836999196] 'agreement among raft nodes before linearized reading' (duration: 184.611449ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:16:54.579846Z","caller":"traceutil/trace.go:171","msg":"trace[1240757326] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"281.761691ms","start":"2026-02-05T22:16:54.29806Z","end":"2026-02-05T22:16:54.579821Z","steps":["trace[1240757326] 'process raft request' (duration: 228.350481ms)","trace[1240757326] 'compare' (duration: 53.327918ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:16:54.610903Z","caller":"traceutil/trace.go:171","msg":"trace[285393320] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1372; }","duration":"143.940135ms","start":"2026-02-05T22:16:54.466885Z","end":"2026-02-05T22:16:54.610825Z","steps":["trace[285393320] 'read index received' (duration: 59.475567ms)","trace[285393320] 'applied index is now lower than readState.Index' (duration: 84.463688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-05T22:16:54.611313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.410785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-05T22:16:54.611457Z","caller":"traceutil/trace.go:171","msg":"trace[1255223242] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1320; }","duration":"144.584849ms","start":"2026-02-05T22:16:54.466859Z","end":"2026-02-05T22:16:54.611444Z","steps":["trace[1255223242] 'agreement among raft nodes before linearized reading' (duration: 144.248852ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:16:54.611961Z","caller":"traceutil/trace.go:171","msg":"trace[1516436510] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"312.277077ms","start":"2026-02-05T22:16:54.29967Z","end":"2026-02-05T22:16:54.611947Z","steps":["trace[1516436510] 'process raft request' (duration: 310.959257ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:16:54.612733Z","caller":"traceutil/trace.go:171","msg":"trace[19184236] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"311.805566ms","start":"2026-02-05T22:16:54.300911Z","end":"2026-02-05T22:16:54.612717Z","steps":["trace[19184236] 'process raft request' (duration: 309.846062ms)"],"step_count":1} {"level":"warn","ts":"2026-02-05T22:16:54.612944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:16:54.300902Z","time spent":"311.870197ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-05T22:16:54.613095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-05T22:16:54.299653Z","time spent":"312.583603ms","remote":"127.0.0.1:52096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-05T22:16:57.031906Z","caller":"traceutil/trace.go:171","msg":"trace[1135693828] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"112.330833ms","start":"2026-02-05T22:16:56.919556Z","end":"2026-02-05T22:16:57.031887Z","steps":["trace[1135693828] 'process raft request' (duration: 112.199061ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:16:57.045347Z","caller":"traceutil/trace.go:171","msg":"trace[437640154] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"125.598463ms","start":"2026-02-05T22:16:56.919727Z","end":"2026-02-05T22:16:57.045325Z","steps":["trace[437640154] 'process raft request' (duration: 125.510431ms)"],"step_count":1} {"level":"info","ts":"2026-02-05T22:17:02.829338Z","caller":"traceutil/trace.go:171","msg":"trace[1805399186] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"109.696635ms","start":"2026-02-05T22:17:02.719623Z","end":"2026-02-05T22:17:02.82932Z","steps":["trace[1805399186] 'process raft request' (duration: 68.49215ms)","trace[1805399186] 'compare' (duration: 41.110783ms)"],"step_count":2} {"level":"info","ts":"2026-02-05T22:18:45.440054Z","caller":"traceutil/trace.go:171","msg":"trace[254297009] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"108.078093ms","start":"2026-02-05T22:18:45.331956Z","end":"2026-02-05T22:18:45.440034Z","steps":["trace[254297009] 'process raft request' (duration: 61.604819ms)","trace[254297009] 'compare' (duration: 46.015494ms)"],"step_count":2}