{"level":"warn","ts":"2026-02-25T19:27:25.791415Z","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-25T19:27:25.791508Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.59:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.59:2380","--initial-cluster=instance=https://199.19.213.59:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.59:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.59: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-25T19:27:25.791718Z","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-25T19:27:25.79174Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-02-25T19:27:25.791808Z","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-25T19:27:25.792562Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"]} {"level":"info","ts":"2026-02-25T19:27:25.792649Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.59: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-25T19:27:27.337207Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.544309191s"} {"level":"info","ts":"2026-02-25T19:27:27.922679Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"41c06612bf92f90","cluster-id":"7f792254ff9520b"} {"level":"info","ts":"2026-02-25T19:27:27.922858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T19:27:27.922933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 0"} {"level":"info","ts":"2026-02-25T19:27:27.923006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 41c06612bf92f90 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T19:27:27.923023Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became follower at term 1"} {"level":"info","ts":"2026-02-25T19:27:27.923091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"warn","ts":"2026-02-25T19:27:27.9925Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T19:27:28.043095Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T19:27:28.206074Z","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-25T19:27:28.250682Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"41c06612bf92f90","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T19:27:28.251012Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"41c06612bf92f90","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T19:27:28.251139Z","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-25T19:27:28.251227Z","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-25T19:27:28.251244Z","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-25T19:27:28.258321Z","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-25T19:27:28.258448Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-02-25T19:27:28.258501Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.59:2380"} {"level":"info","ts":"2026-02-25T19:27:28.258639Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"41c06612bf92f90","initial-advertise-peer-urls":["https://199.19.213.59:2380"],"listen-peer-urls":["https://199.19.213.59:2380"],"advertise-client-urls":["https://199.19.213.59:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.59:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T19:27:28.258687Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T19:27:28.313185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 switched to configuration voters=(296118689918955408)"} {"level":"info","ts":"2026-02-25T19:27:28.313432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","added-peer-id":"41c06612bf92f90","added-peer-peer-urls":["https://199.19.213.59:2380"]} {"level":"info","ts":"2026-02-25T19:27:28.923701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.923766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.923809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgPreVoteResp from 41c06612bf92f90 at term 1"} {"level":"info","ts":"2026-02-25T19:27:28.92383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became candidate at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.923841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 received MsgVoteResp from 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.923856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"41c06612bf92f90 became leader at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.923869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 41c06612bf92f90 elected leader 41c06612bf92f90 at term 2"} {"level":"info","ts":"2026-02-25T19:27:28.925064Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"41c06612bf92f90","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.59:2379]}","request-path":"/0/members/41c06612bf92f90/attributes","cluster-id":"7f792254ff9520b","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T19:27:28.925119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.925085Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:28.925208Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T19:27:28.925414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T19:27:28.925526Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T19:27:28.925784Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7f792254ff9520b","local-member-id":"41c06612bf92f90","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.925953Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.926012Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T19:27:28.928989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.59:2379"} {"level":"info","ts":"2026-02-25T19:27:28.92926Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T19:27:30.687241Z","caller":"traceutil/trace.go:171","msg":"trace[866405770] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"457.610756ms","start":"2026-02-25T19:27:30.229602Z","end":"2026-02-25T19:27:30.687213Z","steps":["trace[866405770] 'process raft request' (duration: 457.438545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.687859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.229591Z","time spent":"457.732947ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:30.694374Z","caller":"traceutil/trace.go:171","msg":"trace[738932376] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"462.927573ms","start":"2026-02-25T19:27:30.231423Z","end":"2026-02-25T19:27:30.694351Z","steps":["trace[738932376] 'read index received' (duration: 455.560857ms)","trace[738932376] 'applied index is now lower than readState.Index' (duration: 7.362506ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:30.694402Z","caller":"traceutil/trace.go:171","msg":"trace[1581113813] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"464.632439ms","start":"2026-02-25T19:27:30.22974Z","end":"2026-02-25T19:27:30.694372Z","steps":["trace[1581113813] 'process raft request' (duration: 464.221385ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:30.694504Z","caller":"traceutil/trace.go:171","msg":"trace[469383601] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"424.547579ms","start":"2026-02-25T19:27:30.269937Z","end":"2026-02-25T19:27:30.694484Z","steps":["trace[469383601] 'process raft request' (duration: 424.178016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.694548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.229734Z","time spent":"464.73509ms","remote":"127.0.0.1:38192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:30.694599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.269912Z","time spent":"424.61753ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:30.694781Z","caller":"traceutil/trace.go:171","msg":"trace[2097431565] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"384.47773ms","start":"2026-02-25T19:27:30.310285Z","end":"2026-02-25T19:27:30.694763Z","steps":["trace[2097431565] 'process raft request' (duration: 384.008205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.694811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.310262Z","time spent":"384.533061ms","remote":"127.0.0.1:38344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:30.694931Z","caller":"traceutil/trace.go:171","msg":"trace[2136700603] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"424.733031ms","start":"2026-02-25T19:27:30.270158Z","end":"2026-02-25T19:27:30.694891Z","steps":["trace[2136700603] 'process raft request' (duration: 423.991804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.694998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.327644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.59\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:30.695031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.691761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.695046Z","caller":"traceutil/trace.go:171","msg":"trace[682122033] range","detail":"{range_begin:/registry/masterleases/199.19.213.59; range_end:; response_count:0; response_revision:11; }","duration":"427.402045ms","start":"2026-02-25T19:27:30.267631Z","end":"2026-02-25T19:27:30.695033Z","steps":["trace[682122033] 'agreement among raft nodes before linearized reading' (duration: 427.247664ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:30.695059Z","caller":"traceutil/trace.go:171","msg":"trace[1205472825] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"317.786192ms","start":"2026-02-25T19:27:30.377266Z","end":"2026-02-25T19:27:30.695052Z","steps":["trace[1205472825] 'agreement among raft nodes before linearized reading' (duration: 317.721572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.267613Z","time spent":"427.451505ms","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.59\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.377243Z","time spent":"317.835902ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270138Z","time spent":"424.885942ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:30.695176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.140393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.695199Z","caller":"traceutil/trace.go:171","msg":"trace[2007364094] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"339.188633ms","start":"2026-02-25T19:27:30.356001Z","end":"2026-02-25T19:27:30.69519Z","steps":["trace[2007364094] 'agreement among raft nodes before linearized reading' (duration: 339.158223ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.355987Z","time spent":"339.222914ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-02-25T19:27:30.695243Z","caller":"traceutil/trace.go:171","msg":"trace[1554335152] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"368.79617ms","start":"2026-02-25T19:27:30.32644Z","end":"2026-02-25T19:27:30.695236Z","steps":["trace[1554335152] 'process raft request' (duration: 367.880432ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.326418Z","time spent":"368.84149ms","remote":"127.0.0.1:38444","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:30.695277Z","caller":"traceutil/trace.go:171","msg":"trace[1821624189] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"424.976673ms","start":"2026-02-25T19:27:30.270284Z","end":"2026-02-25T19:27:30.695261Z","steps":["trace[1821624189] 'process raft request' (duration: 423.893273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.69532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.356609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.695341Z","caller":"traceutil/trace.go:171","msg":"trace[214430158] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"375.409249ms","start":"2026-02-25T19:27:30.319926Z","end":"2026-02-25T19:27:30.695335Z","steps":["trace[214430158] 'agreement among raft nodes before linearized reading' (duration: 375.366768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.319912Z","time spent":"375.438479ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.960412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-02-25T19:27:30.695406Z","caller":"traceutil/trace.go:171","msg":"trace[282941070] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"463.994872ms","start":"2026-02-25T19:27:30.231405Z","end":"2026-02-25T19:27:30.6954Z","steps":["trace[282941070] 'agreement among raft nodes before linearized reading' (duration: 463.931102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.231399Z","time spent":"464.022013ms","remote":"127.0.0.1:38344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3192,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.145163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.695455Z","caller":"traceutil/trace.go:171","msg":"trace[544962649] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"417.183583ms","start":"2026-02-25T19:27:30.278267Z","end":"2026-02-25T19:27:30.695451Z","steps":["trace[544962649] 'agreement among raft nodes before linearized reading' (duration: 417.154053ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.278256Z","time spent":"417.228503ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270274Z","time spent":"425.040624ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:30.695741Z","caller":"traceutil/trace.go:171","msg":"trace[2138720362] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"425.369586ms","start":"2026-02-25T19:27:30.27036Z","end":"2026-02-25T19:27:30.69573Z","steps":["trace[2138720362] 'process raft request' (duration: 423.878763ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:30.695771Z","caller":"traceutil/trace.go:171","msg":"trace[1260998733] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"425.319336ms","start":"2026-02-25T19:27:30.270446Z","end":"2026-02-25T19:27:30.695765Z","steps":["trace[1260998733] 'process raft request' (duration: 423.822152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270349Z","time spent":"425.420177ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:30.695524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.126805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.695733Z","caller":"traceutil/trace.go:171","msg":"trace[1534657247] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"425.362926ms","start":"2026-02-25T19:27:30.270338Z","end":"2026-02-25T19:27:30.695701Z","steps":["trace[1534657247] 'process raft request' (duration: 423.880323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270331Z","time spent":"425.532998ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:30.695872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270435Z","time spent":"425.353476ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:30.69589Z","caller":"traceutil/trace.go:171","msg":"trace[251309551] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"318.520898ms","start":"2026-02-25T19:27:30.377327Z","end":"2026-02-25T19:27:30.695848Z","steps":["trace[251309551] 'agreement among raft nodes before linearized reading' (duration: 318.176605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.695958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.377316Z","time spent":"318.626669ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:27:30.695982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.81767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:30.696045Z","caller":"traceutil/trace.go:171","msg":"trace[659409362] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"425.883771ms","start":"2026-02-25T19:27:30.270146Z","end":"2026-02-25T19:27:30.69603Z","steps":["trace[659409362] 'agreement among raft nodes before linearized reading' (duration: 425.77663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.696021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.677379ms","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:4"} {"level":"warn","ts":"2026-02-25T19:27:30.696094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270103Z","time spent":"425.982591ms","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-25T19:27:30.696104Z","caller":"traceutil/trace.go:171","msg":"trace[186371074] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"425.81676ms","start":"2026-02-25T19:27:30.270266Z","end":"2026-02-25T19:27:30.696083Z","steps":["trace[186371074] 'agreement among raft nodes before linearized reading' (duration: 425.591598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:30.696171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.270258Z","time spent":"425.900901ms","remote":"127.0.0.1:38226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-25T19:27:31.795162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.549161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:31.795461Z","caller":"traceutil/trace.go:171","msg":"trace[1062488468] linearizableReadLoop","detail":"{readStateIndex:70; appliedIndex:67; }","duration":"891.284942ms","start":"2026-02-25T19:27:30.904164Z","end":"2026-02-25T19:27:31.795449Z","steps":["trace[1062488468] 'read index received' (duration: 380.184976ms)","trace[1062488468] 'applied index is now lower than readState.Index' (duration: 511.099346ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:31.795544Z","caller":"traceutil/trace.go:171","msg":"trace[132200289] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"986.293615ms","start":"2026-02-25T19:27:30.80921Z","end":"2026-02-25T19:27:31.795503Z","steps":["trace[132200289] 'process raft request' (duration: 986.138843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.795683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.515564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:31.795714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.809192Z","time spent":"986.449716ms","remote":"127.0.0.1:38666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":565,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:31.795576Z","caller":"traceutil/trace.go:171","msg":"trace[1812643341] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"985.670449ms","start":"2026-02-25T19:27:30.809897Z","end":"2026-02-25T19:27:31.795567Z","steps":["trace[1812643341] 'process raft request' (duration: 985.496678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.795958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.008103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:31.796018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.809877Z","time spent":"986.082213ms","remote":"127.0.0.1:38660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1116,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:31.79606Z","caller":"traceutil/trace.go:171","msg":"trace[680016317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:65; }","duration":"494.145664ms","start":"2026-02-25T19:27:31.301892Z","end":"2026-02-25T19:27:31.796037Z","steps":["trace[680016317] 'agreement among raft nodes before linearized reading' (duration: 493.956133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.795888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.119391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:31.79633Z","caller":"traceutil/trace.go:171","msg":"trace[79869071] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:65; }","duration":"628.596965ms","start":"2026-02-25T19:27:31.167719Z","end":"2026-02-25T19:27:31.796316Z","steps":["trace[79869071] 'agreement among raft nodes before linearized reading' (duration: 628.116551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.79633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.301873Z","time spent":"494.447688ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:31.796361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.1677Z","time spent":"628.655415ms","remote":"127.0.0.1:38552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-02-25T19:27:31.795784Z","caller":"traceutil/trace.go:171","msg":"trace[950043711] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:65; }","duration":"891.627846ms","start":"2026-02-25T19:27:30.904118Z","end":"2026-02-25T19:27:31.795745Z","steps":["trace[950043711] 'agreement among raft nodes before linearized reading' (duration: 891.492965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.79646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.904092Z","time spent":"892.307242ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-25T19:27:31.795872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.821728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:31.796881Z","caller":"traceutil/trace.go:171","msg":"trace[1389347797] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:65; }","duration":"628.815717ms","start":"2026-02-25T19:27:31.168006Z","end":"2026-02-25T19:27:31.796822Z","steps":["trace[1389347797] 'agreement among raft nodes before linearized reading' (duration: 627.842829ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:31.79589Z","caller":"traceutil/trace.go:171","msg":"trace[1032307382] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"987.285344ms","start":"2026-02-25T19:27:30.808572Z","end":"2026-02-25T19:27:31.795858Z","steps":["trace[1032307382] 'process raft request' (duration: 475.850205ms)","trace[1032307382] 'compare' (duration: 510.45168ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:31.796974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.167986Z","time spent":"628.971729ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-02-25T19:27:31.796999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:30.808561Z","time spent":"988.413824ms","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:31.795924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.708042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:31.797094Z","caller":"traceutil/trace.go:171","msg":"trace[341727475] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:65; }","duration":"374.953993ms","start":"2026-02-25T19:27:31.422127Z","end":"2026-02-25T19:27:31.797081Z","steps":["trace[341727475] 'agreement among raft nodes before linearized reading' (duration: 373.766762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:31.797116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:31.422106Z","time spent":"375.004883ms","remote":"127.0.0.1:38292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":26,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-02-25T19:27:32.041538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.869055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:32.041637Z","caller":"traceutil/trace.go:171","msg":"trace[2117611079] linearizableReadLoop","detail":"{readStateIndex:71; appliedIndex:70; }","duration":"242.275679ms","start":"2026-02-25T19:27:31.799344Z","end":"2026-02-25T19:27:32.04162Z","steps":["trace[2117611079] 'read index received' (duration: 120.226252ms)","trace[2117611079] 'applied index is now lower than readState.Index' (duration: 122.048317ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:32.041668Z","caller":"traceutil/trace.go:171","msg":"trace[13311113] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"242.951275ms","start":"2026-02-25T19:27:31.79869Z","end":"2026-02-25T19:27:32.041641Z","steps":["trace[13311113] 'process raft request' (duration: 120.900308ms)","trace[13311113] 'compare' (duration: 121.683584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:32.042013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.658232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:32.042057Z","caller":"traceutil/trace.go:171","msg":"trace[2111255679] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:66; }","duration":"242.725893ms","start":"2026-02-25T19:27:31.799322Z","end":"2026-02-25T19:27:32.042048Z","steps":["trace[2111255679] 'agreement among raft nodes before linearized reading' (duration: 242.42726ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.058894Z","caller":"traceutil/trace.go:171","msg":"trace[1243134528] transaction","detail":"{read_only:false; response_revision:68; number_of_response:1; }","duration":"259.424701ms","start":"2026-02-25T19:27:31.799452Z","end":"2026-02-25T19:27:32.058876Z","steps":["trace[1243134528] 'process raft request' (duration: 259.27529ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.059034Z","caller":"traceutil/trace.go:171","msg":"trace[1769916681] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"257.345852ms","start":"2026-02-25T19:27:31.801653Z","end":"2026-02-25T19:27:32.058998Z","steps":["trace[1769916681] 'process raft request' (duration: 257.14489ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.059252Z","caller":"traceutil/trace.go:171","msg":"trace[2075486899] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"259.806025ms","start":"2026-02-25T19:27:31.799398Z","end":"2026-02-25T19:27:32.059204Z","steps":["trace[2075486899] 'process raft request' (duration: 259.22382ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.059294Z","caller":"traceutil/trace.go:171","msg":"trace[1110703220] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"259.808524ms","start":"2026-02-25T19:27:31.799472Z","end":"2026-02-25T19:27:32.059281Z","steps":["trace[1110703220] 'process raft request' (duration: 259.28861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:32.059401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.274499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189793f1a3d37bc6\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T19:27:32.059431Z","caller":"traceutil/trace.go:171","msg":"trace[948869521] range","detail":"{range_begin:/registry/events/default/instance.189793f1a3d37bc6; range_end:; response_count:1; response_revision:70; }","duration":"259.31716ms","start":"2026-02-25T19:27:31.800107Z","end":"2026-02-25T19:27:32.059424Z","steps":["trace[948869521] 'agreement among raft nodes before linearized reading' (duration: 259.269239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:32.359937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.546261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:32.360124Z","caller":"traceutil/trace.go:171","msg":"trace[852700991] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"296.225758ms","start":"2026-02-25T19:27:32.063867Z","end":"2026-02-25T19:27:32.360093Z","steps":["trace[852700991] 'process raft request' (duration: 159.409575ms)","trace[852700991] 'compare' (duration: 136.44146ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:32.360152Z","caller":"traceutil/trace.go:171","msg":"trace[1527173353] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"295.981305ms","start":"2026-02-25T19:27:32.064151Z","end":"2026-02-25T19:27:32.360132Z","steps":["trace[1527173353] 'process raft request' (duration: 295.881874ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.360281Z","caller":"traceutil/trace.go:171","msg":"trace[357462345] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"295.875004ms","start":"2026-02-25T19:27:32.064363Z","end":"2026-02-25T19:27:32.360238Z","steps":["trace[357462345] 'process raft request' (duration: 295.719473ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.360355Z","caller":"traceutil/trace.go:171","msg":"trace[877807999] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:76; }","duration":"240.133529ms","start":"2026-02-25T19:27:32.120212Z","end":"2026-02-25T19:27:32.360345Z","steps":["trace[877807999] 'read index received' (duration: 103.012704ms)","trace[877807999] 'applied index is now lower than readState.Index' (duration: 137.119435ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:32.360506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.27879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:32.360536Z","caller":"traceutil/trace.go:171","msg":"trace[1542463406] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:74; }","duration":"240.362222ms","start":"2026-02-25T19:27:32.120164Z","end":"2026-02-25T19:27:32.360526Z","steps":["trace[1542463406] 'agreement among raft nodes before linearized reading' (duration: 240.250701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:32.601256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.396675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:public-info-viewer\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:32.60127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.239484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189793f1a3d37bc6\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T19:27:32.601336Z","caller":"traceutil/trace.go:171","msg":"trace[464667032] range","detail":"{range_begin:/registry/clusterroles/system:public-info-viewer; range_end:; response_count:0; response_revision:88; }","duration":"140.497566ms","start":"2026-02-25T19:27:32.460821Z","end":"2026-02-25T19:27:32.601318Z","steps":["trace[464667032] 'range keys from in-memory index tree' (duration: 140.346674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.601384Z","caller":"traceutil/trace.go:171","msg":"trace[1340893423] range","detail":"{range_begin:/registry/events/default/instance.189793f1a3d37bc6; range_end:; response_count:1; response_revision:88; }","duration":"139.324475ms","start":"2026-02-25T19:27:32.462007Z","end":"2026-02-25T19:27:32.601331Z","steps":["trace[1340893423] 'range keys from in-memory index tree' (duration: 139.133354ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:32.872444Z","caller":"traceutil/trace.go:171","msg":"trace[1482059179] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"267.862817ms","start":"2026-02-25T19:27:32.604557Z","end":"2026-02-25T19:27:32.872419Z","steps":["trace[1482059179] 'process raft request' (duration: 267.712015ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:34.803269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000152437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:27:34.803398Z","caller":"traceutil/trace.go:171","msg":"trace[406609099] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000347839s","start":"2026-02-25T19:27:32.803026Z","end":"2026-02-25T19:27:34.803374Z","steps":["trace[406609099] 'agreement among raft nodes before linearized reading' (duration: 2.000195098s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:34.803478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:32.803009Z","time spent":"2.00044008s","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:27:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:27:34.845898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.193333185s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:34.846023Z","caller":"traceutil/trace.go:171","msg":"trace[332965137] linearizableReadLoop","detail":"{readStateIndex:95; appliedIndex:93; }","duration":"2.042922003s","start":"2026-02-25T19:27:32.803078Z","end":"2026-02-25T19:27:34.846Z","steps":["trace[332965137] 'read index received' (duration: 69.228115ms)","trace[332965137] 'applied index is now lower than readState.Index' (duration: 1.973692408s)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:34.846122Z","caller":"traceutil/trace.go:171","msg":"trace[950771551] transaction","detail":"{read_only:false; response_revision:90; number_of_response:1; }","duration":"2.241207913s","start":"2026-02-25T19:27:32.604836Z","end":"2026-02-25T19:27:34.846044Z","steps":["trace[950771551] 'process raft request' (duration: 1.047638436s)","trace[950771551] 'compare' (duration: 1.192112194s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:34.846274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:32.604822Z","time spent":"2.241369794s","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":600,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:35.34662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650105338,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:27:35.547038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999756209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:27:35.547117Z","caller":"traceutil/trace.go:171","msg":"trace[770537963] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.99989204s","start":"2026-02-25T19:27:33.547207Z","end":"2026-02-25T19:27:35.547099Z","steps":["trace[770537963] 'agreement among raft nodes before linearized reading' (duration: 1.999795949s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:35.547163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:33.547185Z","time spent":"1.99996952s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:27:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:27:35.803691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.151285813s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:27:36.814898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010976262s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:36.814995Z","caller":"traceutil/trace.go:171","msg":"trace[1880747808] transaction","detail":"{read_only:false; response_revision:91; number_of_response:1; }","duration":"3.823045467s","start":"2026-02-25T19:27:32.991929Z","end":"2026-02-25T19:27:36.814975Z","steps":["trace[1880747808] 'process raft request' (duration: 2.811939314s)","trace[1880747808] 'compare' (duration: 1.01075159s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:36.815063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:32.991905Z","time spent":"3.823127938s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:36.903146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00036653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:27:36.903268Z","caller":"traceutil/trace.go:171","msg":"trace[432967735] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000547882s","start":"2026-02-25T19:27:34.902698Z","end":"2026-02-25T19:27:36.903246Z","steps":["trace[432967735] 'agreement among raft nodes before linearized reading' (duration: 2.00040482s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:36.903329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:34.902673Z","time spent":"2.000644122s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:37.593928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.789988324s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:27:37.808105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.959341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:37.808185Z","caller":"traceutil/trace.go:171","msg":"trace[1006393183] linearizableReadLoop","detail":"{readStateIndex:97; appliedIndex:95; }","duration":"2.962115896s","start":"2026-02-25T19:27:34.846058Z","end":"2026-02-25T19:27:37.808173Z","steps":["trace[1006393183] 'read index received' (duration: 957.833797ms)","trace[1006393183] 'applied index is now lower than readState.Index' (duration: 2.004280899s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:37.808259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.041273393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:37.8083Z","caller":"traceutil/trace.go:171","msg":"trace[1291257001] transaction","detail":"{read_only:false; response_revision:92; number_of_response:1; }","duration":"3.828515335s","start":"2026-02-25T19:27:33.979774Z","end":"2026-02-25T19:27:37.808289Z","steps":["trace[1291257001] 'process raft request' (duration: 3.614326042s)","trace[1291257001] 'compare' (duration: 213.80641ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:37.8083Z","caller":"traceutil/trace.go:171","msg":"trace[2030538538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:92; }","duration":"4.041361174s","start":"2026-02-25T19:27:33.766925Z","end":"2026-02-25T19:27:37.808286Z","steps":["trace[2030538538] 'agreement among raft nodes before linearized reading' (duration: 4.041307353s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.808333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:33.766896Z","time spent":"4.041427284s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:27:37.808325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.23684853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:37.808401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.070069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:37.80842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760759561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T19:27:37.808428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.93302842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189793f1a3d390f2\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-02-25T19:27:37.808441Z","caller":"traceutil/trace.go:171","msg":"trace[1723689149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:92; }","duration":"905.109949ms","start":"2026-02-25T19:27:36.90332Z","end":"2026-02-25T19:27:37.80843Z","steps":["trace[1723689149] 'agreement among raft nodes before linearized reading' (duration: 905.047979ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:37.80845Z","caller":"traceutil/trace.go:171","msg":"trace[1454915781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:92; }","duration":"3.237017851s","start":"2026-02-25T19:27:34.571406Z","end":"2026-02-25T19:27:37.808424Z","steps":["trace[1454915781] 'agreement among raft nodes before linearized reading' (duration: 3.236789679s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:37.808474Z","caller":"traceutil/trace.go:171","msg":"trace[1801933678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:92; }","duration":"1.760833211s","start":"2026-02-25T19:27:36.047619Z","end":"2026-02-25T19:27:37.808453Z","steps":["trace[1801933678] 'agreement among raft nodes before linearized reading' (duration: 1.760783591s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.808348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:33.979706Z","time spent":"3.828616225s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:37.808516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:36.047599Z","time spent":"1.760909413s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:27:37.808527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:34.571386Z","time spent":"3.237126752s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:27:37.808375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.958684857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:37.808601Z","caller":"traceutil/trace.go:171","msg":"trace[816384922] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:92; }","duration":"2.958943068s","start":"2026-02-25T19:27:34.849644Z","end":"2026-02-25T19:27:37.808587Z","steps":["trace[816384922] 'agreement among raft nodes before linearized reading' (duration: 2.958688976s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.808641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:34.849622Z","time spent":"2.959007459s","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-02-25T19:27:37.808259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.270306353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-02-25T19:27:37.808735Z","caller":"traceutil/trace.go:171","msg":"trace[1743381111] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:92; }","duration":"3.270852948s","start":"2026-02-25T19:27:34.537872Z","end":"2026-02-25T19:27:37.808725Z","steps":["trace[1743381111] 'agreement among raft nodes before linearized reading' (duration: 3.270317873s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.808771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:34.537853Z","time spent":"3.270909038s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3487,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-02-25T19:27:37.808472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:36.903262Z","time spent":"905.203709ms","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:27:37.808469Z","caller":"traceutil/trace.go:171","msg":"trace[1992394446] range","detail":"{range_begin:/registry/events/default/instance.189793f1a3d390f2; range_end:; response_count:1; response_revision:92; }","duration":"4.93308935s","start":"2026-02-25T19:27:32.875369Z","end":"2026-02-25T19:27:37.808459Z","steps":["trace[1992394446] 'agreement among raft nodes before linearized reading' (duration: 4.93302281s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:37.808887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:32.875322Z","time spent":"4.933558015s","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":670,"request content":"key:\"/registry/events/default/instance.189793f1a3d390f2\" "} {"level":"info","ts":"2026-02-25T19:27:38.222597Z","caller":"traceutil/trace.go:171","msg":"trace[182092898] linearizableReadLoop","detail":"{readStateIndex:102; appliedIndex:98; }","duration":"370.109408ms","start":"2026-02-25T19:27:37.852467Z","end":"2026-02-25T19:27:38.222577Z","steps":["trace[182092898] 'read index received' (duration: 317.192411ms)","trace[182092898] 'applied index is now lower than readState.Index' (duration: 52.916257ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:38.222636Z","caller":"traceutil/trace.go:171","msg":"trace[622292182] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"409.322937ms","start":"2026-02-25T19:27:37.813291Z","end":"2026-02-25T19:27:38.222614Z","steps":["trace[622292182] 'process raft request' (duration: 409.175676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:38.222673Z","caller":"traceutil/trace.go:171","msg":"trace[2145867228] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"409.270177ms","start":"2026-02-25T19:27:37.813398Z","end":"2026-02-25T19:27:38.222668Z","steps":["trace[2145867228] 'process raft request' (duration: 409.108276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:38.222723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.813392Z","time spent":"409.293057ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:38.222724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.813274Z","time spent":"409.405898ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:27:38.222825Z","caller":"traceutil/trace.go:171","msg":"trace[432305910] transaction","detail":"{read_only:false; response_revision:94; number_of_response:1; }","duration":"410.583838ms","start":"2026-02-25T19:27:37.812222Z","end":"2026-02-25T19:27:38.222806Z","steps":["trace[432305910] 'process raft request' (duration: 357.440169ms)","trace[432305910] 'compare' (duration: 52.677365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:38.222874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.812213Z","time spent":"410.641158ms","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:27:38.2229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.145686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T19:27:38.222917Z","caller":"traceutil/trace.go:171","msg":"trace[441791419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:97; }","duration":"320.192816ms","start":"2026-02-25T19:27:37.902719Z","end":"2026-02-25T19:27:38.222912Z","steps":["trace[441791419] 'agreement among raft nodes before linearized reading' (duration: 320.160626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:38.222931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.902704Z","time spent":"320.224366ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:27:38.223194Z","caller":"traceutil/trace.go:171","msg":"trace[1745645805] transaction","detail":"{read_only:false; response_revision:97; number_of_response:1; }","duration":"391.482933ms","start":"2026-02-25T19:27:37.831676Z","end":"2026-02-25T19:27:38.223159Z","steps":["trace[1745645805] 'process raft request' (duration: 390.850728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:38.223262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.408371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189793f1a3d31514\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-02-25T19:27:38.223329Z","caller":"traceutil/trace.go:171","msg":"trace[31890382] range","detail":"{range_begin:/registry/events/default/instance.189793f1a3d31514; range_end:; response_count:1; response_revision:97; }","duration":"370.903475ms","start":"2026-02-25T19:27:37.852407Z","end":"2026-02-25T19:27:38.223311Z","steps":["trace[31890382] 'agreement among raft nodes before linearized reading' (duration: 370.27794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:38.223361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.831655Z","time spent":"391.613194ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4645,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:38.223377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:37.852383Z","time spent":"370.981236ms","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.189793f1a3d31514\" "} {"level":"warn","ts":"2026-02-25T19:27:38.461489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.846913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-25T19:27:38.461601Z","caller":"traceutil/trace.go:171","msg":"trace[214279967] linearizableReadLoop","detail":"{readStateIndex:104; appliedIndex:103; }","duration":"181.597306ms","start":"2026-02-25T19:27:38.279987Z","end":"2026-02-25T19:27:38.461585Z","steps":["trace[214279967] 'read index received' (duration: 69.658241ms)","trace[214279967] 'applied index is now lower than readState.Index' (duration: 111.937835ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:38.461622Z","caller":"traceutil/trace.go:171","msg":"trace[696803848] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"232.849087ms","start":"2026-02-25T19:27:38.22875Z","end":"2026-02-25T19:27:38.461599Z","steps":["trace[696803848] 'process raft request' (duration: 120.828522ms)","trace[696803848] 'compare' (duration: 111.693972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:38.461757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.077399ms","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-25T19:27:38.461795Z","caller":"traceutil/trace.go:171","msg":"trace[1865597582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:99; }","duration":"233.159449ms","start":"2026-02-25T19:27:38.228626Z","end":"2026-02-25T19:27:38.461785Z","steps":["trace[1865597582] 'agreement among raft nodes before linearized reading' (duration: 233.044038ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:38.487171Z","caller":"traceutil/trace.go:171","msg":"trace[499875271] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"188.155041ms","start":"2026-02-25T19:27:38.298996Z","end":"2026-02-25T19:27:38.487151Z","steps":["trace[499875271] 'process raft request' (duration: 188.04111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:38.487513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.22922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189793f1a3d37bc6\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-02-25T19:27:38.487604Z","caller":"traceutil/trace.go:171","msg":"trace[895011637] range","detail":"{range_begin:/registry/events/default/instance.189793f1a3d37bc6; range_end:; response_count:1; response_revision:100; }","duration":"204.369602ms","start":"2026-02-25T19:27:38.283213Z","end":"2026-02-25T19:27:38.487582Z","steps":["trace[895011637] 'agreement among raft nodes before linearized reading' (duration: 203.982979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:39.774109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.09125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:39.774232Z","caller":"traceutil/trace.go:171","msg":"trace[751531442] transaction","detail":"{read_only:false; response_revision:167; number_of_response:1; }","duration":"175.92736ms","start":"2026-02-25T19:27:39.598284Z","end":"2026-02-25T19:27:39.774212Z","steps":["trace[751531442] 'process raft request' (duration: 54.648109ms)","trace[751531442] 'compare' (duration: 120.866508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:40.008342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.013518ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:40.008638Z","caller":"traceutil/trace.go:171","msg":"trace[1655047779] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"186.044676ms","start":"2026-02-25T19:27:39.822566Z","end":"2026-02-25T19:27:40.008611Z","steps":["trace[1655047779] 'process raft request' (duration: 64.649035ms)","trace[1655047779] 'compare' (duration: 120.780966ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:40.239098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.710803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:40.239199Z","caller":"traceutil/trace.go:171","msg":"trace[903132551] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"150.427805ms","start":"2026-02-25T19:27:40.088753Z","end":"2026-02-25T19:27:40.23918Z","steps":["trace[903132551] 'process raft request' (duration: 44.565941ms)","trace[903132551] 'compare' (duration: 105.484971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:40.741798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.124824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:40.741916Z","caller":"traceutil/trace.go:171","msg":"trace[1584468433] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-scheduler; range_end:; response_count:0; response_revision:193; }","duration":"142.287965ms","start":"2026-02-25T19:27:40.59961Z","end":"2026-02-25T19:27:40.741898Z","steps":["trace[1584468433] 'range keys from in-memory index tree' (duration: 142.016332ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:40.899879Z","caller":"traceutil/trace.go:171","msg":"trace[322431854] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"155.219897ms","start":"2026-02-25T19:27:40.744639Z","end":"2026-02-25T19:27:40.899859Z","steps":["trace[322431854] 'process raft request' (duration: 155.075636ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:40.921404Z","caller":"traceutil/trace.go:171","msg":"trace[1580976254] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"101.603009ms","start":"2026-02-25T19:27:40.819781Z","end":"2026-02-25T19:27:40.921384Z","steps":["trace[1580976254] 'process raft request' (duration: 101.358857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:41.228037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.910999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:41.228161Z","caller":"traceutil/trace.go:171","msg":"trace[464034204] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"114.041249ms","start":"2026-02-25T19:27:41.114101Z","end":"2026-02-25T19:27:41.228142Z","steps":["trace[464034204] 'compare' (duration: 106.726367ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:41.567091Z","caller":"traceutil/trace.go:171","msg":"trace[904717368] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"134.074862ms","start":"2026-02-25T19:27:41.432959Z","end":"2026-02-25T19:27:41.567034Z","steps":["trace[904717368] 'process raft request' (duration: 74.076051ms)","trace[904717368] 'compare' (duration: 59.828859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:41.886844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.048533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:41.887005Z","caller":"traceutil/trace.go:171","msg":"trace[1690513139] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"240.253536ms","start":"2026-02-25T19:27:41.646689Z","end":"2026-02-25T19:27:41.886942Z","steps":["trace[1690513139] 'process raft request' (duration: 81.041001ms)","trace[1690513139] 'compare' (duration: 158.899232ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:41.926242Z","caller":"traceutil/trace.go:171","msg":"trace[511391178] linearizableReadLoop","detail":"{readStateIndex:219; appliedIndex:218; }","duration":"119.323396ms","start":"2026-02-25T19:27:41.806888Z","end":"2026-02-25T19:27:41.926211Z","steps":["trace[511391178] 'read index received' (duration: 119.058114ms)","trace[511391178] 'applied index is now lower than readState.Index' (duration: 263.792µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:41.926433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.536018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:41.926496Z","caller":"traceutil/trace.go:171","msg":"trace[1805796594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"119.664899ms","start":"2026-02-25T19:27:41.806815Z","end":"2026-02-25T19:27:41.92648Z","steps":["trace[1805796594] 'agreement among raft nodes before linearized reading' (duration: 119.561638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:41.926676Z","caller":"traceutil/trace.go:171","msg":"trace[165865986] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"273.419918ms","start":"2026-02-25T19:27:41.653004Z","end":"2026-02-25T19:27:41.926424Z","steps":["trace[165865986] 'process raft request' (duration: 273.068245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:42.206079Z","caller":"traceutil/trace.go:171","msg":"trace[1169795890] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"153.097929ms","start":"2026-02-25T19:27:42.05296Z","end":"2026-02-25T19:27:42.206058Z","steps":["trace[1169795890] 'process raft request' (duration: 153.046578ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:42.206109Z","caller":"traceutil/trace.go:171","msg":"trace[208277757] transaction","detail":"{read_only:false; response_revision:222; number_of_response:1; }","duration":"156.383526ms","start":"2026-02-25T19:27:42.049687Z","end":"2026-02-25T19:27:42.20607Z","steps":["trace[208277757] 'process raft request' (duration: 114.375989ms)","trace[208277757] 'compare' (duration: 41.813685ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:42.387629Z","caller":"traceutil/trace.go:171","msg":"trace[602265573] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"176.620857ms","start":"2026-02-25T19:27:42.210979Z","end":"2026-02-25T19:27:42.3876Z","steps":["trace[602265573] 'process raft request' (duration: 142.848061ms)","trace[602265573] 'compare' (duration: 33.648055ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:42.564638Z","caller":"traceutil/trace.go:171","msg":"trace[978241485] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"110.688389ms","start":"2026-02-25T19:27:42.453916Z","end":"2026-02-25T19:27:42.564605Z","steps":["trace[978241485] 'process raft request' (duration: 49.899584ms)","trace[978241485] 'compare' (duration: 60.561883ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:42.745483Z","caller":"traceutil/trace.go:171","msg":"trace[1999533759] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"116.591288ms","start":"2026-02-25T19:27:42.628864Z","end":"2026-02-25T19:27:42.745455Z","steps":["trace[1999533759] 'process raft request' (duration: 115.059265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:42.99719Z","caller":"traceutil/trace.go:171","msg":"trace[1789058867] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"116.82462ms","start":"2026-02-25T19:27:42.880345Z","end":"2026-02-25T19:27:42.99717Z","steps":["trace[1789058867] 'process raft request' (duration: 116.729199ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:43.098466Z","caller":"traceutil/trace.go:171","msg":"trace[1146423330] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"100.484052ms","start":"2026-02-25T19:27:42.997947Z","end":"2026-02-25T19:27:43.098431Z","steps":["trace[1146423330] 'process raft request' (duration: 83.480032ms)","trace[1146423330] 'compare' (duration: 16.851449ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:46.655855Z","caller":"traceutil/trace.go:171","msg":"trace[285576022] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"139.645755ms","start":"2026-02-25T19:27:46.516185Z","end":"2026-02-25T19:27:46.65583Z","steps":["trace[285576022] 'read index received' (duration: 51.883759ms)","trace[285576022] 'applied index is now lower than readState.Index' (duration: 87.760986ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:46.655942Z","caller":"traceutil/trace.go:171","msg":"trace[889973578] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"145.234893ms","start":"2026-02-25T19:27:46.510682Z","end":"2026-02-25T19:27:46.655917Z","steps":["trace[889973578] 'process raft request' (duration: 57.359584ms)","trace[889973578] 'compare' (duration: 87.686874ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:46.656199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.961524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T19:27:46.656278Z","caller":"traceutil/trace.go:171","msg":"trace[154005044] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:335; }","duration":"140.108787ms","start":"2026-02-25T19:27:46.516155Z","end":"2026-02-25T19:27:46.656263Z","steps":["trace[154005044] 'agreement among raft nodes before linearized reading' (duration: 139.908402ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:46.866405Z","caller":"traceutil/trace.go:171","msg":"trace[1061640040] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"149.343611ms","start":"2026-02-25T19:27:46.717035Z","end":"2026-02-25T19:27:46.866379Z","steps":["trace[1061640040] 'read index received' (duration: 71.383724ms)","trace[1061640040] 'applied index is now lower than readState.Index' (duration: 77.958997ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:46.86646Z","caller":"traceutil/trace.go:171","msg":"trace[33411467] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"155.812152ms","start":"2026-02-25T19:27:46.710631Z","end":"2026-02-25T19:27:46.866444Z","steps":["trace[33411467] 'process raft request' (duration: 77.809543ms)","trace[33411467] 'compare' (duration: 77.839215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:46.866552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.483965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:46.866586Z","caller":"traceutil/trace.go:171","msg":"trace[524603596] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:338; }","duration":"149.591748ms","start":"2026-02-25T19:27:46.716985Z","end":"2026-02-25T19:27:46.866577Z","steps":["trace[524603596] 'agreement among raft nodes before linearized reading' (duration: 149.509335ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:47.161386Z","caller":"traceutil/trace.go:171","msg":"trace[194031205] linearizableReadLoop","detail":"{readStateIndex:357; appliedIndex:356; }","duration":"233.012489ms","start":"2026-02-25T19:27:46.928356Z","end":"2026-02-25T19:27:47.161369Z","steps":["trace[194031205] 'read index received' (duration: 189.255895ms)","trace[194031205] 'applied index is now lower than readState.Index' (duration: 43.755804ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:47.161482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.108271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T19:27:47.16151Z","caller":"traceutil/trace.go:171","msg":"trace[1187367382] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:340; }","duration":"233.175153ms","start":"2026-02-25T19:27:46.928323Z","end":"2026-02-25T19:27:47.161498Z","steps":["trace[1187367382] 'agreement among raft nodes before linearized reading' (duration: 233.104931ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:47.161554Z","caller":"traceutil/trace.go:171","msg":"trace[1902013772] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"284.776685ms","start":"2026-02-25T19:27:46.876743Z","end":"2026-02-25T19:27:47.16152Z","steps":["trace[1902013772] 'process raft request' (duration: 240.944349ms)","trace[1902013772] 'compare' (duration: 43.589719ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:47.367925Z","caller":"traceutil/trace.go:171","msg":"trace[1158686819] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:358; }","duration":"165.086863ms","start":"2026-02-25T19:27:47.202811Z","end":"2026-02-25T19:27:47.367898Z","steps":["trace[1158686819] 'read index received' (duration: 71.94947ms)","trace[1158686819] 'applied index is now lower than readState.Index' (duration: 93.136323ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:47.367969Z","caller":"traceutil/trace.go:171","msg":"trace[1082158267] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"196.402431ms","start":"2026-02-25T19:27:47.171533Z","end":"2026-02-25T19:27:47.367935Z","steps":["trace[1082158267] 'process raft request' (duration: 103.301199ms)","trace[1082158267] 'compare' (duration: 92.925698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:47.368133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.310949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-25T19:27:47.368206Z","caller":"traceutil/trace.go:171","msg":"trace[1963848546] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:342; }","duration":"165.439852ms","start":"2026-02-25T19:27:47.20275Z","end":"2026-02-25T19:27:47.368189Z","steps":["trace[1963848546] 'agreement among raft nodes before linearized reading' (duration: 165.248407ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:47.818876Z","caller":"traceutil/trace.go:171","msg":"trace[1942217137] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"202.85382ms","start":"2026-02-25T19:27:47.616006Z","end":"2026-02-25T19:27:47.81886Z","steps":["trace[1942217137] 'read index received' (duration: 202.668496ms)","trace[1942217137] 'applied index is now lower than readState.Index' (duration: 184.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:47.818962Z","caller":"traceutil/trace.go:171","msg":"trace[622491608] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"228.190222ms","start":"2026-02-25T19:27:47.590749Z","end":"2026-02-25T19:27:47.818939Z","steps":["trace[622491608] 'process raft request' (duration: 227.991277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:47.819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.981863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T19:27:47.819023Z","caller":"traceutil/trace.go:171","msg":"trace[283165306] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:347; }","duration":"203.061265ms","start":"2026-02-25T19:27:47.615956Z","end":"2026-02-25T19:27:47.819017Z","steps":["trace[283165306] 'agreement among raft nodes before linearized reading' (duration: 202.973443ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:50.456303Z","caller":"traceutil/trace.go:171","msg":"trace[54782410] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"126.030437ms","start":"2026-02-25T19:27:50.330241Z","end":"2026-02-25T19:27:50.456272Z","steps":["trace[54782410] 'process raft request' (duration: 72.567361ms)","trace[54782410] 'compare' (duration: 53.21073ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:50.456302Z","caller":"traceutil/trace.go:171","msg":"trace[732560075] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"123.322658ms","start":"2026-02-25T19:27:50.332962Z","end":"2026-02-25T19:27:50.456284Z","steps":["trace[732560075] 'process raft request' (duration: 123.253337ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:50.461757Z","caller":"traceutil/trace.go:171","msg":"trace[1556522090] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"116.337701ms","start":"2026-02-25T19:27:50.345396Z","end":"2026-02-25T19:27:50.461733Z","steps":["trace[1556522090] 'process raft request' (duration: 116.013342ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:51.931296Z","caller":"traceutil/trace.go:171","msg":"trace[876423227] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"117.272326ms","start":"2026-02-25T19:27:51.814002Z","end":"2026-02-25T19:27:51.931274Z","steps":["trace[876423227] 'process raft request' (duration: 117.155593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.307477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.075712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:27:52.307545Z","caller":"traceutil/trace.go:171","msg":"trace[735920579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"275.186675ms","start":"2026-02-25T19:27:52.032337Z","end":"2026-02-25T19:27:52.307524Z","steps":["trace[735920579] 'range keys from in-memory index tree' (duration: 274.967809ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.307619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.432246ms","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-25T19:27:52.307701Z","caller":"traceutil/trace.go:171","msg":"trace[368028683] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"151.554669ms","start":"2026-02-25T19:27:52.156129Z","end":"2026-02-25T19:27:52.307683Z","steps":["trace[368028683] 'range keys from in-memory index tree' (duration: 151.325544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.696357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.69259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:52.696546Z","caller":"traceutil/trace.go:171","msg":"trace[166112118] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"384.941036ms","start":"2026-02-25T19:27:52.311586Z","end":"2026-02-25T19:27:52.696527Z","steps":["trace[166112118] 'process raft request' (duration: 204.99812ms)","trace[166112118] 'compare' (duration: 179.593927ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:52.696637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.311567Z","time spent":"385.036439ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:52.696816Z","caller":"traceutil/trace.go:171","msg":"trace[852892561] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"379.956113ms","start":"2026-02-25T19:27:52.316845Z","end":"2026-02-25T19:27:52.696801Z","steps":["trace[852892561] 'process raft request' (duration: 379.679906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.696889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.316833Z","time spent":"380.019804ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:52.696928Z","caller":"traceutil/trace.go:171","msg":"trace[23345973] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"383.960032ms","start":"2026-02-25T19:27:52.312957Z","end":"2026-02-25T19:27:52.696917Z","steps":["trace[23345973] 'process raft request' (duration: 383.50342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:52.696988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.312943Z","time spent":"384.012094ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:53.081686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.64864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:53.081803Z","caller":"traceutil/trace.go:171","msg":"trace[639694668] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"313.710095ms","start":"2026-02-25T19:27:52.768075Z","end":"2026-02-25T19:27:53.081785Z","steps":["trace[639694668] 'read index received' (duration: 207.709167ms)","trace[639694668] 'applied index is now lower than readState.Index' (duration: 105.999798ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:27:53.082561Z","caller":"traceutil/trace.go:171","msg":"trace[623049027] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"373.940743ms","start":"2026-02-25T19:27:52.708587Z","end":"2026-02-25T19:27:53.082528Z","steps":["trace[623049027] 'process raft request' (duration: 267.370101ms)","trace[623049027] 'compare' (duration: 105.505516ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:53.082724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.70857Z","time spent":"374.066647ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:27:53.0834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.275374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:27:53.086659Z","caller":"traceutil/trace.go:171","msg":"trace[692415904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:414; }","duration":"318.583086ms","start":"2026-02-25T19:27:52.76804Z","end":"2026-02-25T19:27:53.086624Z","steps":["trace[692415904] 'agreement among raft nodes before linearized reading' (duration: 314.762881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:53.08681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:52.768022Z","time spent":"318.75477ms","remote":"127.0.0.1:38452","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-25T19:27:53.087801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.366027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:27:53.087977Z","caller":"traceutil/trace.go:171","msg":"trace[1116978150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"299.754626ms","start":"2026-02-25T19:27:52.7882Z","end":"2026-02-25T19:27:53.087955Z","steps":["trace[1116978150] 'agreement among raft nodes before linearized reading' (duration: 294.450115ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:53.213989Z","caller":"traceutil/trace.go:171","msg":"trace[377282628] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"116.847568ms","start":"2026-02-25T19:27:53.097112Z","end":"2026-02-25T19:27:53.213959Z","steps":["trace[377282628] 'process raft request' (duration: 116.786607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:53.214017Z","caller":"traceutil/trace.go:171","msg":"trace[1042002025] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"122.507928ms","start":"2026-02-25T19:27:53.091473Z","end":"2026-02-25T19:27:53.213981Z","steps":["trace[1042002025] 'process raft request' (duration: 115.660439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:53.481372Z","caller":"traceutil/trace.go:171","msg":"trace[964596251] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"125.065062ms","start":"2026-02-25T19:27:53.356261Z","end":"2026-02-25T19:27:53.481326Z","steps":["trace[964596251] 'process raft request' (duration: 124.063377ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:54.895445Z","caller":"traceutil/trace.go:171","msg":"trace[945975065] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"191.327988ms","start":"2026-02-25T19:27:54.704098Z","end":"2026-02-25T19:27:54.895426Z","steps":["trace[945975065] 'process raft request' (duration: 191.173125ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:27:54.942396Z","caller":"traceutil/trace.go:171","msg":"trace[1694104895] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"237.757751ms","start":"2026-02-25T19:27:54.704615Z","end":"2026-02-25T19:27:54.942373Z","steps":["trace[1694104895] 'process raft request' (duration: 237.641238ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.053112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.11523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:27:56.053657Z","caller":"traceutil/trace.go:171","msg":"trace[1147135041] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"831.496046ms","start":"2026-02-25T19:27:55.222139Z","end":"2026-02-25T19:27:56.053635Z","steps":["trace[1147135041] 'process raft request' (duration: 684.749211ms)","trace[1147135041] 'compare' (duration: 145.968367ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:27:56.053743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.222117Z","time spent":"831.589108ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:27:56.054223Z","caller":"traceutil/trace.go:171","msg":"trace[1783963630] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"417.363356ms","start":"2026-02-25T19:27:55.636837Z","end":"2026-02-25T19:27:56.0542Z","steps":["trace[1783963630] 'process raft request' (duration: 416.384362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:27:56.054319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:27:55.636796Z","time spent":"417.480449ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:01.803329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714163872s","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-25T19:28:01.803398Z","caller":"traceutil/trace.go:171","msg":"trace[1983878517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"1.714295385s","start":"2026-02-25T19:28:00.089086Z","end":"2026-02-25T19:28:01.803382Z","steps":["trace[1983878517] 'range keys from in-memory index tree' (duration: 1.714034709s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.803428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.089066Z","time spent":"1.714354876s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:01.803557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.307971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T19:28:01.80356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.844837ms","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-25T19:28:01.80361Z","caller":"traceutil/trace.go:171","msg":"trace[977009751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"882.407774ms","start":"2026-02-25T19:28:00.921188Z","end":"2026-02-25T19:28:01.803596Z","steps":["trace[977009751] 'range keys from in-memory index tree' (duration: 882.135657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.803588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.960431ms","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-25T19:28:01.803653Z","caller":"traceutil/trace.go:171","msg":"trace[1200536145] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:434; }","duration":"760.98587ms","start":"2026-02-25T19:28:01.042625Z","end":"2026-02-25T19:28:01.803611Z","steps":["trace[1200536145] 'range keys from in-memory index tree' (duration: 760.691194ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.803657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.921166Z","time spent":"882.476485ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:28:01.803696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:01.042599Z","time spent":"761.087343ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T19:28:01.803814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763287696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:01.803848Z","caller":"traceutil/trace.go:171","msg":"trace[571335177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:434; }","duration":"1.763351026s","start":"2026-02-25T19:28:00.040489Z","end":"2026-02-25T19:28:01.80384Z","steps":["trace[571335177] 'range keys from in-memory index tree' (duration: 1.763235694s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.803869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:00.040472Z","time spent":"1.763391188s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:28:01.803681Z","caller":"traceutil/trace.go:171","msg":"trace[436126256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:434; }","duration":"439.073013ms","start":"2026-02-25T19:28:01.364582Z","end":"2026-02-25T19:28:01.803655Z","steps":["trace[436126256] 'range keys from in-memory index tree' (duration: 438.851438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:01.803968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:01.364513Z","time spent":"439.444522ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:28:03.080689Z","caller":"traceutil/trace.go:171","msg":"trace[1791184002] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:458; }","duration":"260.657128ms","start":"2026-02-25T19:28:02.820017Z","end":"2026-02-25T19:28:03.080674Z","steps":["trace[1791184002] 'read index received' (duration: 260.535236ms)","trace[1791184002] 'applied index is now lower than readState.Index' (duration: 121.172µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:03.080758Z","caller":"traceutil/trace.go:171","msg":"trace[327125698] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"266.47673ms","start":"2026-02-25T19:28:02.814274Z","end":"2026-02-25T19:28:03.080751Z","steps":["trace[327125698] 'process raft request' (duration: 266.314796ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:03.081158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.121849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-02-25T19:28:03.081298Z","caller":"traceutil/trace.go:171","msg":"trace[1213793418] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:439; }","duration":"261.286063ms","start":"2026-02-25T19:28:02.819988Z","end":"2026-02-25T19:28:03.081274Z","steps":["trace[1213793418] 'agreement among raft nodes before linearized reading' (duration: 261.071848ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:03.240827Z","caller":"traceutil/trace.go:171","msg":"trace[382773578] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"146.972388ms","start":"2026-02-25T19:28:03.093831Z","end":"2026-02-25T19:28:03.240803Z","steps":["trace[382773578] 'process raft request' (duration: 146.795544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:03.953587Z","caller":"traceutil/trace.go:171","msg":"trace[491449090] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"122.419808ms","start":"2026-02-25T19:28:03.831136Z","end":"2026-02-25T19:28:03.953556Z","steps":["trace[491449090] 'process raft request' (duration: 122.281855ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.081528Z","caller":"traceutil/trace.go:171","msg":"trace[2092939738] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"250.071717ms","start":"2026-02-25T19:28:03.831434Z","end":"2026-02-25T19:28:04.081506Z","steps":["trace[2092939738] 'process raft request' (duration: 209.108826ms)","trace[2092939738] 'compare' (duration: 40.840109ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:04.081984Z","caller":"traceutil/trace.go:171","msg":"trace[1762363901] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"250.265201ms","start":"2026-02-25T19:28:03.831698Z","end":"2026-02-25T19:28:04.081963Z","steps":["trace[1762363901] 'process raft request' (duration: 249.778351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:04.08202Z","caller":"traceutil/trace.go:171","msg":"trace[163555177] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:462; }","duration":"227.279275ms","start":"2026-02-25T19:28:03.854722Z","end":"2026-02-25T19:28:04.082001Z","steps":["trace[163555177] 'read index received' (duration: 98.664314ms)","trace[163555177] 'applied index is now lower than readState.Index' (duration: 128.613691ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:04.082107Z","caller":"traceutil/trace.go:171","msg":"trace[622319211] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"249.648217ms","start":"2026-02-25T19:28:03.832452Z","end":"2026-02-25T19:28:04.0821Z","steps":["trace[622319211] 'process raft request' (duration: 249.286369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:04.082164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.407629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-02-25T19:28:04.0822Z","caller":"traceutil/trace.go:171","msg":"trace[1349790601] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:445; }","duration":"227.50937ms","start":"2026-02-25T19:28:03.854679Z","end":"2026-02-25T19:28:04.082189Z","steps":["trace[1349790601] 'agreement among raft nodes before linearized reading' (duration: 227.373777ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:08.312974Z","caller":"traceutil/trace.go:171","msg":"trace[414814334] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"201.313093ms","start":"2026-02-25T19:28:08.111639Z","end":"2026-02-25T19:28:08.312952Z","steps":["trace[414814334] 'process raft request' (duration: 201.18311ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:08.322916Z","caller":"traceutil/trace.go:171","msg":"trace[2023372045] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"211.127413ms","start":"2026-02-25T19:28:08.111766Z","end":"2026-02-25T19:28:08.322894Z","steps":["trace[2023372045] 'process raft request' (duration: 210.96677ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:08.323048Z","caller":"traceutil/trace.go:171","msg":"trace[1556439613] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"211.223865ms","start":"2026-02-25T19:28:08.111812Z","end":"2026-02-25T19:28:08.323035Z","steps":["trace[1556439613] 'process raft request' (duration: 211.043781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:10.587207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.094147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:10.587364Z","caller":"traceutil/trace.go:171","msg":"trace[156830557] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"189.864687ms","start":"2026-02-25T19:28:10.397475Z","end":"2026-02-25T19:28:10.58734Z","steps":["trace[156830557] 'process raft request' (duration: 75.491274ms)","trace[156830557] 'compare' (duration: 113.905614ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:10.66992Z","caller":"traceutil/trace.go:171","msg":"trace[1842987036] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"271.0573ms","start":"2026-02-25T19:28:10.398822Z","end":"2026-02-25T19:28:10.669879Z","steps":["trace[1842987036] 'process raft request' (duration: 270.841605ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:12.461752Z","caller":"traceutil/trace.go:171","msg":"trace[1647218733] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"335.05085ms","start":"2026-02-25T19:28:12.126673Z","end":"2026-02-25T19:28:12.461724Z","steps":["trace[1647218733] 'process raft request' (duration: 334.847236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:12.461895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:12.126657Z","time spent":"335.165602ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:13.130592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.551175ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:13.130681Z","caller":"traceutil/trace.go:171","msg":"trace[746460596] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"539.767146ms","start":"2026-02-25T19:28:12.590898Z","end":"2026-02-25T19:28:13.130665Z","steps":["trace[746460596] 'read index received' (duration: 8.005166ms)","trace[746460596] 'applied index is now lower than readState.Index' (duration: 531.76093ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:13.13072Z","caller":"traceutil/trace.go:171","msg":"trace[283184712] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"664.926992ms","start":"2026-02-25T19:28:12.465743Z","end":"2026-02-25T19:28:13.13067Z","steps":["trace[283184712] 'process raft request' (duration: 133.232664ms)","trace[283184712] 'compare' (duration: 531.090416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:13.130779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.878879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:28:13.130807Z","caller":"traceutil/trace.go:171","msg":"trace[181292808] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:489; }","duration":"539.94382ms","start":"2026-02-25T19:28:12.590855Z","end":"2026-02-25T19:28:13.130798Z","steps":["trace[181292808] 'agreement among raft nodes before linearized reading' (duration: 539.891089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:13.130828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:12.465722Z","time spent":"665.046066ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:13.130838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:12.590835Z","time spent":"539.996171ms","remote":"127.0.0.1:38452","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-25T19:28:13.130866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.903117ms","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-25T19:28:13.130953Z","caller":"traceutil/trace.go:171","msg":"trace[1296781194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:489; }","duration":"457.018419ms","start":"2026-02-25T19:28:12.673913Z","end":"2026-02-25T19:28:13.130931Z","steps":["trace[1296781194] 'agreement among raft nodes before linearized reading' (duration: 456.905727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:13.131031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:12.673895Z","time spent":"457.123072ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:28:14.51165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.372099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:14.511768Z","caller":"traceutil/trace.go:171","msg":"trace[23593797] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"1.377017893s","start":"2026-02-25T19:28:13.134741Z","end":"2026-02-25T19:28:14.511759Z","steps":["trace[23593797] 'process raft request' (duration: 1.376976802s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.5118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.13473Z","time spent":"1.377055803s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:14.511974Z","caller":"traceutil/trace.go:171","msg":"trace[1216465295] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"1.314783292s","start":"2026-02-25T19:28:13.197182Z","end":"2026-02-25T19:28:14.511966Z","steps":["trace[1216465295] 'process raft request' (duration: 1.314558057s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:14.511972Z","caller":"traceutil/trace.go:171","msg":"trace[101633060] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:511; }","duration":"907.071244ms","start":"2026-02-25T19:28:13.604871Z","end":"2026-02-25T19:28:14.511942Z","steps":["trace[101633060] 'read index received' (duration: 315.48657ms)","trace[101633060] 'applied index is now lower than readState.Index' (duration: 591.583394ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:14.512066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.197166Z","time spent":"1.314845974s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:14.512128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.240758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.59\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T19:28:14.512212Z","caller":"traceutil/trace.go:171","msg":"trace[90795360] range","detail":"{range_begin:/registry/masterleases/199.19.213.59; range_end:; response_count:1; response_revision:492; }","duration":"907.344849ms","start":"2026-02-25T19:28:13.604838Z","end":"2026-02-25T19:28:14.512183Z","steps":["trace[90795360] 'agreement among raft nodes before linearized reading' (duration: 907.205647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:14.512269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.604822Z","time spent":"907.435362ms","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.59\" "} {"level":"info","ts":"2026-02-25T19:28:14.511853Z","caller":"traceutil/trace.go:171","msg":"trace[25714520] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"1.378279298s","start":"2026-02-25T19:28:13.133546Z","end":"2026-02-25T19:28:14.511825Z","steps":["trace[25714520] 'process raft request' (duration: 786.677184ms)","trace[25714520] 'compare' (duration: 591.307698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:14.512609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:13.13353Z","time spent":"1.378988303s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:14.658828Z","caller":"traceutil/trace.go:171","msg":"trace[594118859] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"132.225267ms","start":"2026-02-25T19:28:14.526561Z","end":"2026-02-25T19:28:14.658787Z","steps":["trace[594118859] 'process raft request' (duration: 85.749269ms)","trace[594118859] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/masterleases/199.19.213.59; req_size:116; } (duration: 46.172292ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:16.87818Z","caller":"traceutil/trace.go:171","msg":"trace[679502682] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:521; }","duration":"159.416128ms","start":"2026-02-25T19:28:16.718739Z","end":"2026-02-25T19:28:16.878155Z","steps":["trace[679502682] 'read index received' (duration: 129.399346ms)","trace[679502682] 'applied index is now lower than readState.Index' (duration: 30.015592ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:16.878292Z","caller":"traceutil/trace.go:171","msg":"trace[461108114] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"276.263593ms","start":"2026-02-25T19:28:16.601987Z","end":"2026-02-25T19:28:16.878251Z","steps":["trace[461108114] 'process raft request' (duration: 246.095318ms)","trace[461108114] 'compare' (duration: 29.966651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:16.878392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.629103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:16.878462Z","caller":"traceutil/trace.go:171","msg":"trace[606958287] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:499; }","duration":"159.756375ms","start":"2026-02-25T19:28:16.718689Z","end":"2026-02-25T19:28:16.878446Z","steps":["trace[606958287] 'agreement among raft nodes before linearized reading' (duration: 159.609242ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:17.224214Z","caller":"traceutil/trace.go:171","msg":"trace[734645086] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:522; }","duration":"178.452951ms","start":"2026-02-25T19:28:17.045735Z","end":"2026-02-25T19:28:17.224188Z","steps":["trace[734645086] 'read index received' (duration: 177.726117ms)","trace[734645086] 'applied index is now lower than readState.Index' (duration: 725.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:17.224313Z","caller":"traceutil/trace.go:171","msg":"trace[652702471] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"243.683844ms","start":"2026-02-25T19:28:16.980571Z","end":"2026-02-25T19:28:17.224255Z","steps":["trace[652702471] 'process raft request' (duration: 243.292286ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:17.224375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.628955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:17.224602Z","caller":"traceutil/trace.go:171","msg":"trace[1109226521] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"178.89293ms","start":"2026-02-25T19:28:17.045681Z","end":"2026-02-25T19:28:17.224574Z","steps":["trace[1109226521] 'agreement among raft nodes before linearized reading' (duration: 178.628405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:18.088616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.207025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:18.088766Z","caller":"traceutil/trace.go:171","msg":"trace[1352349569] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"838.048575ms","start":"2026-02-25T19:28:17.250693Z","end":"2026-02-25T19:28:18.088742Z","steps":["trace[1352349569] 'process raft request' (duration: 59.631326ms)","trace[1352349569] 'compare' (duration: 778.012411ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:18.088834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:17.250685Z","time spent":"838.120567ms","remote":"127.0.0.1:38366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:18.690429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.710473ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:18.690533Z","caller":"traceutil/trace.go:171","msg":"trace[1482224289] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"146.738327ms","start":"2026-02-25T19:28:18.543777Z","end":"2026-02-25T19:28:18.690515Z","steps":["trace[1482224289] 'read index received' (duration: 26.511µs)","trace[1482224289] 'applied index is now lower than readState.Index' (duration: 146.710156ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:18.690619Z","caller":"traceutil/trace.go:171","msg":"trace[2069703295] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"596.012816ms","start":"2026-02-25T19:28:18.094595Z","end":"2026-02-25T19:28:18.690608Z","steps":["trace[2069703295] 'process raft request' (duration: 398.047488ms)","trace[2069703295] 'compare' (duration: 197.465378ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:18.690692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:18.094569Z","time spent":"596.094818ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:18.690893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.086554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:28:18.690978Z","caller":"traceutil/trace.go:171","msg":"trace[695771567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:504; }","duration":"147.212806ms","start":"2026-02-25T19:28:18.543745Z","end":"2026-02-25T19:28:18.690958Z","steps":["trace[695771567] 'agreement among raft nodes before linearized reading' (duration: 146.979761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:18.692256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.560844ms","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-25T19:28:18.692317Z","caller":"traceutil/trace.go:171","msg":"trace[1704959687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:504; }","duration":"143.664097ms","start":"2026-02-25T19:28:18.548638Z","end":"2026-02-25T19:28:18.692302Z","steps":["trace[1704959687] 'agreement among raft nodes before linearized reading' (duration: 142.185137ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:18.897905Z","caller":"traceutil/trace.go:171","msg":"trace[71150469] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"155.232574ms","start":"2026-02-25T19:28:18.742638Z","end":"2026-02-25T19:28:18.89787Z","steps":["trace[71150469] 'process raft request' (duration: 61.174558ms)","trace[71150469] 'compare' (duration: 93.629448ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:18.89858Z","caller":"traceutil/trace.go:171","msg":"trace[1115963269] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"155.892587ms","start":"2026-02-25T19:28:18.742671Z","end":"2026-02-25T19:28:18.898563Z","steps":["trace[1115963269] 'process raft request' (duration: 155.142752ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.13776Z","caller":"traceutil/trace.go:171","msg":"trace[224535763] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"184.231286ms","start":"2026-02-25T19:28:18.953492Z","end":"2026-02-25T19:28:19.137723Z","steps":["trace[224535763] 'process raft request' (duration: 144.985787ms)","trace[224535763] 'compare' (duration: 39.001354ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:19.137796Z","caller":"traceutil/trace.go:171","msg":"trace[1934793488] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"106.762791ms","start":"2026-02-25T19:28:19.031011Z","end":"2026-02-25T19:28:19.137774Z","steps":["trace[1934793488] 'read index received' (duration: 67.455481ms)","trace[1934793488] 'applied index is now lower than readState.Index' (duration: 39.30579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:19.234071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.035276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:19.234159Z","caller":"traceutil/trace.go:171","msg":"trace[1743127640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"203.181339ms","start":"2026-02-25T19:28:19.030958Z","end":"2026-02-25T19:28:19.23414Z","steps":["trace[1743127640] 'agreement among raft nodes before linearized reading' (duration: 106.896294ms)","trace[1743127640] 'range keys from in-memory index tree' (duration: 96.152673ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:19.322887Z","caller":"traceutil/trace.go:171","msg":"trace[1928610968] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"175.646289ms","start":"2026-02-25T19:28:19.1472Z","end":"2026-02-25T19:28:19.322846Z","steps":["trace[1928610968] 'process raft request' (duration: 175.405854ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.446521Z","caller":"traceutil/trace.go:171","msg":"trace[1747871953] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"118.600482ms","start":"2026-02-25T19:28:19.327889Z","end":"2026-02-25T19:28:19.44649Z","steps":["trace[1747871953] 'process raft request' (duration: 27.06157ms)","trace[1747871953] 'compare' (duration: 91.4277ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:19.451002Z","caller":"traceutil/trace.go:171","msg":"trace[1463829495] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"119.610262ms","start":"2026-02-25T19:28:19.331384Z","end":"2026-02-25T19:28:19.450994Z","steps":["trace[1463829495] 'process raft request' (duration: 119.588642ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.451047Z","caller":"traceutil/trace.go:171","msg":"trace[1245521004] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"119.834547ms","start":"2026-02-25T19:28:19.331189Z","end":"2026-02-25T19:28:19.451024Z","steps":["trace[1245521004] 'process raft request' (duration: 119.743075ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.616579Z","caller":"traceutil/trace.go:171","msg":"trace[1679024964] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"160.532472ms","start":"2026-02-25T19:28:19.456025Z","end":"2026-02-25T19:28:19.616558Z","steps":["trace[1679024964] 'process raft request' (duration: 128.720489ms)","trace[1679024964] 'compare' (duration: 31.715611ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:19.836807Z","caller":"traceutil/trace.go:171","msg":"trace[154472388] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"377.814917ms","start":"2026-02-25T19:28:19.458965Z","end":"2026-02-25T19:28:19.836779Z","steps":["trace[154472388] 'process raft request' (duration: 377.130413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:19.841161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:19.458942Z","time spent":"382.079591ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:28:19.844458Z","caller":"traceutil/trace.go:171","msg":"trace[61727097] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"295.360702ms","start":"2026-02-25T19:28:19.549045Z","end":"2026-02-25T19:28:19.844405Z","steps":["trace[61727097] 'process raft request' (duration: 289.14499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.898917Z","caller":"traceutil/trace.go:171","msg":"trace[378215273] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"277.334801ms","start":"2026-02-25T19:28:19.621539Z","end":"2026-02-25T19:28:19.898874Z","steps":["trace[378215273] 'process raft request' (duration: 277.129847ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.898946Z","caller":"traceutil/trace.go:171","msg":"trace[1914989288] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"276.386222ms","start":"2026-02-25T19:28:19.622529Z","end":"2026-02-25T19:28:19.898915Z","steps":["trace[1914989288] 'process raft request' (duration: 276.30718ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:19.899327Z","caller":"traceutil/trace.go:171","msg":"trace[38013871] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"277.34662ms","start":"2026-02-25T19:28:19.621657Z","end":"2026-02-25T19:28:19.899004Z","steps":["trace[38013871] 'process raft request' (duration: 277.129766ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:20.0768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.178126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:20.076989Z","caller":"traceutil/trace.go:171","msg":"trace[45728609] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"151.620417ms","start":"2026-02-25T19:28:19.925341Z","end":"2026-02-25T19:28:20.076962Z","steps":["trace[45728609] 'process raft request' (duration: 17.211877ms)","trace[45728609] 'compare' (duration: 134.056414ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:20.077113Z","caller":"traceutil/trace.go:171","msg":"trace[1298845596] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"149.488645ms","start":"2026-02-25T19:28:19.92761Z","end":"2026-02-25T19:28:20.077099Z","steps":["trace[1298845596] 'read index received' (duration: 14.924092ms)","trace[1298845596] 'applied index is now lower than readState.Index' (duration: 134.562643ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:20.077227Z","caller":"traceutil/trace.go:171","msg":"trace[1100061944] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"144.549779ms","start":"2026-02-25T19:28:19.932603Z","end":"2026-02-25T19:28:20.077153Z","steps":["trace[1100061944] 'process raft request' (duration: 144.364365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:20.077451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.776793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329960"} {"level":"info","ts":"2026-02-25T19:28:20.077485Z","caller":"traceutil/trace.go:171","msg":"trace[577642777] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:531; }","duration":"144.848934ms","start":"2026-02-25T19:28:19.932626Z","end":"2026-02-25T19:28:20.077475Z","steps":["trace[577642777] 'agreement among raft nodes before linearized reading' (duration: 144.570819ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:20.077613Z","caller":"traceutil/trace.go:171","msg":"trace[2077650870] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"142.776954ms","start":"2026-02-25T19:28:19.934405Z","end":"2026-02-25T19:28:20.077181Z","steps":["trace[2077650870] 'process raft request' (duration: 142.622901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:20.078174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.197708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6c452\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-02-25T19:28:20.078352Z","caller":"traceutil/trace.go:171","msg":"trace[733924957] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6c452; range_end:; response_count:1; response_revision:531; }","duration":"154.413432ms","start":"2026-02-25T19:28:19.923921Z","end":"2026-02-25T19:28:20.078335Z","steps":["trace[733924957] 'agreement among raft nodes before linearized reading' (duration: 153.27157ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:20.210145Z","caller":"traceutil/trace.go:171","msg":"trace[930033848] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"113.593196ms","start":"2026-02-25T19:28:20.096535Z","end":"2026-02-25T19:28:20.210128Z","steps":["trace[930033848] 'process raft request' (duration: 113.507315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:20.21035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.854942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-02-25T19:28:20.210415Z","caller":"traceutil/trace.go:171","msg":"trace[476386336] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:534; }","duration":"113.962734ms","start":"2026-02-25T19:28:20.096437Z","end":"2026-02-25T19:28:20.2104Z","steps":["trace[476386336] 'agreement among raft nodes before linearized reading' (duration: 113.77358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.053881Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106299,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:24.073227Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018313099s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:28:24.073657Z","caller":"traceutil/trace.go:171","msg":"trace[306115863] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"535.169283ms","start":"2026-02-25T19:28:23.538462Z","end":"2026-02-25T19:28:24.073631Z","steps":["trace[306115863] 'read index received' (duration: 534.937878ms)","trace[306115863] 'applied index is now lower than readState.Index' (duration: 230.105µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:24.073762Z","caller":"traceutil/trace.go:171","msg":"trace[1415602310] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.01893201s","start":"2026-02-25T19:28:23.054814Z","end":"2026-02-25T19:28:24.073746Z","steps":["trace[1415602310] 'process raft request' (duration: 1.018670955s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.073904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:23.054793Z","time spent":"1.019008022s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:24.073952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.829625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.59\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T19:28:24.073991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.51269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:28:24.074026Z","caller":"traceutil/trace.go:171","msg":"trace[716435269] range","detail":"{range_begin:/registry/masterleases/199.19.213.59; range_end:; response_count:1; response_revision:546; }","duration":"468.964247ms","start":"2026-02-25T19:28:23.605042Z","end":"2026-02-25T19:28:24.074007Z","steps":["trace[716435269] 'agreement among raft nodes before linearized reading' (duration: 468.793573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:24.074061Z","caller":"traceutil/trace.go:171","msg":"trace[2014855606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"535.592021ms","start":"2026-02-25T19:28:23.538449Z","end":"2026-02-25T19:28:24.074041Z","steps":["trace[2014855606] 'agreement among raft nodes before linearized reading' (duration: 535.397778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:24.074075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:23.605023Z","time spent":"469.042359ms","remote":"127.0.0.1:38154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/199.19.213.59\" "} {"level":"warn","ts":"2026-02-25T19:28:24.074098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:23.538407Z","time spent":"535.682332ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T19:28:24.199972Z","caller":"traceutil/trace.go:171","msg":"trace[1220229365] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"120.673611ms","start":"2026-02-25T19:28:24.07928Z","end":"2026-02-25T19:28:24.199954Z","steps":["trace[1220229365] 'process raft request' (duration: 119.462979ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:24.220698Z","caller":"traceutil/trace.go:171","msg":"trace[1293944223] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"134.727016ms","start":"2026-02-25T19:28:24.085953Z","end":"2026-02-25T19:28:24.22068Z","steps":["trace[1293944223] 'process raft request' (duration: 134.617054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:24.953618Z","caller":"traceutil/trace.go:171","msg":"trace[842056775] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"231.258993ms","start":"2026-02-25T19:28:24.72234Z","end":"2026-02-25T19:28:24.953599Z","steps":["trace[842056775] 'process raft request' (duration: 230.810515ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:25.378951Z","caller":"traceutil/trace.go:171","msg":"trace[820537125] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"420.685116ms","start":"2026-02-25T19:28:24.958238Z","end":"2026-02-25T19:28:25.378923Z","steps":["trace[820537125] 'process raft request' (duration: 415.972148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.3791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.958218Z","time spent":"420.807659ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:25.379468Z","caller":"traceutil/trace.go:171","msg":"trace[793928323] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:575; }","duration":"184.200341ms","start":"2026-02-25T19:28:25.19524Z","end":"2026-02-25T19:28:25.379441Z","steps":["trace[793928323] 'read index received' (duration: 179.089476ms)","trace[793928323] 'applied index is now lower than readState.Index' (duration: 5.109795ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:25.379589Z","caller":"traceutil/trace.go:171","msg":"trace[1979740981] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"420.627825ms","start":"2026-02-25T19:28:24.958943Z","end":"2026-02-25T19:28:25.37957Z","steps":["trace[1979740981] 'process raft request' (duration: 420.33889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.379596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.328954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:25.37965Z","caller":"traceutil/trace.go:171","msg":"trace[295968947] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:551; }","duration":"184.432655ms","start":"2026-02-25T19:28:25.195199Z","end":"2026-02-25T19:28:25.379632Z","steps":["trace[295968947] 'agreement among raft nodes before linearized reading' (duration: 184.337873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:25.379669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:24.95893Z","time spent":"420.699056ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:26.352602Z","caller":"traceutil/trace.go:171","msg":"trace[1270552445] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"543.28089ms","start":"2026-02-25T19:28:25.809293Z","end":"2026-02-25T19:28:26.352574Z","steps":["trace[1270552445] 'process raft request' (duration: 543.064396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:26.35276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:25.809274Z","time spent":"543.405883ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:28:26.412025Z","caller":"traceutil/trace.go:171","msg":"trace[592444210] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:578; }","duration":"379.417834ms","start":"2026-02-25T19:28:26.032579Z","end":"2026-02-25T19:28:26.411997Z","steps":["trace[592444210] 'read index received' (duration: 319.787219ms)","trace[592444210] 'applied index is now lower than readState.Index' (duration: 59.629515ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:26.412128Z","caller":"traceutil/trace.go:171","msg":"trace[1124276090] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"597.478024ms","start":"2026-02-25T19:28:25.814626Z","end":"2026-02-25T19:28:26.412104Z","steps":["trace[1124276090] 'process raft request' (duration: 597.304041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:26.412201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.608608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:26.412186Z","caller":"traceutil/trace.go:171","msg":"trace[646471086] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"602.129881ms","start":"2026-02-25T19:28:25.810019Z","end":"2026-02-25T19:28:26.412149Z","steps":["trace[646471086] 'process raft request' (duration: 601.792145ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:26.412237Z","caller":"traceutil/trace.go:171","msg":"trace[328072036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:555; }","duration":"379.656709ms","start":"2026-02-25T19:28:26.032568Z","end":"2026-02-25T19:28:26.412225Z","steps":["trace[328072036] 'agreement among raft nodes before linearized reading' (duration: 379.567687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:26.41224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:25.81461Z","time spent":"597.570786ms","remote":"127.0.0.1:38240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:26.412275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:26.032519Z","time spent":"379.7505ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:26.412291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:25.809994Z","time spent":"602.252253ms","remote":"127.0.0.1:38366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:26.412549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.926411ms","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-25T19:28:26.412652Z","caller":"traceutil/trace.go:171","msg":"trace[30668529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"334.083774ms","start":"2026-02-25T19:28:26.078539Z","end":"2026-02-25T19:28:26.412623Z","steps":["trace[30668529] 'agreement among raft nodes before linearized reading' (duration: 333.791759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:26.412709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:26.078519Z","time spent":"334.177085ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:26.412721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.250529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T19:28:26.412778Z","caller":"traceutil/trace.go:171","msg":"trace[1103746647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"209.345712ms","start":"2026-02-25T19:28:26.203415Z","end":"2026-02-25T19:28:26.412761Z","steps":["trace[1103746647] 'agreement among raft nodes before linearized reading' (duration: 209.177288ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:26.654279Z","caller":"traceutil/trace.go:171","msg":"trace[2146543728] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"111.14498ms","start":"2026-02-25T19:28:26.543113Z","end":"2026-02-25T19:28:26.654258Z","steps":["trace[2146543728] 'process raft request' (duration: 111.013968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:26.807761Z","caller":"traceutil/trace.go:171","msg":"trace[366824278] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"149.317978ms","start":"2026-02-25T19:28:26.658416Z","end":"2026-02-25T19:28:26.807734Z","steps":["trace[366824278] 'process raft request' (duration: 68.462739ms)","trace[366824278] 'compare' (duration: 80.733537ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:27.034259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.733073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:27.034355Z","caller":"traceutil/trace.go:171","msg":"trace[1854978845] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:566; }","duration":"135.871875ms","start":"2026-02-25T19:28:26.898462Z","end":"2026-02-25T19:28:27.034334Z","steps":["trace[1854978845] 'range keys from in-memory index tree' (duration: 126.581393ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.044952Z","caller":"traceutil/trace.go:171","msg":"trace[215605158] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"145.93444ms","start":"2026-02-25T19:28:26.899001Z","end":"2026-02-25T19:28:27.044935Z","steps":["trace[215605158] 'process raft request' (duration: 145.721556ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.045006Z","caller":"traceutil/trace.go:171","msg":"trace[477549768] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"145.008073ms","start":"2026-02-25T19:28:26.89997Z","end":"2026-02-25T19:28:27.044978Z","steps":["trace[477549768] 'process raft request' (duration: 144.85426ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.160537Z","caller":"traceutil/trace.go:171","msg":"trace[349223657] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"103.980263ms","start":"2026-02-25T19:28:27.056534Z","end":"2026-02-25T19:28:27.160514Z","steps":["trace[349223657] 'process raft request' (duration: 103.85638ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.16054Z","caller":"traceutil/trace.go:171","msg":"trace[1026046460] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"104.031024ms","start":"2026-02-25T19:28:27.056471Z","end":"2026-02-25T19:28:27.160502Z","steps":["trace[1026046460] 'process raft request' (duration: 103.77887ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.160934Z","caller":"traceutil/trace.go:171","msg":"trace[766279701] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"104.185947ms","start":"2026-02-25T19:28:27.056729Z","end":"2026-02-25T19:28:27.160915Z","steps":["trace[766279701] 'process raft request' (duration: 103.722449ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:27.161158Z","caller":"traceutil/trace.go:171","msg":"trace[502624650] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"104.243818ms","start":"2026-02-25T19:28:27.056842Z","end":"2026-02-25T19:28:27.161086Z","steps":["trace[502624650] 'process raft request' (duration: 103.968383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:30.033886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.44686ms","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-25T19:28:30.033955Z","caller":"traceutil/trace.go:171","msg":"trace[2140151933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"621.560533ms","start":"2026-02-25T19:28:29.41238Z","end":"2026-02-25T19:28:30.033941Z","steps":["trace[2140151933] 'range keys from in-memory index tree' (duration: 621.321848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:30.033957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.349848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T19:28:30.034032Z","caller":"traceutil/trace.go:171","msg":"trace[367864559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"632.492771ms","start":"2026-02-25T19:28:29.401521Z","end":"2026-02-25T19:28:30.034013Z","steps":["trace[367864559] 'range keys from in-memory index tree' (duration: 632.178555ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:30.034082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:29.4015Z","time spent":"632.566252ms","remote":"127.0.0.1:38452","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-25T19:28:30.034175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075334394s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:30.034283Z","caller":"traceutil/trace.go:171","msg":"trace[254871077] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:580; }","duration":"1.075448876s","start":"2026-02-25T19:28:28.958816Z","end":"2026-02-25T19:28:30.034265Z","steps":["trace[254871077] 'range keys from in-memory index tree' (duration: 1.075319964s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:30.034318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.057343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:28:30.033987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:29.412363Z","time spent":"621.617593ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T19:28:30.034378Z","caller":"traceutil/trace.go:171","msg":"trace[1345705790] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"984.166345ms","start":"2026-02-25T19:28:29.050198Z","end":"2026-02-25T19:28:30.034364Z","steps":["trace[1345705790] 'range keys from in-memory index tree' (duration: 983.969862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:30.034406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:29.050179Z","time spent":"984.221346ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:35.092877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106417,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:28:35.571529Z","caller":"traceutil/trace.go:171","msg":"trace[1861480529] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"979.141365ms","start":"2026-02-25T19:28:34.592356Z","end":"2026-02-25T19:28:35.571497Z","steps":["trace[1861480529] 'read index received' (duration: 978.951362ms)","trace[1861480529] 'applied index is now lower than readState.Index' (duration: 188.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:35.571682Z","caller":"traceutil/trace.go:171","msg":"trace[600559879] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.033368733s","start":"2026-02-25T19:28:34.538302Z","end":"2026-02-25T19:28:35.57167Z","steps":["trace[600559879] 'process raft request' (duration: 1.033040238s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:35.571798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:34.538276Z","time spent":"1.033443395s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:35.572003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.630154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6c452\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-02-25T19:28:35.572087Z","caller":"traceutil/trace.go:171","msg":"trace[1282488929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6c452; range_end:; response_count:1; response_revision:595; }","duration":"979.752856ms","start":"2026-02-25T19:28:34.592312Z","end":"2026-02-25T19:28:35.572065Z","steps":["trace[1282488929] 'agreement among raft nodes before linearized reading' (duration: 979.526652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:35.572141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:34.592293Z","time spent":"979.832987ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16983,"request content":"key:\"/registry/pods/kube-system/cilium-6c452\" "} {"level":"info","ts":"2026-02-25T19:28:35.611397Z","caller":"traceutil/trace.go:171","msg":"trace[1256904586] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"340.231645ms","start":"2026-02-25T19:28:35.271133Z","end":"2026-02-25T19:28:35.611365Z","steps":["trace[1256904586] 'process raft request' (duration: 340.016422ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:35.611439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.99746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:35.611518Z","caller":"traceutil/trace.go:171","msg":"trace[571506479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:596; }","duration":"579.132172ms","start":"2026-02-25T19:28:35.032368Z","end":"2026-02-25T19:28:35.611501Z","steps":["trace[571506479] 'agreement among raft nodes before linearized reading' (duration: 579.001109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:35.611563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:35.032356Z","time spent":"579.194104ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:35.61157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:35.271112Z","time spent":"340.366857ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:35.61168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011327983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:28:35.611828Z","caller":"traceutil/trace.go:171","msg":"trace[1956553701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:596; }","duration":"1.011443546s","start":"2026-02-25T19:28:34.600293Z","end":"2026-02-25T19:28:35.611737Z","steps":["trace[1956553701] 'agreement among raft nodes before linearized reading' (duration: 1.010986438s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:35.611905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:34.600276Z","time spent":"1.011594068s","remote":"127.0.0.1:38452","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-25T19:28:36.113751Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:36.614886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:37.11547Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:37.278898Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.668025665s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T19:28:37.279969Z","caller":"traceutil/trace.go:171","msg":"trace[612583952] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.696049051s","start":"2026-02-25T19:28:35.58388Z","end":"2026-02-25T19:28:37.279929Z","steps":["trace[612583952] 'process raft request' (duration: 1.695243168s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:37.280173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:35.583863Z","time spent":"1.696216244s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:37.612503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99969239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:28:37.612581Z","caller":"traceutil/trace.go:171","msg":"trace[1252008852] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999928655s","start":"2026-02-25T19:28:35.612628Z","end":"2026-02-25T19:28:37.612556Z","steps":["trace[1252008852] 'agreement among raft nodes before linearized reading' (duration: 1.999838493s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:37.612621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:35.612614Z","time spent":"1.999998336s","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:28:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-25T19:28:38.179932Z","caller":"traceutil/trace.go:171","msg":"trace[1104610035] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:625; }","duration":"2.567095932s","start":"2026-02-25T19:28:35.612786Z","end":"2026-02-25T19:28:38.179882Z","steps":["trace[1104610035] 'read index received' (duration: 1.666335696s)","trace[1104610035] 'applied index is now lower than readState.Index' (duration: 900.759066ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:38.180066Z","caller":"traceutil/trace.go:171","msg":"trace[564931713] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.563239135s","start":"2026-02-25T19:28:35.61674Z","end":"2026-02-25T19:28:38.17998Z","steps":["trace[564931713] 'process raft request' (duration: 2.562896459s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:38.180087Z","caller":"traceutil/trace.go:171","msg":"trace[792084965] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.265563041s","start":"2026-02-25T19:28:36.914464Z","end":"2026-02-25T19:28:38.180027Z","steps":["trace[792084965] 'process raft request' (duration: 1.265372058s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:38.180458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:36.914434Z","time spent":"1.265949098s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:38.180728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:35.616725Z","time spent":"2.56354641s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:38.680316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106426,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:39.620771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000289807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:28:39.620834Z","caller":"traceutil/trace.go:171","msg":"trace[677365575] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00041724s","start":"2026-02-25T19:28:37.620403Z","end":"2026-02-25T19:28:39.62082Z","steps":["trace[677365575] 'agreement among raft nodes before linearized reading' (duration: 2.000325518s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:39.620864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.620384Z","time spent":"2.00047472s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:40.739646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.628996689s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:28:41.070237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999928683s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:28:41.070326Z","caller":"traceutil/trace.go:171","msg":"trace[1055245772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.00031755s","start":"2026-02-25T19:28:36.069985Z","end":"2026-02-25T19:28:41.070303Z","steps":["trace[1055245772] 'agreement among raft nodes before linearized reading' (duration: 5.000208798s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:41.070375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:36.069965Z","time spent":"5.000400641s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/25 19:28:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:28:41.400835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00045747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T19:28:41.400915Z","caller":"traceutil/trace.go:171","msg":"trace[2060590337] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000597561s","start":"2026-02-25T19:28:39.400301Z","end":"2026-02-25T19:28:41.400899Z","steps":["trace[2060590337] 'agreement among raft nodes before linearized reading' (duration: 2.00048941s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:41.400982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:39.400282Z","time spent":"2.000689044s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:28:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:28:41.622003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000411689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:28:41.622066Z","caller":"traceutil/trace.go:171","msg":"trace[1858811514] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000543051s","start":"2026-02-25T19:28:39.621509Z","end":"2026-02-25T19:28:41.622053Z","steps":["trace[1858811514] 'agreement among raft nodes before linearized reading' (duration: 2.00046079s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:41.622099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:39.621478Z","time spent":"2.000613782s","remote":"127.0.0.1:50444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 19:28:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:28:42.575397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999488571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T19:28:42.575463Z","caller":"traceutil/trace.go:171","msg":"trace[1351110873] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999570812s","start":"2026-02-25T19:28:37.575876Z","end":"2026-02-25T19:28:42.575446Z","steps":["trace[1351110873] 'agreement among raft nodes before linearized reading' (duration: 4.999485481s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:42.575497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.575821Z","time spent":"4.999669134s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/25 19:28:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T19:28:42.761951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.803547156s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:42.762094Z","caller":"traceutil/trace.go:171","msg":"trace[534265708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:599; }","duration":"3.803708908s","start":"2026-02-25T19:28:38.958363Z","end":"2026-02-25T19:28:42.762071Z","steps":["trace[534265708] 'range keys from in-memory index tree' (duration: 3.803528235s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:42.762169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.913298591s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:28:42.762318Z","caller":"traceutil/trace.go:171","msg":"trace[1188889595] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"5.146520341s","start":"2026-02-25T19:28:37.615784Z","end":"2026-02-25T19:28:42.762304Z","steps":["trace[1188889595] 'process raft request' (duration: 1.233012036s)","trace[1188889595] 'compare' (duration: 3.913021447s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:42.762414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.615764Z","time spent":"5.146593502s","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:28:42.762719Z","caller":"traceutil/trace.go:171","msg":"trace[1693121010] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"4.58270462s","start":"2026-02-25T19:28:38.179995Z","end":"2026-02-25T19:28:42.7627Z","steps":["trace[1693121010] 'read index received' (duration: 668.8144ms)","trace[1693121010] 'applied index is now lower than readState.Index' (duration: 3.91388807s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:43.218465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.201695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-02-25T19:28:43.218596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.931462692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6c452\" ","response":"range_response_count:1 size:17145"} {"level":"warn","ts":"2026-02-25T19:28:43.218601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.559639729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:28:43.218686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.054974413s","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-25T19:28:43.218695Z","caller":"traceutil/trace.go:171","msg":"trace[1463627496] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:600; }","duration":"5.559782911s","start":"2026-02-25T19:28:37.658884Z","end":"2026-02-25T19:28:43.218667Z","steps":["trace[1463627496] 'agreement among raft nodes before linearized reading' (duration: 5.103942882s)","trace[1463627496] 'count revisions from in-memory index tree' (duration: 455.676346ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:43.218696Z","caller":"traceutil/trace.go:171","msg":"trace[1657304125] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6c452; range_end:; response_count:1; response_revision:600; }","duration":"5.931564964s","start":"2026-02-25T19:28:37.287092Z","end":"2026-02-25T19:28:43.218657Z","steps":["trace[1657304125] 'agreement among raft nodes before linearized reading' (duration: 5.475751095s)","trace[1657304125] 'range keys from in-memory index tree' (duration: 455.608545ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:43.218746Z","caller":"traceutil/trace.go:171","msg":"trace[1270206614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:600; }","duration":"7.055081465s","start":"2026-02-25T19:28:36.163649Z","end":"2026-02-25T19:28:43.218731Z","steps":["trace[1270206614] 'agreement among raft nodes before linearized reading' (duration: 6.599200785s)","trace[1270206614] 'range keys from in-memory index tree' (duration: 455.714967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:43.218764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.658874Z","time spent":"5.559873422s","remote":"127.0.0.1:38714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:28:43.218778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:36.163629Z","time spent":"7.055142756s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:43.218768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:37.287079Z","time spent":"5.931673815s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17167,"request content":"key:\"/registry/pods/kube-system/cilium-6c452\" "} {"level":"warn","ts":"2026-02-25T19:28:43.218836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.034204674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:28:43.218872Z","caller":"traceutil/trace.go:171","msg":"trace[1289330710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"3.034285456s","start":"2026-02-25T19:28:40.184577Z","end":"2026-02-25T19:28:43.218862Z","steps":["trace[1289330710] 'agreement among raft nodes before linearized reading' (duration: 3.034152284s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:43.218697Z","caller":"traceutil/trace.go:171","msg":"trace[2225246] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"455.90241ms","start":"2026-02-25T19:28:42.762777Z","end":"2026-02-25T19:28:43.21868Z","steps":["trace[2225246] 'read index received' (duration: 80.861µs)","trace[2225246] 'applied index is now lower than readState.Index' (duration: 455.820539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:43.218906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:40.184556Z","time spent":"3.034343347s","remote":"127.0.0.1:38452","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-25T19:28:43.219137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.461011358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:43.21917Z","caller":"traceutil/trace.go:171","msg":"trace[1326906958] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:600; }","duration":"1.46108342s","start":"2026-02-25T19:28:41.758076Z","end":"2026-02-25T19:28:43.219159Z","steps":["trace[1326906958] 'agreement among raft nodes before linearized reading' (duration: 1.461028529s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.2192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:41.758056Z","time spent":"1.461134451s","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-02-25T19:28:43.219205Z","caller":"traceutil/trace.go:171","msg":"trace[1005949107] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"452.017577ms","start":"2026-02-25T19:28:42.767171Z","end":"2026-02-25T19:28:43.219189Z","steps":["trace[1005949107] 'process raft request' (duration: 451.417967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.219302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:42.767146Z","time spent":"452.099327ms","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:43.228625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.706551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:28:43.228715Z","caller":"traceutil/trace.go:171","msg":"trace[94289220] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:601; }","duration":"364.856293ms","start":"2026-02-25T19:28:42.86384Z","end":"2026-02-25T19:28:43.228696Z","steps":["trace[94289220] 'agreement among raft nodes before linearized reading' (duration: 355.353307ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.228764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:42.863819Z","time spent":"364.933873ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":21,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:28:43.229008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.40429ms","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-25T19:28:43.229057Z","caller":"traceutil/trace.go:171","msg":"trace[2009957323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"156.493402ms","start":"2026-02-25T19:28:43.072553Z","end":"2026-02-25T19:28:43.229046Z","steps":["trace[2009957323] 'agreement among raft nodes before linearized reading' (duration: 146.657941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.229112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.946760637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:28:43.229154Z","caller":"traceutil/trace.go:171","msg":"trace[1828035320] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:601; }","duration":"1.946844239s","start":"2026-02-25T19:28:41.282299Z","end":"2026-02-25T19:28:43.229143Z","steps":["trace[1828035320] 'agreement among raft nodes before linearized reading' (duration: 1.936850745s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.229186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:41.282272Z","time spent":"1.946903799s","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:28:43.229315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584458373s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:43.229336Z","caller":"traceutil/trace.go:171","msg":"trace[2113605577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"1.584506174s","start":"2026-02-25T19:28:41.644825Z","end":"2026-02-25T19:28:43.229331Z","steps":["trace[2113605577] 'agreement among raft nodes before linearized reading' (duration: 1.574328037s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:43.229407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:41.644807Z","time spent":"1.584594445s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:28:43.769659Z","caller":"traceutil/trace.go:171","msg":"trace[1984255703] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:636; }","duration":"147.782009ms","start":"2026-02-25T19:28:43.621847Z","end":"2026-02-25T19:28:43.769628Z","steps":["trace[1984255703] 'read index received' (duration: 147.770329ms)","trace[1984255703] 'applied index is now lower than readState.Index' (duration: 9.36µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:43.769873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.001073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6c452\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-02-25T19:28:43.769936Z","caller":"traceutil/trace.go:171","msg":"trace[869013165] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6c452; range_end:; response_count:1; response_revision:605; }","duration":"148.112094ms","start":"2026-02-25T19:28:43.621808Z","end":"2026-02-25T19:28:43.76992Z","steps":["trace[869013165] 'agreement among raft nodes before linearized reading' (duration: 147.909111ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:43.862332Z","caller":"traceutil/trace.go:171","msg":"trace[827454423] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"239.641819ms","start":"2026-02-25T19:28:43.622671Z","end":"2026-02-25T19:28:43.862313Z","steps":["trace[827454423] 'process raft request' (duration: 234.046637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:44.890994Z","caller":"traceutil/trace.go:171","msg":"trace[81543280] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"136.219654ms","start":"2026-02-25T19:28:44.754749Z","end":"2026-02-25T19:28:44.890969Z","steps":["trace[81543280] 'process raft request' (duration: 136.085172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:46.489436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:46.993379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106465,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T19:28:47.080582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.094828105s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T19:28:47.083501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.812070642s","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-25T19:28:47.083576Z","caller":"traceutil/trace.go:171","msg":"trace[700618633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"1.812168403s","start":"2026-02-25T19:28:45.271383Z","end":"2026-02-25T19:28:47.083551Z","steps":["trace[700618633] 'range keys from in-memory index tree' (duration: 1.81192759s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.083628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.271371Z","time spent":"1.812245315s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T19:28:47.083932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016304225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:28:47.084003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.844453628s","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-25T19:28:47.084006Z","caller":"traceutil/trace.go:171","msg":"trace[269052822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"2.016392127s","start":"2026-02-25T19:28:45.0676Z","end":"2026-02-25T19:28:47.083992Z","steps":["trace[269052822] 'range keys from in-memory index tree' (duration: 2.016245744s)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:47.084032Z","caller":"traceutil/trace.go:171","msg":"trace[475148273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.844526658s","start":"2026-02-25T19:28:45.239497Z","end":"2026-02-25T19:28:47.084024Z","steps":["trace[475148273] 'range keys from in-memory index tree' (duration: 1.844337875s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.084048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.067586Z","time spent":"2.016449278s","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T19:28:47.084057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.239477Z","time spent":"1.844572939s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:47.084099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814474141s","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-25T19:28:47.084152Z","caller":"traceutil/trace.go:171","msg":"trace[547302908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"1.814556432s","start":"2026-02-25T19:28:45.26958Z","end":"2026-02-25T19:28:47.084136Z","steps":["trace[547302908] 'range keys from in-memory index tree' (duration: 1.814215577s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.084181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.269566Z","time spent":"1.814608682s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T19:28:47.084284Z","caller":"traceutil/trace.go:171","msg":"trace[1086093131] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.098563435s","start":"2026-02-25T19:28:45.985687Z","end":"2026-02-25T19:28:47.08425Z","steps":["trace[1086093131] 'process raft request' (duration: 1.095043048s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.084392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.985668Z","time spent":"1.098648676s","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:47.093329Z","caller":"traceutil/trace.go:171","msg":"trace[1351131270] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"1.105087859s","start":"2026-02-25T19:28:45.988228Z","end":"2026-02-25T19:28:47.093316Z","steps":["trace[1351131270] 'read index received' (duration: 1.092513738s)","trace[1351131270] 'applied index is now lower than readState.Index' (duration: 12.573411ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:47.093406Z","caller":"traceutil/trace.go:171","msg":"trace[1030882062] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.104438108s","start":"2026-02-25T19:28:45.988962Z","end":"2026-02-25T19:28:47.0934Z","steps":["trace[1030882062] 'process raft request' (duration: 1.104243565s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.093457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.988952Z","time spent":"1.104468899s","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:47.093787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105553686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-csp8k\" ","response":"range_response_count:1 size:4679"} {"level":"info","ts":"2026-02-25T19:28:47.093815Z","caller":"traceutil/trace.go:171","msg":"trace[409694785] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-csp8k; range_end:; response_count:1; response_revision:621; }","duration":"1.105585407s","start":"2026-02-25T19:28:45.988223Z","end":"2026-02-25T19:28:47.093809Z","steps":["trace[409694785] 'agreement among raft nodes before linearized reading' (duration: 1.105500735s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.093833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:45.988179Z","time spent":"1.105650117s","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4701,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-csp8k\" "} {"level":"warn","ts":"2026-02-25T19:28:47.095749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092099907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T19:28:47.095812Z","caller":"traceutil/trace.go:171","msg":"trace[1633355760] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:624; }","duration":"1.092192959s","start":"2026-02-25T19:28:46.003605Z","end":"2026-02-25T19:28:47.095798Z","steps":["trace[1633355760] 'agreement among raft nodes before linearized reading' (duration: 1.092073997s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.096082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:46.003592Z","time spent":"1.092480934s","remote":"127.0.0.1:38256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-25T19:28:47.096035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.569216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T19:28:47.09619Z","caller":"traceutil/trace.go:171","msg":"trace[972087334] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:624; }","duration":"367.728269ms","start":"2026-02-25T19:28:46.72845Z","end":"2026-02-25T19:28:47.096179Z","steps":["trace[972087334] 'agreement among raft nodes before linearized reading' (duration: 367.551816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.096218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:46.728397Z","time spent":"367.81044ms","remote":"127.0.0.1:38632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T19:28:47.618161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3427411485650106478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T19:28:47.809001Z","caller":"traceutil/trace.go:171","msg":"trace[2022508427] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"700.685755ms","start":"2026-02-25T19:28:47.108272Z","end":"2026-02-25T19:28:47.808957Z","steps":["trace[2022508427] 'process raft request' (duration: 700.521773ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.809155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.108262Z","time spent":"700.814117ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4869,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:47.810634Z","caller":"traceutil/trace.go:171","msg":"trace[1568489392] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"692.762738ms","start":"2026-02-25T19:28:47.117853Z","end":"2026-02-25T19:28:47.810616Z","steps":["trace[1568489392] 'read index received' (duration: 691.451547ms)","trace[1568489392] 'applied index is now lower than readState.Index' (duration: 1.310401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:47.810749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.87761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:47.81078Z","caller":"traceutil/trace.go:171","msg":"trace[1613896107] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:628; }","duration":"692.94504ms","start":"2026-02-25T19:28:47.117829Z","end":"2026-02-25T19:28:47.810774Z","steps":["trace[1613896107] 'agreement among raft nodes before linearized reading' (duration: 692.855739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.81081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.117819Z","time spent":"692.982952ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T19:28:47.810973Z","caller":"traceutil/trace.go:171","msg":"trace[641789337] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"702.100098ms","start":"2026-02-25T19:28:47.108831Z","end":"2026-02-25T19:28:47.810931Z","steps":["trace[641789337] 'process raft request' (duration: 701.680061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:47.811084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.108824Z","time spent":"702.202729ms","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:48.285525Z","caller":"traceutil/trace.go:171","msg":"trace[468600984] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"458.648978ms","start":"2026-02-25T19:28:47.826851Z","end":"2026-02-25T19:28:48.285499Z","steps":["trace[468600984] 'read index received' (duration: 409.214351ms)","trace[468600984] 'applied index is now lower than readState.Index' (duration: 49.433547ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:48.285598Z","caller":"traceutil/trace.go:171","msg":"trace[1989491845] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"458.750069ms","start":"2026-02-25T19:28:47.826818Z","end":"2026-02-25T19:28:48.285568Z","steps":["trace[1989491845] 'process raft request' (duration: 409.15999ms)","trace[1989491845] 'compare' (duration: 49.099972ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:48.285705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.842471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T19:28:48.285698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.830591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:48.285739Z","caller":"traceutil/trace.go:171","msg":"trace[106168174] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"458.901802ms","start":"2026-02-25T19:28:47.826827Z","end":"2026-02-25T19:28:48.285728Z","steps":["trace[106168174] 'agreement among raft nodes before linearized reading' (duration: 458.79867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.285779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.826819Z","time spent":"458.952383ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:28:48.28578Z","caller":"traceutil/trace.go:171","msg":"trace[1827006161] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:631; }","duration":"458.927552ms","start":"2026-02-25T19:28:47.826833Z","end":"2026-02-25T19:28:48.28576Z","steps":["trace[1827006161] 'agreement among raft nodes before linearized reading' (duration: 458.76855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.285897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.826826Z","time spent":"459.060804ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-25T19:28:48.285733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.826807Z","time spent":"458.854842ms","remote":"127.0.0.1:38358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17689,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:48.288728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.822718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:48.288781Z","caller":"traceutil/trace.go:171","msg":"trace[700997352] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:637; }","duration":"461.89761ms","start":"2026-02-25T19:28:47.82687Z","end":"2026-02-25T19:28:48.288768Z","steps":["trace[700997352] 'agreement among raft nodes before linearized reading' (duration: 461.781828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.288806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.826866Z","time spent":"461.935431ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:28:48.288857Z","caller":"traceutil/trace.go:171","msg":"trace[1527756948] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"261.672675ms","start":"2026-02-25T19:28:48.027135Z","end":"2026-02-25T19:28:48.288808Z","steps":["trace[1527756948] 'process raft request' (duration: 261.37379ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:48.289152Z","caller":"traceutil/trace.go:171","msg":"trace[1202412275] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"208.213603ms","start":"2026-02-25T19:28:48.080931Z","end":"2026-02-25T19:28:48.289145Z","steps":["trace[1202412275] 'process raft request' (duration: 207.597463ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:48.289286Z","caller":"traceutil/trace.go:171","msg":"trace[234637020] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"103.200231ms","start":"2026-02-25T19:28:48.18608Z","end":"2026-02-25T19:28:48.289281Z","steps":["trace[234637020] 'process raft request' (duration: 102.47665ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:48.289323Z","caller":"traceutil/trace.go:171","msg":"trace[2063281723] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"460.318595ms","start":"2026-02-25T19:28:47.828989Z","end":"2026-02-25T19:28:48.289308Z","steps":["trace[2063281723] 'process raft request' (duration: 459.352559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.289467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.828981Z","time spent":"460.407736ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T19:28:48.289471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.096147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:48.289624Z","caller":"traceutil/trace.go:171","msg":"trace[1910470025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:637; }","duration":"461.26377ms","start":"2026-02-25T19:28:47.828354Z","end":"2026-02-25T19:28:48.289618Z","steps":["trace[1910470025] 'agreement among raft nodes before linearized reading' (duration: 461.103607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.289643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.828347Z","time spent":"461.28955ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:28:48.289669Z","caller":"traceutil/trace.go:171","msg":"trace[1036543280] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"457.323346ms","start":"2026-02-25T19:28:47.832334Z","end":"2026-02-25T19:28:48.289658Z","steps":["trace[1036543280] 'process raft request' (duration: 456.111187ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.289674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.731163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:48.289729Z","caller":"traceutil/trace.go:171","msg":"trace[982935211] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"455.855493ms","start":"2026-02-25T19:28:47.833865Z","end":"2026-02-25T19:28:48.28972Z","steps":["trace[982935211] 'process raft request' (duration: 454.616674ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:48.289731Z","caller":"traceutil/trace.go:171","msg":"trace[1247425647] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:0; response_revision:637; }","duration":"462.827464ms","start":"2026-02-25T19:28:47.826888Z","end":"2026-02-25T19:28:48.289716Z","steps":["trace[1247425647] 'agreement among raft nodes before linearized reading' (duration: 462.722143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:48.289778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.833858Z","time spent":"455.890234ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:48.289722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.832327Z","time spent":"457.368017ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T19:28:48.289772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:47.826875Z","time spent":"462.888395ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:28:50.641212Z","caller":"traceutil/trace.go:171","msg":"trace[88775413] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"455.140907ms","start":"2026-02-25T19:28:50.18604Z","end":"2026-02-25T19:28:50.641181Z","steps":["trace[88775413] 'process raft request' (duration: 454.758351ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.641456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:50.186013Z","time spent":"455.285649ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:28:50.782686Z","caller":"traceutil/trace.go:171","msg":"trace[2145385565] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:710; }","duration":"488.758405ms","start":"2026-02-25T19:28:50.293899Z","end":"2026-02-25T19:28:50.782658Z","steps":["trace[2145385565] 'read index received' (duration: 346.915755ms)","trace[2145385565] 'applied index is now lower than readState.Index' (duration: 141.84127ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:28:50.78284Z","caller":"traceutil/trace.go:171","msg":"trace[1428417840] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"529.942822ms","start":"2026-02-25T19:28:50.252859Z","end":"2026-02-25T19:28:50.782802Z","steps":["trace[1428417840] 'process raft request' (duration: 529.462025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.783133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:50.252834Z","time spent":"530.146625ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:28:50.783238Z","caller":"traceutil/trace.go:171","msg":"trace[2134990607] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"593.547853ms","start":"2026-02-25T19:28:50.189665Z","end":"2026-02-25T19:28:50.783213Z","steps":["trace[2134990607] 'process raft request' (duration: 590.315282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.783303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.380845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T19:28:50.783352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:50.189645Z","time spent":"593.652834ms","remote":"127.0.0.1:38216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T19:28:50.783359Z","caller":"traceutil/trace.go:171","msg":"trace[146066808] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"489.488276ms","start":"2026-02-25T19:28:50.293858Z","end":"2026-02-25T19:28:50.783346Z","steps":["trace[146066808] 'agreement among raft nodes before linearized reading' (duration: 489.161981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.783404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:50.293839Z","time spent":"489.556058ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T19:28:50.793851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.594556ms","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-25T19:28:50.793951Z","caller":"traceutil/trace.go:171","msg":"trace[774599812] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"167.735797ms","start":"2026-02-25T19:28:50.626196Z","end":"2026-02-25T19:28:50.793932Z","steps":["trace[774599812] 'agreement among raft nodes before linearized reading' (duration: 167.571675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.794449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.843195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-csp8k\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-02-25T19:28:50.794517Z","caller":"traceutil/trace.go:171","msg":"trace[447773800] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-csp8k; range_end:; response_count:1; response_revision:682; }","duration":"259.941188ms","start":"2026-02-25T19:28:50.534567Z","end":"2026-02-25T19:28:50.794509Z","steps":["trace[447773800] 'agreement among raft nodes before linearized reading' (duration: 259.817316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.795054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.280789ms","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":"info","ts":"2026-02-25T19:28:50.795133Z","caller":"traceutil/trace.go:171","msg":"trace[557011964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"352.388841ms","start":"2026-02-25T19:28:50.442733Z","end":"2026-02-25T19:28:50.795122Z","steps":["trace[557011964] 'agreement among raft nodes before linearized reading' (duration: 350.915868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:50.795188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:28:50.442719Z","time spent":"352.460112ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4105,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-02-25T19:28:58.344925Z","caller":"traceutil/trace.go:171","msg":"trace[385779980] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"126.478216ms","start":"2026-02-25T19:28:58.218424Z","end":"2026-02-25T19:28:58.344902Z","steps":["trace[385779980] 'process raft request' (duration: 126.210132ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:28:58.474094Z","caller":"traceutil/trace.go:171","msg":"trace[739832002] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"113.054686ms","start":"2026-02-25T19:28:58.36102Z","end":"2026-02-25T19:28:58.474075Z","steps":["trace[739832002] 'process raft request' (duration: 111.342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:28:59.209935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.661666ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-25T19:28:59.210049Z","caller":"traceutil/trace.go:171","msg":"trace[1387495275] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"172.401541ms","start":"2026-02-25T19:28:59.03763Z","end":"2026-02-25T19:28:59.210031Z","steps":["trace[1387495275] 'read index received' (duration: 68.613953ms)","trace[1387495275] 'applied index is now lower than readState.Index' (duration: 103.785888ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:28:59.210191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.554813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:28:59.210254Z","caller":"traceutil/trace.go:171","msg":"trace[188162885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"172.642484ms","start":"2026-02-25T19:28:59.037594Z","end":"2026-02-25T19:28:59.210236Z","steps":["trace[188162885] 'agreement among raft nodes before linearized reading' (duration: 172.552123ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:05.743042Z","caller":"traceutil/trace.go:171","msg":"trace[1508563613] linearizableReadLoop","detail":"{readStateIndex:846; appliedIndex:845; }","duration":"306.748567ms","start":"2026-02-25T19:29:05.436271Z","end":"2026-02-25T19:29:05.74302Z","steps":["trace[1508563613] 'read index received' (duration: 306.520614ms)","trace[1508563613] 'applied index is now lower than readState.Index' (duration: 226.913µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:05.743147Z","caller":"traceutil/trace.go:171","msg":"trace[1350043783] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"350.626051ms","start":"2026-02-25T19:29:05.392489Z","end":"2026-02-25T19:29:05.743115Z","steps":["trace[1350043783] 'process raft request' (duration: 350.402357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:05.743221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.92856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:29:05.743264Z","caller":"traceutil/trace.go:171","msg":"trace[977485472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:810; }","duration":"307.024152ms","start":"2026-02-25T19:29:05.436228Z","end":"2026-02-25T19:29:05.743252Z","steps":["trace[977485472] 'agreement among raft nodes before linearized reading' (duration: 306.88298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:05.743305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:05.436208Z","time spent":"307.089252ms","remote":"127.0.0.1:38452","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-25T19:29:05.74333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:29:05.39247Z","time spent":"350.765523ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:29:46.211932Z","caller":"traceutil/trace.go:171","msg":"trace[2046491059] linearizableReadLoop","detail":"{readStateIndex:973; appliedIndex:972; }","duration":"110.635052ms","start":"2026-02-25T19:29:46.101276Z","end":"2026-02-25T19:29:46.211911Z","steps":["trace[2046491059] 'read index received' (duration: 110.48279ms)","trace[2046491059] 'applied index is now lower than readState.Index' (duration: 151.492µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:46.212004Z","caller":"traceutil/trace.go:171","msg":"trace[165847215] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"186.35743ms","start":"2026-02-25T19:29:46.025632Z","end":"2026-02-25T19:29:46.21199Z","steps":["trace[165847215] 'process raft request' (duration: 186.153168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:46.212094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.805014ms","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-25T19:29:46.212118Z","caller":"traceutil/trace.go:171","msg":"trace[1111175269] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:928; }","duration":"110.839615ms","start":"2026-02-25T19:29:46.10127Z","end":"2026-02-25T19:29:46.21211Z","steps":["trace[1111175269] 'agreement among raft nodes before linearized reading' (duration: 110.721603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:47.510883Z","caller":"traceutil/trace.go:171","msg":"trace[1452134436] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"100.953511ms","start":"2026-02-25T19:29:47.409908Z","end":"2026-02-25T19:29:47.510862Z","steps":["trace[1452134436] 'process raft request' (duration: 100.804499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:55.684228Z","caller":"traceutil/trace.go:171","msg":"trace[1047911826] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"122.314533ms","start":"2026-02-25T19:29:55.561887Z","end":"2026-02-25T19:29:55.684202Z","steps":["trace[1047911826] 'read index received' (duration: 115.307231ms)","trace[1047911826] 'applied index is now lower than readState.Index' (duration: 7.005922ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:29:55.684294Z","caller":"traceutil/trace.go:171","msg":"trace[1589270515] transaction","detail":"{read_only:false; number_of_response:1; response_revision:972; }","duration":"184.82381ms","start":"2026-02-25T19:29:55.499422Z","end":"2026-02-25T19:29:55.684246Z","steps":["trace[1589270515] 'process raft request' (duration: 177.790687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:55.684571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.670877ms","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-25T19:29:55.684617Z","caller":"traceutil/trace.go:171","msg":"trace[980919928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:972; }","duration":"122.769028ms","start":"2026-02-25T19:29:55.561837Z","end":"2026-02-25T19:29:55.684606Z","steps":["trace[980919928] 'agreement among raft nodes before linearized reading' (duration: 122.465544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:29:58.895919Z","caller":"traceutil/trace.go:171","msg":"trace[1003935177] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"108.116244ms","start":"2026-02-25T19:29:58.787778Z","end":"2026-02-25T19:29:58.895894Z","steps":["trace[1003935177] 'process raft request' (duration: 107.942272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:29:59.101383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.396222ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:29:59.101516Z","caller":"traceutil/trace.go:171","msg":"trace[1997038082] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:994; }","duration":"142.582304ms","start":"2026-02-25T19:29:58.958911Z","end":"2026-02-25T19:29:59.101494Z","steps":["trace[1997038082] 'range keys from in-memory index tree' (duration: 142.372981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:08.036446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.979291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T19:30:08.03657Z","caller":"traceutil/trace.go:171","msg":"trace[433284323] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1074; }","duration":"204.764957ms","start":"2026-02-25T19:30:07.831793Z","end":"2026-02-25T19:30:08.036558Z","steps":["trace[433284323] 'read index received' (duration: 89.594153ms)","trace[433284323] 'applied index is now lower than readState.Index' (duration: 115.170074ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:08.036647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.850017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:08.036669Z","caller":"traceutil/trace.go:171","msg":"trace[1934190251] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1025; }","duration":"204.894138ms","start":"2026-02-25T19:30:07.831769Z","end":"2026-02-25T19:30:08.036663Z","steps":["trace[1934190251] 'agreement among raft nodes before linearized reading' (duration: 204.852927ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:08.036723Z","caller":"traceutil/trace.go:171","msg":"trace[153942051] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"214.091933ms","start":"2026-02-25T19:30:07.822604Z","end":"2026-02-25T19:30:08.036696Z","steps":["trace[153942051] 'process raft request' (duration: 98.794988ms)","trace[153942051] 'compare' (duration: 114.87626ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:08.0368Z","caller":"traceutil/trace.go:171","msg":"trace[1191201338] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"212.718537ms","start":"2026-02-25T19:30:07.82406Z","end":"2026-02-25T19:30:08.036778Z","steps":["trace[1191201338] 'process raft request' (duration: 212.461994ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:09.171911Z","caller":"traceutil/trace.go:171","msg":"trace[577093176] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"791.909283ms","start":"2026-02-25T19:30:08.379973Z","end":"2026-02-25T19:30:09.171882Z","steps":["trace[577093176] 'process raft request' (duration: 791.715761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:09.172092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:08.379958Z","time spent":"792.043674ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:09.303143Z","caller":"traceutil/trace.go:171","msg":"trace[800996747] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"920.668696ms","start":"2026-02-25T19:30:08.382432Z","end":"2026-02-25T19:30:09.303101Z","steps":["trace[800996747] 'process raft request' (duration: 857.64645ms)","trace[800996747] 'compare' (duration: 62.820643ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:09.303364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:08.38241Z","time spent":"920.862707ms","remote":"127.0.0.1:38452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T19:30:09.35728Z","caller":"traceutil/trace.go:171","msg":"trace[636701727] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"326.294487ms","start":"2026-02-25T19:30:09.030964Z","end":"2026-02-25T19:30:09.357258Z","steps":["trace[636701727] 'read index received' (duration: 140.737579ms)","trace[636701727] 'applied index is now lower than readState.Index' (duration: 185.555858ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:09.357584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.459018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:09.357633Z","caller":"traceutil/trace.go:171","msg":"trace[1925861529] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"326.672241ms","start":"2026-02-25T19:30:09.030948Z","end":"2026-02-25T19:30:09.35762Z","steps":["trace[1925861529] 'agreement among raft nodes before linearized reading' (duration: 326.425858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:09.357675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:09.030872Z","time spent":"326.793812ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:30:09.357615Z","caller":"traceutil/trace.go:171","msg":"trace[1296824662] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"219.249931ms","start":"2026-02-25T19:30:09.13818Z","end":"2026-02-25T19:30:09.35743Z","steps":["trace[1296824662] 'process raft request' (duration: 218.961967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:33.415248Z","caller":"traceutil/trace.go:171","msg":"trace[578781936] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"103.841822ms","start":"2026-02-25T19:30:33.311381Z","end":"2026-02-25T19:30:33.415223Z","steps":["trace[578781936] 'process raft request' (duration: 103.62908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:34.422088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.570596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T19:30:34.422277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.82731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-02-25T19:30:34.422373Z","caller":"traceutil/trace.go:171","msg":"trace[607410858] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:1102; }","duration":"384.945771ms","start":"2026-02-25T19:30:34.03741Z","end":"2026-02-25T19:30:34.422356Z","steps":["trace[607410858] 'range keys from in-memory index tree' (duration: 384.696728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:34.422156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.436893ms","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-25T19:30:34.422414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:34.037402Z","time spent":"385.003712ms","remote":"127.0.0.1:38250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1486,"request content":"key:\"/registry/configmaps/local-path-storage/local-path-config\" "} {"level":"warn","ts":"2026-02-25T19:30:34.422328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.927735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T19:30:34.422565Z","caller":"traceutil/trace.go:171","msg":"trace[872569598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"391.192659ms","start":"2026-02-25T19:30:34.031326Z","end":"2026-02-25T19:30:34.422518Z","steps":["trace[872569598] 'range keys from in-memory index tree' (duration: 390.795424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:34.422637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:34.031312Z","time spent":"391.30603ms","remote":"127.0.0.1:50446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T19:30:34.422433Z","caller":"traceutil/trace.go:171","msg":"trace[1480946466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"227.748366ms","start":"2026-02-25T19:30:34.194672Z","end":"2026-02-25T19:30:34.42242Z","steps":["trace[1480946466] 'range keys from in-memory index tree' (duration: 227.278021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:34.422276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.431599ms","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-25T19:30:34.422746Z","caller":"traceutil/trace.go:171","msg":"trace[1129174093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"248.899294ms","start":"2026-02-25T19:30:34.173808Z","end":"2026-02-25T19:30:34.422707Z","steps":["trace[1129174093] 'range keys from in-memory index tree' (duration: 248.322777ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:34.422376Z","caller":"traceutil/trace.go:171","msg":"trace[966278940] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1102; }","duration":"357.90842ms","start":"2026-02-25T19:30:34.064446Z","end":"2026-02-25T19:30:34.422355Z","steps":["trace[966278940] 'range keys from in-memory index tree' (duration: 357.403944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:34.422897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T19:30:34.064429Z","time spent":"358.456826ms","remote":"127.0.0.1:38366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" "} {"level":"info","ts":"2026-02-25T19:30:34.580447Z","caller":"traceutil/trace.go:171","msg":"trace[676837213] linearizableReadLoop","detail":"{readStateIndex:1161; appliedIndex:1160; }","duration":"152.0573ms","start":"2026-02-25T19:30:34.428366Z","end":"2026-02-25T19:30:34.580423Z","steps":["trace[676837213] 'read index received' (duration: 48.339841ms)","trace[676837213] 'applied index is now lower than readState.Index' (duration: 103.716079ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:34.580578Z","caller":"traceutil/trace.go:171","msg":"trace[679786298] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"153.147771ms","start":"2026-02-25T19:30:34.427413Z","end":"2026-02-25T19:30:34.580561Z","steps":["trace[679786298] 'process raft request' (duration: 140.689147ms)","trace[679786298] 'compare' (duration: 12.07433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T19:30:34.580725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.340922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pod-garbage-collector\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T19:30:34.580809Z","caller":"traceutil/trace.go:171","msg":"trace[490430774] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pod-garbage-collector; range_end:; response_count:1; response_revision:1104; }","duration":"152.461364ms","start":"2026-02-25T19:30:34.42832Z","end":"2026-02-25T19:30:34.580782Z","steps":["trace[490430774] 'agreement among raft nodes before linearized reading' (duration: 152.296223ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T19:30:35.543924Z","caller":"traceutil/trace.go:171","msg":"trace[1808804996] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"119.176023ms","start":"2026-02-25T19:30:35.424724Z","end":"2026-02-25T19:30:35.5439Z","steps":["trace[1808804996] 'read index received' (duration: 119.068372ms)","trace[1808804996] 'applied index is now lower than readState.Index' (duration: 106.621µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T19:30:35.544147Z","caller":"traceutil/trace.go:171","msg":"trace[583489720] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"122.787552ms","start":"2026-02-25T19:30:35.421321Z","end":"2026-02-25T19:30:35.544108Z","steps":["trace[583489720] 'process raft request' (duration: 122.450869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:35.544194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.979841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T19:30:35.544261Z","caller":"traceutil/trace.go:171","msg":"trace[515384583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"119.071612ms","start":"2026-02-25T19:30:35.425172Z","end":"2026-02-25T19:30:35.544244Z","steps":["trace[515384583] 'agreement among raft nodes before linearized reading' (duration: 118.91476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T19:30:35.544579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.85179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-25T19:30:35.54461Z","caller":"traceutil/trace.go:171","msg":"trace[1921840473] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:1; response_revision:1105; }","duration":"119.921041ms","start":"2026-02-25T19:30:35.424682Z","end":"2026-02-25T19:30:35.544603Z","steps":["trace[1921840473] 'agreement among raft nodes before linearized reading' (duration: 119.302184ms)"],"step_count":1}