{"level":"warn","ts":"2026-06-02T06:56:07.925201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:56:07.925336Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.142: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.142:2380","--initial-cluster=instance=https://199.19.213.142:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.142:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.142: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-06-02T06:56:07.925425Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-02T06:56:07.925436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-06-02T06:56:07.925515Z","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-06-02T06:56:07.926271Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"]} {"level":"info","ts":"2026-06-02T06:56:07.926344Z","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.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.142: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-06-02T06:56:07.931074Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.519595ms"} {"level":"info","ts":"2026-06-02T06:56:07.938219Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"54fca804e66e40f4","cluster-id":"374317481b3c77b9"} {"level":"info","ts":"2026-06-02T06:56:07.938334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=()"} {"level":"info","ts":"2026-06-02T06:56:07.938383Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 0"} {"level":"info","ts":"2026-06-02T06:56:07.938455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 54fca804e66e40f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-02T06:56:07.938486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became follower at term 1"} {"level":"info","ts":"2026-06-02T06:56:07.93856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"warn","ts":"2026-06-02T06:56:07.947809Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-02T06:56:07.950223Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-02T06:56:07.951245Z","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-06-02T06:56:07.953561Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"54fca804e66e40f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-02T06:56:07.953733Z","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-06-02T06:56:07.953851Z","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-06-02T06:56:07.954064Z","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-06-02T06:56:07.953804Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"54fca804e66e40f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-02T06:56:07.956158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 switched to configuration voters=(6123954332316352756)"} {"level":"info","ts":"2026-06-02T06:56:07.956443Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","added-peer-id":"54fca804e66e40f4","added-peer-peer-urls":["https://199.19.213.142:2380"]} {"level":"info","ts":"2026-06-02T06:56:07.959219Z","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-06-02T06:56:07.959582Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-06-02T06:56:07.959635Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.142:2380"} {"level":"info","ts":"2026-06-02T06:56:07.961327Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"54fca804e66e40f4","initial-advertise-peer-urls":["https://199.19.213.142:2380"],"listen-peer-urls":["https://199.19.213.142:2380"],"advertise-client-urls":["https://199.19.213.142:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.142:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-02T06:56:07.961414Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-02T06:56:08.839403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 is starting a new election at term 1"} {"level":"info","ts":"2026-06-02T06:56:08.839458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-02T06:56:08.839474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgPreVoteResp from 54fca804e66e40f4 at term 1"} {"level":"info","ts":"2026-06-02T06:56:08.839489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became candidate at term 2"} {"level":"info","ts":"2026-06-02T06:56:08.839497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 received MsgVoteResp from 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-06-02T06:56:08.839509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"54fca804e66e40f4 became leader at term 2"} {"level":"info","ts":"2026-06-02T06:56:08.839522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 54fca804e66e40f4 elected leader 54fca804e66e40f4 at term 2"} {"level":"info","ts":"2026-06-02T06:56:08.840476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"54fca804e66e40f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.142:2379]}","request-path":"/0/members/54fca804e66e40f4/attributes","cluster-id":"374317481b3c77b9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-02T06:56:08.840521Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:56:08.840985Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:56:08.841276Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-02T06:56:08.843964Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-02T06:56:08.844245Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.142:2379"} {"level":"info","ts":"2026-06-02T06:56:08.852643Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-02T06:56:08.860101Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-02T06:56:08.861832Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"374317481b3c77b9","local-member-id":"54fca804e66e40f4","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:56:08.861932Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:56:08.86196Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-02T06:56:13.348418Z","caller":"traceutil/trace.go:171","msg":"trace[491106385] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"118.141591ms","start":"2026-06-02T06:56:13.230251Z","end":"2026-06-02T06:56:13.348392Z","steps":["trace[491106385] 'process raft request' (duration: 54.544486ms)","trace[491106385] 'compare' (duration: 63.337539ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:13.348465Z","caller":"traceutil/trace.go:171","msg":"trace[1621485517] linearizableReadLoop","detail":"{readStateIndex:245; appliedIndex:244; }","duration":"112.98548ms","start":"2026-06-02T06:56:13.235443Z","end":"2026-06-02T06:56:13.348428Z","steps":["trace[1621485517] 'read index received' (duration: 49.293973ms)","trace[1621485517] 'applied index is now lower than readState.Index' (duration: 63.686287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:13.348657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.198165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:13.348711Z","caller":"traceutil/trace.go:171","msg":"trace[100382966] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:240; }","duration":"113.307708ms","start":"2026-06-02T06:56:13.235393Z","end":"2026-06-02T06:56:13.348701Z","steps":["trace[100382966] 'agreement among raft nodes before linearized reading' (duration: 113.150164ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:18.365129Z","caller":"traceutil/trace.go:171","msg":"trace[819212680] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"128.694809ms","start":"2026-06-02T06:56:18.236411Z","end":"2026-06-02T06:56:18.365105Z","steps":["trace[819212680] 'process raft request' (duration: 52.370521ms)","trace[819212680] 'compare' (duration: 75.9789ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:40.742336Z","caller":"traceutil/trace.go:171","msg":"trace[247053769] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"439.770682ms","start":"2026-06-02T06:56:40.302536Z","end":"2026-06-02T06:56:40.742307Z","steps":["trace[247053769] 'read index received' (duration: 439.510356ms)","trace[247053769] 'applied index is now lower than readState.Index' (duration: 259.206µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:40.742414Z","caller":"traceutil/trace.go:171","msg":"trace[321793004] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"459.589716ms","start":"2026-06-02T06:56:40.282795Z","end":"2026-06-02T06:56:40.742385Z","steps":["trace[321793004] 'process raft request' (duration: 459.35688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:40.74253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.981877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-06-02T06:56:40.742581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.616176ms","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-06-02T06:56:40.742621Z","caller":"traceutil/trace.go:171","msg":"trace[1021485836] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:434; }","duration":"440.121611ms","start":"2026-06-02T06:56:40.302485Z","end":"2026-06-02T06:56:40.742607Z","steps":["trace[1021485836] 'agreement among raft nodes before linearized reading' (duration: 439.986937ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:40.742634Z","caller":"traceutil/trace.go:171","msg":"trace[132060348] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:434; }","duration":"261.696498ms","start":"2026-06-02T06:56:40.480921Z","end":"2026-06-02T06:56:40.742618Z","steps":["trace[132060348] 'agreement among raft nodes before linearized reading' (duration: 261.611006ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:40.742659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:40.302466Z","time spent":"440.183843ms","remote":"127.0.0.1:59226","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-06-02T06:56:40.743141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.317477ms","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-06-02T06:56:40.743847Z","caller":"traceutil/trace.go:171","msg":"trace[1855276603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:434; }","duration":"249.073447ms","start":"2026-06-02T06:56:40.494753Z","end":"2026-06-02T06:56:40.743826Z","steps":["trace[1855276603] 'agreement among raft nodes before linearized reading' (duration: 247.813585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:40.743271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:40.282775Z","time spent":"459.75294ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:44.95251Z","caller":"traceutil/trace.go:171","msg":"trace[1228410496] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"110.257061ms","start":"2026-06-02T06:56:44.842222Z","end":"2026-06-02T06:56:44.952479Z","steps":["trace[1228410496] 'process raft request' (duration: 104.026803ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:44.952732Z","caller":"traceutil/trace.go:171","msg":"trace[1305278752] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"106.944387ms","start":"2026-06-02T06:56:44.84575Z","end":"2026-06-02T06:56:44.952694Z","steps":["trace[1305278752] 'read index received' (duration: 100.522374ms)","trace[1305278752] 'applied index is now lower than readState.Index' (duration: 6.418303ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:44.952785Z","caller":"traceutil/trace.go:171","msg":"trace[1617792774] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"101.431606ms","start":"2026-06-02T06:56:44.851335Z","end":"2026-06-02T06:56:44.952767Z","steps":["trace[1617792774] 'process raft request' (duration: 101.078558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:44.953054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.288615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-06-02T06:56:44.953094Z","caller":"traceutil/trace.go:171","msg":"trace[1775870348] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:475; }","duration":"107.382928ms","start":"2026-06-02T06:56:44.845699Z","end":"2026-06-02T06:56:44.953082Z","steps":["trace[1775870348] 'agreement among raft nodes before linearized reading' (duration: 107.175573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:44.953095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.141145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mmg4d\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-06-02T06:56:44.953138Z","caller":"traceutil/trace.go:171","msg":"trace[1313951410] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-mmg4d; range_end:; response_count:1; response_revision:475; }","duration":"102.225326ms","start":"2026-06-02T06:56:44.8509Z","end":"2026-06-02T06:56:44.953125Z","steps":["trace[1313951410] 'agreement among raft nodes before linearized reading' (duration: 101.990371ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:47.024632Z","caller":"traceutil/trace.go:171","msg":"trace[1258468868] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"607.234246ms","start":"2026-06-02T06:56:46.417256Z","end":"2026-06-02T06:56:47.02449Z","steps":["trace[1258468868] 'process raft request' (duration: 607.112293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.024752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.417239Z","time spent":"607.454673ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.030569Z","caller":"traceutil/trace.go:171","msg":"trace[1247952735] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"595.316454ms","start":"2026-06-02T06:56:46.435227Z","end":"2026-06-02T06:56:47.030544Z","steps":["trace[1247952735] 'process raft request' (duration: 594.579445ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:47.03072Z","caller":"traceutil/trace.go:171","msg":"trace[718727416] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"575.187443ms","start":"2026-06-02T06:56:46.455523Z","end":"2026-06-02T06:56:47.03071Z","steps":["trace[718727416] 'process raft request' (duration: 574.353601ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:47.030746Z","caller":"traceutil/trace.go:171","msg":"trace[767919592] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"608.526358ms","start":"2026-06-02T06:56:46.422194Z","end":"2026-06-02T06:56:47.030721Z","steps":["trace[767919592] 'process raft request' (duration: 607.541463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.030864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.422186Z","time spent":"608.619572ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:56:47.030871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.455507Z","time spent":"575.238814ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.030954Z","caller":"traceutil/trace.go:171","msg":"trace[1723129303] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"593.527488ms","start":"2026-06-02T06:56:46.43741Z","end":"2026-06-02T06:56:47.030938Z","steps":["trace[1723129303] 'process raft request' (duration: 592.416369ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:47.03096Z","caller":"traceutil/trace.go:171","msg":"trace[1934488973] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"588.999902ms","start":"2026-06-02T06:56:46.441953Z","end":"2026-06-02T06:56:47.030953Z","steps":["trace[1934488973] 'process raft request' (duration: 587.895484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.031002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.441922Z","time spent":"589.063714ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:56:47.031011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.437405Z","time spent":"593.57817ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.031065Z","caller":"traceutil/trace.go:171","msg":"trace[162323420] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"597.987462ms","start":"2026-06-02T06:56:46.433069Z","end":"2026-06-02T06:56:47.031057Z","steps":["trace[162323420] 'process raft request' (duration: 596.691268ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.0311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.433054Z","time spent":"598.028634ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.031163Z","caller":"traceutil/trace.go:171","msg":"trace[978804604] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"544.653376ms","start":"2026-06-02T06:56:46.486504Z","end":"2026-06-02T06:56:47.031158Z","steps":["trace[978804604] 'process raft request' (duration: 543.489007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.031192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.486479Z","time spent":"544.700207ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:56:47.031045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.435221Z","time spent":"595.416126ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.031179Z","caller":"traceutil/trace.go:171","msg":"trace[164168254] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"609.684139ms","start":"2026-06-02T06:56:46.421473Z","end":"2026-06-02T06:56:47.031157Z","steps":["trace[164168254] 'process raft request' (duration: 608.178601ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:47.031239Z","caller":"traceutil/trace.go:171","msg":"trace[1137239055] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"571.142769ms","start":"2026-06-02T06:56:46.460091Z","end":"2026-06-02T06:56:47.031234Z","steps":["trace[1137239055] 'process raft request' (duration: 569.856107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.031266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.460068Z","time spent":"571.187631ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:56:47.031268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.421449Z","time spent":"609.778791ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.031366Z","caller":"traceutil/trace.go:171","msg":"trace[1367786149] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"449.412126ms","start":"2026-06-02T06:56:46.581943Z","end":"2026-06-02T06:56:47.031355Z","steps":["trace[1367786149] 'process raft request' (duration: 448.098993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.03142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.581919Z","time spent":"449.475428ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.031433Z","caller":"traceutil/trace.go:171","msg":"trace[521299957] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"598.277789ms","start":"2026-06-02T06:56:46.433147Z","end":"2026-06-02T06:56:47.031424Z","steps":["trace[521299957] 'process raft request' (duration: 596.637387ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.031477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:46.433143Z","time spent":"598.31492ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:47.042707Z","caller":"traceutil/trace.go:171","msg":"trace[2113995249] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:500; }","duration":"276.926517ms","start":"2026-06-02T06:56:46.765762Z","end":"2026-06-02T06:56:47.042689Z","steps":["trace[2113995249] 'read index received' (duration: 258.801636ms)","trace[2113995249] 'applied index is now lower than readState.Index' (duration: 18.12323ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:47.042964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.196443ms","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-06-02T06:56:47.043038Z","caller":"traceutil/trace.go:171","msg":"trace[1290329766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"277.276495ms","start":"2026-06-02T06:56:46.765715Z","end":"2026-06-02T06:56:47.042992Z","steps":["trace[1290329766] 'agreement among raft nodes before linearized reading' (duration: 277.05341ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.04448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.996967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:47.044524Z","caller":"traceutil/trace.go:171","msg":"trace[1556181164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"127.074719ms","start":"2026-06-02T06:56:46.917439Z","end":"2026-06-02T06:56:47.044513Z","steps":["trace[1556181164] 'agreement among raft nodes before linearized reading' (duration: 127.008288ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.044945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.927058ms","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-06-02T06:56:47.044979Z","caller":"traceutil/trace.go:171","msg":"trace[503549209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"269.99811ms","start":"2026-06-02T06:56:46.774974Z","end":"2026-06-02T06:56:47.044972Z","steps":["trace[503549209] 'agreement among raft nodes before linearized reading' (duration: 269.417185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.04512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.586587ms","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-06-02T06:56:47.045144Z","caller":"traceutil/trace.go:171","msg":"trace[1189193942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"265.66052ms","start":"2026-06-02T06:56:46.779476Z","end":"2026-06-02T06:56:47.045136Z","steps":["trace[1189193942] 'agreement among raft nodes before linearized reading' (duration: 265.581248ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:47.04524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.462689ms","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-06-02T06:56:47.045262Z","caller":"traceutil/trace.go:171","msg":"trace[897442593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"266.504881ms","start":"2026-06-02T06:56:46.778751Z","end":"2026-06-02T06:56:47.045256Z","steps":["trace[897442593] 'agreement among raft nodes before linearized reading' (duration: 266.46589ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:53.254008Z","caller":"traceutil/trace.go:171","msg":"trace[1891560598] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"335.548034ms","start":"2026-06-02T06:56:52.918433Z","end":"2026-06-02T06:56:53.253981Z","steps":["trace[1891560598] 'read index received' (duration: 333.39625ms)","trace[1891560598] 'applied index is now lower than readState.Index' (duration: 2.150654ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:53.254414Z","caller":"traceutil/trace.go:171","msg":"trace[812122415] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"360.601931ms","start":"2026-06-02T06:56:52.893451Z","end":"2026-06-02T06:56:53.254053Z","steps":["trace[812122415] 'process raft request' (duration: 359.085253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:53.254767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:52.89341Z","time spent":"361.252948ms","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:56:53.2551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.604351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:53.255288Z","caller":"traceutil/trace.go:171","msg":"trace[1374280544] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"336.847338ms","start":"2026-06-02T06:56:52.918375Z","end":"2026-06-02T06:56:53.255222Z","steps":["trace[1374280544] 'agreement among raft nodes before linearized reading' (duration: 335.834242ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:53.255345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.32333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-06-02T06:56:53.255389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:52.918352Z","time spent":"336.989632ms","remote":"127.0.0.1:58960","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-06-02T06:56:53.255417Z","caller":"traceutil/trace.go:171","msg":"trace[2076964818] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"176.429062ms","start":"2026-06-02T06:56:53.078964Z","end":"2026-06-02T06:56:53.255393Z","steps":["trace[2076964818] 'agreement among raft nodes before linearized reading' (duration: 176.211097ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:53.255471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.808507ms","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-06-02T06:56:53.255536Z","caller":"traceutil/trace.go:171","msg":"trace[1602727677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"175.870678ms","start":"2026-06-02T06:56:53.079634Z","end":"2026-06-02T06:56:53.255504Z","steps":["trace[1602727677] 'agreement among raft nodes before linearized reading' (duration: 175.710094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:53.255984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.645508ms","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-06-02T06:56:53.256063Z","caller":"traceutil/trace.go:171","msg":"trace[2129811327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"176.73454ms","start":"2026-06-02T06:56:53.079291Z","end":"2026-06-02T06:56:53.256025Z","steps":["trace[2129811327] 'agreement among raft nodes before linearized reading' (duration: 176.443503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:53.256098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.077843ms","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-06-02T06:56:53.256147Z","caller":"traceutil/trace.go:171","msg":"trace[416724433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"174.158995ms","start":"2026-06-02T06:56:53.081964Z","end":"2026-06-02T06:56:53.256123Z","steps":["trace[416724433] 'agreement among raft nodes before linearized reading' (duration: 173.950719ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:56:53.36205Z","caller":"traceutil/trace.go:171","msg":"trace[971457485] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"100.42853ms","start":"2026-06-02T06:56:53.261588Z","end":"2026-06-02T06:56:53.362017Z","steps":["trace[971457485] 'process raft request' (duration: 94.329961ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:54.339516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.620033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:56:54.339923Z","caller":"traceutil/trace.go:171","msg":"trace[1401036785] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.077945728s","start":"2026-06-02T06:56:53.261953Z","end":"2026-06-02T06:56:54.339899Z","steps":["trace[1401036785] 'process raft request' (duration: 321.63197ms)","trace[1401036785] 'compare' (duration: 755.437178ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:54.340085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:53.261906Z","time spent":"1.078117922s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:54.340127Z","caller":"traceutil/trace.go:171","msg":"trace[406163555] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"918.632457ms","start":"2026-06-02T06:56:53.421477Z","end":"2026-06-02T06:56:54.340109Z","steps":["trace[406163555] 'read index received' (duration: 162.043393ms)","trace[406163555] 'applied index is now lower than readState.Index' (duration: 756.588174ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:54.34015Z","caller":"traceutil/trace.go:171","msg":"trace[887464486] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.075073445s","start":"2026-06-02T06:56:53.265053Z","end":"2026-06-02T06:56:54.340126Z","steps":["trace[887464486] 'process raft request' (duration: 1.07484746s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:54.340255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:53.265011Z","time spent":"1.075188117s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:54.340314Z","caller":"traceutil/trace.go:171","msg":"trace[1243285733] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.020463368s","start":"2026-06-02T06:56:53.319845Z","end":"2026-06-02T06:56:54.340308Z","steps":["trace[1243285733] 'process raft request' (duration: 1.020179091s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:54.340344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:53.319825Z","time spent":"1.020502929s","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-02T06:56:54.340318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.836203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kgj8k\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-02T06:56:54.340374Z","caller":"traceutil/trace.go:171","msg":"trace[1858615080] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kgj8k; range_end:; response_count:1; response_revision:594; }","duration":"918.917284ms","start":"2026-06-02T06:56:53.421445Z","end":"2026-06-02T06:56:54.340362Z","steps":["trace[1858615080] 'agreement among raft nodes before linearized reading' (duration: 918.74253ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:54.340408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:53.421429Z","time spent":"918.971226ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-kgj8k\" "} {"level":"info","ts":"2026-06-02T06:56:54.340385Z","caller":"traceutil/trace.go:171","msg":"trace[1900200595] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.076453059s","start":"2026-06-02T06:56:53.263864Z","end":"2026-06-02T06:56:54.340317Z","steps":["trace[1900200595] 'process raft request' (duration: 1.075946648s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:54.340687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:53.263833Z","time spent":"1.076680173s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:54.564735Z","caller":"traceutil/trace.go:171","msg":"trace[294229175] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"200.392647ms","start":"2026-06-02T06:56:54.364313Z","end":"2026-06-02T06:56:54.564705Z","steps":["trace[294229175] 'process raft request' (duration: 122.465041ms)","trace[294229175] 'compare' (duration: 77.769212ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:55.419951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910270729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:56:55.921004Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910270729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:56:56.421452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910270729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:56:56.919226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00031647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T06:56:56.919295Z","caller":"traceutil/trace.go:171","msg":"trace[1735323] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000434932s","start":"2026-06-02T06:56:54.918844Z","end":"2026-06-02T06:56:56.919279Z","steps":["trace[1735323] 'agreement among raft nodes before linearized reading' (duration: 2.00035061s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.919338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:54.918823Z","time spent":"2.000507154s","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 06:56:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T06:56:56.922387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910270729,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:56:56.979268Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.208528955s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:56:56.980586Z","caller":"traceutil/trace.go:171","msg":"trace[2137904224] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"2.211026553s","start":"2026-06-02T06:56:54.769504Z","end":"2026-06-02T06:56:56.980531Z","steps":["trace[2137904224] 'process raft request' (duration: 2.210281925s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.980733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:54.769479Z","time spent":"2.211163116s","remote":"127.0.0.1:59082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:56:56.981086Z","caller":"traceutil/trace.go:171","msg":"trace[1318752769] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"2.061465823s","start":"2026-06-02T06:56:54.918895Z","end":"2026-06-02T06:56:56.980361Z","steps":["trace[1318752769] 'read index received' (duration: 2.060605623s)","trace[1318752769] 'applied index is now lower than readState.Index' (duration: 858.96µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:56:56.981553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.607038877s","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-06-02T06:56:56.981605Z","caller":"traceutil/trace.go:171","msg":"trace[1200726693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:597; }","duration":"1.60715003s","start":"2026-06-02T06:56:55.37444Z","end":"2026-06-02T06:56:56.98159Z","steps":["trace[1200726693] 'agreement among raft nodes before linearized reading' (duration: 1.606868454s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.981653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:55.374426Z","time spent":"1.607217962s","remote":"127.0.0.1:59226","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-06-02T06:56:56.982253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.21432ms","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-06-02T06:56:56.982296Z","caller":"traceutil/trace.go:171","msg":"trace[489871705] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:597; }","duration":"636.379804ms","start":"2026-06-02T06:56:56.345903Z","end":"2026-06-02T06:56:56.982282Z","steps":["trace[489871705] 'agreement among raft nodes before linearized reading' (duration: 636.277462ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.982328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:56.345895Z","time spent":"636.425975ms","remote":"127.0.0.1:59226","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-06-02T06:56:56.982521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.478719ms","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-06-02T06:56:56.982638Z","caller":"traceutil/trace.go:171","msg":"trace[2082374434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:597; }","duration":"637.607851ms","start":"2026-06-02T06:56:56.345016Z","end":"2026-06-02T06:56:56.982624Z","steps":["trace[2082374434] 'agreement among raft nodes before linearized reading' (duration: 637.426607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.982674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:56.345011Z","time spent":"637.655962ms","remote":"127.0.0.1:59226","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-06-02T06:56:56.983034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.063962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T06:56:56.98309Z","caller":"traceutil/trace.go:171","msg":"trace[1384542242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:597; }","duration":"638.159664ms","start":"2026-06-02T06:56:56.344915Z","end":"2026-06-02T06:56:56.983074Z","steps":["trace[1384542242] 'agreement among raft nodes before linearized reading' (duration: 637.966349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:56.983128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:56.344886Z","time spent":"638.236056ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-02T06:56:59.014163Z","caller":"traceutil/trace.go:171","msg":"trace[102528144] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"322.265085ms","start":"2026-06-02T06:56:58.691875Z","end":"2026-06-02T06:56:59.01414Z","steps":["trace[102528144] 'process raft request' (duration: 322.152882ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:59.014303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:56:58.691854Z","time spent":"322.381118ms","remote":"127.0.0.1:59014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-02T06:56:59.019607Z","caller":"traceutil/trace.go:171","msg":"trace[1018097887] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"101.36894ms","start":"2026-06-02T06:56:58.918224Z","end":"2026-06-02T06:56:59.019593Z","steps":["trace[1018097887] 'read index received' (duration: 95.878045ms)","trace[1018097887] 'applied index is now lower than readState.Index' (duration: 5.490155ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:56:59.019711Z","caller":"traceutil/trace.go:171","msg":"trace[1689111810] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"127.944025ms","start":"2026-06-02T06:56:58.891739Z","end":"2026-06-02T06:56:59.019683Z","steps":["trace[1689111810] 'process raft request' (duration: 127.773821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:56:59.019886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.632747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:56:59.02002Z","caller":"traceutil/trace.go:171","msg":"trace[1212194127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"101.73521ms","start":"2026-06-02T06:56:58.918194Z","end":"2026-06-02T06:56:59.01993Z","steps":["trace[1212194127] 'agreement among raft nodes before linearized reading' (duration: 101.498104ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:08.474533Z","caller":"traceutil/trace.go:171","msg":"trace[648635014] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"124.59976ms","start":"2026-06-02T06:57:08.349912Z","end":"2026-06-02T06:57:08.474512Z","steps":["trace[648635014] 'process raft request' (duration: 124.480687ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:08.796005Z","caller":"traceutil/trace.go:171","msg":"trace[674497190] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"133.804059ms","start":"2026-06-02T06:57:08.662165Z","end":"2026-06-02T06:57:08.795969Z","steps":["trace[674497190] 'process raft request' (duration: 53.941059ms)","trace[674497190] 'compare' (duration: 79.568763ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:57:08.799824Z","caller":"traceutil/trace.go:171","msg":"trace[1866924116] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"134.979367ms","start":"2026-06-02T06:57:08.664819Z","end":"2026-06-02T06:57:08.799799Z","steps":["trace[1866924116] 'process raft request' (duration: 134.921346ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:08.799851Z","caller":"traceutil/trace.go:171","msg":"trace[2020425701] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"137.34786ms","start":"2026-06-02T06:57:08.662468Z","end":"2026-06-02T06:57:08.799816Z","steps":["trace[2020425701] 'process raft request' (duration: 137.135385ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:10.762245Z","caller":"traceutil/trace.go:171","msg":"trace[1270920482] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"279.964211ms","start":"2026-06-02T06:57:10.48223Z","end":"2026-06-02T06:57:10.762194Z","steps":["trace[1270920482] 'process raft request' (duration: 279.827398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:13.658298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910271050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:57:14.159361Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910271050,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:57:14.575499Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.458129402s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:57:14.575907Z","caller":"traceutil/trace.go:171","msg":"trace[484558908] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"1.458574084s","start":"2026-06-02T06:57:13.117307Z","end":"2026-06-02T06:57:14.575881Z","steps":["trace[484558908] 'process raft request' (duration: 1.458387189s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.576074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.117294Z","time spent":"1.458680116s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:14.933933Z","caller":"traceutil/trace.go:171","msg":"trace[344070102] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:790; }","duration":"1.7756208s","start":"2026-06-02T06:57:13.158226Z","end":"2026-06-02T06:57:14.933847Z","steps":["trace[344070102] 'read index received' (duration: 1.417480737s)","trace[344070102] 'applied index is now lower than readState.Index' (duration: 358.138853ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:57:14.934026Z","caller":"traceutil/trace.go:171","msg":"trace[742618714] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.386680214s","start":"2026-06-02T06:57:13.547332Z","end":"2026-06-02T06:57:14.934012Z","steps":["trace[742618714] 'process raft request' (duration: 1.386362707s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.934167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.775930196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-02T06:57:14.934292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.54731Z","time spent":"1.386780407s","remote":"127.0.0.1:59206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:14.934314Z","caller":"traceutil/trace.go:171","msg":"trace[1174456579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"1.776133151s","start":"2026-06-02T06:57:13.158173Z","end":"2026-06-02T06:57:14.934306Z","steps":["trace[1174456579] 'agreement among raft nodes before linearized reading' (duration: 1.775865385s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.934347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.158151Z","time spent":"1.776181562s","remote":"127.0.0.1:59226","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-06-02T06:57:14.939847Z","caller":"traceutil/trace.go:171","msg":"trace[809156269] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"359.430672ms","start":"2026-06-02T06:57:14.580397Z","end":"2026-06-02T06:57:14.939827Z","steps":["trace[809156269] 'process raft request' (duration: 359.324ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.940074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:14.580284Z","time spent":"359.627246ms","remote":"127.0.0.1:59146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:57:14.940353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.723477471s","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-06-02T06:57:14.940404Z","caller":"traceutil/trace.go:171","msg":"trace[2135825789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"1.723569824s","start":"2026-06-02T06:57:13.21682Z","end":"2026-06-02T06:57:14.94039Z","steps":["trace[2135825789] 'agreement among raft nodes before linearized reading' (duration: 1.723428351s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.940413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.466399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-02T06:57:14.940431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.216801Z","time spent":"1.723624304s","remote":"127.0.0.1:59226","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":"info","ts":"2026-06-02T06:57:14.940454Z","caller":"traceutil/trace.go:171","msg":"trace[441405311] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"164.541491ms","start":"2026-06-02T06:57:14.7759Z","end":"2026-06-02T06:57:14.940441Z","steps":["trace[441405311] 'agreement among raft nodes before linearized reading' (duration: 164.412158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.940432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022430252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:57:14.940499Z","caller":"traceutil/trace.go:171","msg":"trace[1153745386] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"1.022537725s","start":"2026-06-02T06:57:13.917949Z","end":"2026-06-02T06:57:14.940486Z","steps":["trace[1153745386] 'agreement among raft nodes before linearized reading' (duration: 1.022432382s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.94053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.917929Z","time spent":"1.022593867s","remote":"127.0.0.1:58960","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-06-02T06:57:14.940549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.697774295s","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-06-02T06:57:14.94063Z","caller":"traceutil/trace.go:171","msg":"trace[2013033167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"1.697877978s","start":"2026-06-02T06:57:13.242736Z","end":"2026-06-02T06:57:14.940614Z","steps":["trace[2013033167] 'agreement among raft nodes before linearized reading' (duration: 1.697652573s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:14.940657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:13.242724Z","time spent":"1.697926109s","remote":"127.0.0.1:59226","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-06-02T06:57:14.940949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.284809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:57:14.941009Z","caller":"traceutil/trace.go:171","msg":"trace[319712840] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:766; }","duration":"298.376111ms","start":"2026-06-02T06:57:14.642613Z","end":"2026-06-02T06:57:14.940989Z","steps":["trace[319712840] 'agreement among raft nodes before linearized reading' (duration: 298.291139ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:49.044991Z","caller":"traceutil/trace.go:171","msg":"trace[1512284752] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"310.92211ms","start":"2026-06-02T06:57:48.734044Z","end":"2026-06-02T06:57:49.044966Z","steps":["trace[1512284752] 'process raft request' (duration: 310.749685ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:49.045344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:48.734032Z","time spent":"311.240008ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:49.981656Z","caller":"traceutil/trace.go:171","msg":"trace[375191515] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"257.277674ms","start":"2026-06-02T06:57:49.724342Z","end":"2026-06-02T06:57:49.981619Z","steps":["trace[375191515] 'process raft request' (duration: 253.754133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.646904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.544427ms","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-06-02T06:57:51.646995Z","caller":"traceutil/trace.go:171","msg":"trace[588432002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"597.67412ms","start":"2026-06-02T06:57:51.049301Z","end":"2026-06-02T06:57:51.646975Z","steps":["trace[588432002] 'range keys from in-memory index tree' (duration: 597.366802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.646993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.515943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:57:51.647034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.049285Z","time spent":"597.739272ms","remote":"127.0.0.1:59226","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-06-02T06:57:51.647038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.598409ms","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-06-02T06:57:51.647062Z","caller":"traceutil/trace.go:171","msg":"trace[960452450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"727.617476ms","start":"2026-06-02T06:57:50.919425Z","end":"2026-06-02T06:57:51.647043Z","steps":["trace[960452450] 'range keys from in-memory index tree' (duration: 727.455091ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:51.647077Z","caller":"traceutil/trace.go:171","msg":"trace[1842697666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"463.66837ms","start":"2026-06-02T06:57:51.183398Z","end":"2026-06-02T06:57:51.647067Z","steps":["trace[1842697666] 'range keys from in-memory index tree' (duration: 463.404774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.647103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:50.919412Z","time spent":"727.679447ms","remote":"127.0.0.1:58960","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-06-02T06:57:51.647168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.066561ms","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-06-02T06:57:51.647201Z","caller":"traceutil/trace.go:171","msg":"trace[2062011377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:979; }","duration":"464.119472ms","start":"2026-06-02T06:57:51.183071Z","end":"2026-06-02T06:57:51.64719Z","steps":["trace[2062011377] 'range keys from in-memory index tree' (duration: 463.984759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.647197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.963818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-06-02T06:57:51.647222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.183058Z","time spent":"464.159203ms","remote":"127.0.0.1:59226","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-06-02T06:57:51.647231Z","caller":"traceutil/trace.go:171","msg":"trace[90754206] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:979; }","duration":"464.02258ms","start":"2026-06-02T06:57:51.183198Z","end":"2026-06-02T06:57:51.647221Z","steps":["trace[90754206] 'range keys from in-memory index tree' (duration: 463.887916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.647103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.183379Z","time spent":"463.716802ms","remote":"127.0.0.1:59226","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-06-02T06:57:51.647259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.183195Z","time spent":"464.056981ms","remote":"127.0.0.1:59226","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-06-02T06:57:51.647342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.308182ms","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-06-02T06:57:51.647361Z","caller":"traceutil/trace.go:171","msg":"trace[1211674222] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:979; }","duration":"502.327283ms","start":"2026-06-02T06:57:51.145028Z","end":"2026-06-02T06:57:51.647355Z","steps":["trace[1211674222] 'range keys from in-memory index tree' (duration: 502.217819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:51.647377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.144984Z","time spent":"502.390014ms","remote":"127.0.0.1:59226","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":"info","ts":"2026-06-02T06:57:52.123619Z","caller":"traceutil/trace.go:171","msg":"trace[177545035] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"472.96925ms","start":"2026-06-02T06:57:51.650631Z","end":"2026-06-02T06:57:52.1236Z","steps":["trace[177545035] 'process raft request' (duration: 472.839786ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:52.123764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.650613Z","time spent":"473.064882ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:52.129599Z","caller":"traceutil/trace.go:171","msg":"trace[2045048600] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"478.82264ms","start":"2026-06-02T06:57:51.650757Z","end":"2026-06-02T06:57:52.12958Z","steps":["trace[2045048600] 'process raft request' (duration: 478.661356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:52.129776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.650749Z","time spent":"478.931442ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:52.130144Z","caller":"traceutil/trace.go:171","msg":"trace[1874855662] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"479.304122ms","start":"2026-06-02T06:57:51.650805Z","end":"2026-06-02T06:57:52.130109Z","steps":["trace[1874855662] 'process raft request' (duration: 478.651566ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:52.130403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.650798Z","time spent":"479.494447ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:57:52.130503Z","caller":"traceutil/trace.go:171","msg":"trace[968914818] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"121.487648ms","start":"2026-06-02T06:57:52.009002Z","end":"2026-06-02T06:57:52.13049Z","steps":["trace[968914818] 'process raft request' (duration: 121.348294ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:52.130535Z","caller":"traceutil/trace.go:171","msg":"trace[1612466253] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"121.519138ms","start":"2026-06-02T06:57:52.009003Z","end":"2026-06-02T06:57:52.130522Z","steps":["trace[1612466253] 'process raft request' (duration: 121.429786ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:52.130465Z","caller":"traceutil/trace.go:171","msg":"trace[1182528415] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"479.568009ms","start":"2026-06-02T06:57:51.650884Z","end":"2026-06-02T06:57:52.130452Z","steps":["trace[1182528415] 'process raft request' (duration: 479.184989ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:52.130476Z","caller":"traceutil/trace.go:171","msg":"trace[601551610] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1016; }","duration":"359.131657ms","start":"2026-06-02T06:57:51.771333Z","end":"2026-06-02T06:57:52.130464Z","steps":["trace[601551610] 'read index received' (duration: 352.183559ms)","trace[601551610] 'applied index is now lower than readState.Index' (duration: 6.947368ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:57:52.130721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.322328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T06:57:52.130753Z","caller":"traceutil/trace.go:171","msg":"trace[240345247] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:986; }","duration":"330.38427ms","start":"2026-06-02T06:57:51.800359Z","end":"2026-06-02T06:57:52.130744Z","steps":["trace[240345247] 'agreement among raft nodes before linearized reading' (duration: 330.326649ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:52.130772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.800345Z","time spent":"330.420681ms","remote":"127.0.0.1:59370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:57:52.130727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.403594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T06:57:52.130842Z","caller":"traceutil/trace.go:171","msg":"trace[1048896750] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:986; }","duration":"359.576888ms","start":"2026-06-02T06:57:51.771255Z","end":"2026-06-02T06:57:52.130832Z","steps":["trace[1048896750] 'agreement among raft nodes before linearized reading' (duration: 359.373493ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:57:52.130925Z","caller":"traceutil/trace.go:171","msg":"trace[1471502099] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"478.83861ms","start":"2026-06-02T06:57:51.650693Z","end":"2026-06-02T06:57:52.129532Z","steps":["trace[1471502099] 'process raft request' (duration: 478.535863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:57:52.130967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.650679Z","time spent":"480.264376ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:57:52.130984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.771236Z","time spent":"359.733833ms","remote":"127.0.0.1:59000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"warn","ts":"2026-06-02T06:57:52.131233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:57:51.650856Z","time spent":"479.739793ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:12.551631Z","caller":"traceutil/trace.go:171","msg":"trace[388241655] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"329.226822ms","start":"2026-06-02T06:58:12.222383Z","end":"2026-06-02T06:58:12.55161Z","steps":["trace[388241655] 'read index received' (duration: 329.071508ms)","trace[388241655] 'applied index is now lower than readState.Index' (duration: 152.754µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:12.551736Z","caller":"traceutil/trace.go:171","msg":"trace[1847164078] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"356.67214ms","start":"2026-06-02T06:58:12.195052Z","end":"2026-06-02T06:58:12.551724Z","steps":["trace[1847164078] 'process raft request' (duration: 356.452935ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:12.551816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.195018Z","time spent":"356.738751ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:12.551921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.210721ms","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-06-02T06:58:12.552Z","caller":"traceutil/trace.go:171","msg":"trace[616251619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"329.335765ms","start":"2026-06-02T06:58:12.222646Z","end":"2026-06-02T06:58:12.551982Z","steps":["trace[616251619] 'agreement among raft nodes before linearized reading' (duration: 329.113019ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:12.552048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.222635Z","time spent":"329.404746ms","remote":"127.0.0.1:59226","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-06-02T06:58:12.552051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.167789ms","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-06-02T06:58:12.552088Z","caller":"traceutil/trace.go:171","msg":"trace[350849993] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"284.233941ms","start":"2026-06-02T06:58:12.267846Z","end":"2026-06-02T06:58:12.55208Z","steps":["trace[350849993] 'agreement among raft nodes before linearized reading' (duration: 284.119618ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:12.552214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.349413ms","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-06-02T06:58:12.552306Z","caller":"traceutil/trace.go:171","msg":"trace[1016938683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1047; }","duration":"320.476927ms","start":"2026-06-02T06:58:12.231809Z","end":"2026-06-02T06:58:12.552286Z","steps":["trace[1016938683] 'agreement among raft nodes before linearized reading' (duration: 320.21801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:12.552351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.231797Z","time spent":"320.545178ms","remote":"127.0.0.1:59226","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-06-02T06:58:12.552359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.87714ms","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-06-02T06:58:12.552504Z","caller":"traceutil/trace.go:171","msg":"trace[1127772990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"330.136636ms","start":"2026-06-02T06:58:12.222344Z","end":"2026-06-02T06:58:12.552481Z","steps":["trace[1127772990] 'agreement among raft nodes before linearized reading' (duration: 329.606432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:12.55264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.222329Z","time spent":"330.2759ms","remote":"127.0.0.1:59226","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-06-02T06:58:12.553644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.72039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T06:58:12.553723Z","caller":"traceutil/trace.go:171","msg":"trace[1267482686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"283.821642ms","start":"2026-06-02T06:58:12.269879Z","end":"2026-06-02T06:58:12.5537Z","steps":["trace[1267482686] 'agreement among raft nodes before linearized reading' (duration: 282.070016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.635477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.906834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:58:13.635646Z","caller":"traceutil/trace.go:171","msg":"trace[1930760314] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.078566642s","start":"2026-06-02T06:58:12.556985Z","end":"2026-06-02T06:58:13.635552Z","steps":["trace[1930760314] 'process raft request' (duration: 580.498374ms)","trace[1930760314] 'compare' (duration: 497.793391ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:13.635703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.556967Z","time spent":"1.078712666s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:13.716626Z","caller":"traceutil/trace.go:171","msg":"trace[681389691] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"1.159459758s","start":"2026-06-02T06:58:12.557138Z","end":"2026-06-02T06:58:13.716597Z","steps":["trace[681389691] 'process raft request' (duration: 1.159202402s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.716795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.557114Z","time spent":"1.159587762s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:13.71698Z","caller":"traceutil/trace.go:171","msg":"trace[115200731] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"1.152146799s","start":"2026-06-02T06:58:12.564808Z","end":"2026-06-02T06:58:13.716955Z","steps":["trace[115200731] 'process raft request' (duration: 1.151722029s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.717137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.564794Z","time spent":"1.152254962s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:13.717621Z","caller":"traceutil/trace.go:171","msg":"trace[1786929374] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"1.129988979s","start":"2026-06-02T06:58:12.587613Z","end":"2026-06-02T06:58:13.717602Z","steps":["trace[1786929374] 'process raft request' (duration: 1.12923956s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.71773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.587596Z","time spent":"1.130080901s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:13.718213Z","caller":"traceutil/trace.go:171","msg":"trace[863865923] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.130513622s","start":"2026-06-02T06:58:12.587675Z","end":"2026-06-02T06:58:13.718189Z","steps":["trace[863865923] 'process raft request' (duration: 1.129815134s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.718304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.587671Z","time spent":"1.130589584s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:13.718583Z","caller":"traceutil/trace.go:171","msg":"trace[983530780] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"1.028288636s","start":"2026-06-02T06:58:12.690271Z","end":"2026-06-02T06:58:13.71856Z","steps":["trace[983530780] 'process raft request' (duration: 1.027612788s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:13.718566Z","caller":"traceutil/trace.go:171","msg":"trace[167539180] linearizableReadLoop","detail":"{readStateIndex:1095; appliedIndex:1089; }","duration":"749.622217ms","start":"2026-06-02T06:58:12.968886Z","end":"2026-06-02T06:58:13.718508Z","steps":["trace[167539180] 'read index received' (duration: 168.596419ms)","trace[167539180] 'applied index is now lower than readState.Index' (duration: 581.024478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:13.718865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.977436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-02T06:58:13.718925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.690244Z","time spent":"1.028397348s","remote":"127.0.0.1:59226","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-06-02T06:58:13.719138Z","caller":"traceutil/trace.go:171","msg":"trace[975600936] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1053; }","duration":"750.244603ms","start":"2026-06-02T06:58:12.968869Z","end":"2026-06-02T06:58:13.719113Z","steps":["trace[975600936] 'agreement among raft nodes before linearized reading' (duration: 749.939495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:13.719592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:12.968791Z","time spent":"750.735356ms","remote":"127.0.0.1:52856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":29,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-06-02T06:58:14.740941Z","caller":"traceutil/trace.go:171","msg":"trace[505408549] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"182.098317ms","start":"2026-06-02T06:58:14.558799Z","end":"2026-06-02T06:58:14.740898Z","steps":["trace[505408549] 'process raft request' (duration: 181.943844ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.133943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.147222ms","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-06-02T06:58:16.13403Z","caller":"traceutil/trace.go:171","msg":"trace[500265281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"495.289445ms","start":"2026-06-02T06:58:15.638724Z","end":"2026-06-02T06:58:16.134014Z","steps":["trace[500265281] 'range keys from in-memory index tree' (duration: 494.971048ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.638702Z","time spent":"495.360008ms","remote":"127.0.0.1:59226","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-06-02T06:58:16.134199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.215057893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:16.134234Z","caller":"traceutil/trace.go:171","msg":"trace[708454637] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"1.215130755s","start":"2026-06-02T06:58:14.919094Z","end":"2026-06-02T06:58:16.134224Z","steps":["trace[708454637] 'range keys from in-memory index tree' (duration: 1.214983381s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:14.919073Z","time spent":"1.215179505s","remote":"127.0.0.1:58960","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-06-02T06:58:16.134421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.194296ms","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-06-02T06:58:16.134463Z","caller":"traceutil/trace.go:171","msg":"trace[692860091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"411.267339ms","start":"2026-06-02T06:58:15.723183Z","end":"2026-06-02T06:58:16.134451Z","steps":["trace[692860091] 'range keys from in-memory index tree' (duration: 411.077233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.723169Z","time spent":"411.32037ms","remote":"127.0.0.1:59226","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-06-02T06:58:16.134648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.203086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-06-02T06:58:16.134672Z","caller":"traceutil/trace.go:171","msg":"trace[1276432517] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"411.252038ms","start":"2026-06-02T06:58:15.723413Z","end":"2026-06-02T06:58:16.134665Z","steps":["trace[1276432517] 'range keys from in-memory index tree' (duration: 411.118765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.723401Z","time spent":"411.282988ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-02T06:58:16.134752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.360002ms","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-06-02T06:58:16.134853Z","caller":"traceutil/trace.go:171","msg":"trace[1478097292] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1054; }","duration":"356.438464ms","start":"2026-06-02T06:58:15.778378Z","end":"2026-06-02T06:58:16.134817Z","steps":["trace[1478097292] 'range keys from in-memory index tree' (duration: 356.349872ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.108962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-02T06:58:16.134832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.832967ms","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-06-02T06:58:16.134907Z","caller":"traceutil/trace.go:171","msg":"trace[1901031885] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"390.294137ms","start":"2026-06-02T06:58:15.7446Z","end":"2026-06-02T06:58:16.134894Z","steps":["trace[1901031885] 'process raft request' (duration: 240.085772ms)","trace[1901031885] 'compare' (duration: 149.795534ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:16.134905Z","caller":"traceutil/trace.go:171","msg":"trace[1014653724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"396.925919ms","start":"2026-06-02T06:58:15.737962Z","end":"2026-06-02T06:58:16.134888Z","steps":["trace[1014653724] 'range keys from in-memory index tree' (duration: 396.702483ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.134952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.737954Z","time spent":"396.98564ms","remote":"127.0.0.1:59226","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-06-02T06:58:16.134962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.744557Z","time spent":"390.378899ms","remote":"127.0.0.1:59226","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-06-02T06:58:16.135145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.427648ms","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-06-02T06:58:16.135224Z","caller":"traceutil/trace.go:171","msg":"trace[399824194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"412.618623ms","start":"2026-06-02T06:58:15.72259Z","end":"2026-06-02T06:58:16.135208Z","steps":["trace[399824194] 'range keys from in-memory index tree' (duration: 411.32147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.136265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:15.722572Z","time spent":"413.66162ms","remote":"127.0.0.1:59226","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-06-02T06:58:16.274016Z","caller":"traceutil/trace.go:171","msg":"trace[861209625] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"134.452558ms","start":"2026-06-02T06:58:16.13954Z","end":"2026-06-02T06:58:16.273992Z","steps":["trace[861209625] 'process raft request' (duration: 134.288354ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:16.293704Z","caller":"traceutil/trace.go:171","msg":"trace[282383193] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"154.110465ms","start":"2026-06-02T06:58:16.139577Z","end":"2026-06-02T06:58:16.293688Z","steps":["trace[282383193] 'process raft request' (duration: 153.989972ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:16.293975Z","caller":"traceutil/trace.go:171","msg":"trace[518759733] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"154.374112ms","start":"2026-06-02T06:58:16.139592Z","end":"2026-06-02T06:58:16.293966Z","steps":["trace[518759733] 'process raft request' (duration: 154.069745ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:16.294738Z","caller":"traceutil/trace.go:171","msg":"trace[1579281539] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1097; }","duration":"150.559023ms","start":"2026-06-02T06:58:16.14416Z","end":"2026-06-02T06:58:16.294719Z","steps":["trace[1579281539] 'read index received' (duration: 129.717216ms)","trace[1579281539] 'applied index is now lower than readState.Index' (duration: 20.840727ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:16.294838Z","caller":"traceutil/trace.go:171","msg":"trace[449301175] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"154.072564ms","start":"2026-06-02T06:58:16.140751Z","end":"2026-06-02T06:58:16.294824Z","steps":["trace[449301175] 'process raft request' (duration: 153.765946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:16.294849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.673217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:16.294895Z","caller":"traceutil/trace.go:171","msg":"trace[106153115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"150.76254ms","start":"2026-06-02T06:58:16.144119Z","end":"2026-06-02T06:58:16.294881Z","steps":["trace[106153115] 'agreement among raft nodes before linearized reading' (duration: 150.672787ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:16.295041Z","caller":"traceutil/trace.go:171","msg":"trace[1578833132] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"154.162336ms","start":"2026-06-02T06:58:16.140869Z","end":"2026-06-02T06:58:16.295031Z","steps":["trace[1578833132] 'process raft request' (duration: 153.798917ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:17.66077Z","caller":"traceutil/trace.go:171","msg":"trace[1788808578] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"204.075414ms","start":"2026-06-02T06:58:17.456659Z","end":"2026-06-02T06:58:17.660734Z","steps":["trace[1788808578] 'read index received' (duration: 120.356535ms)","trace[1788808578] 'applied index is now lower than readState.Index' (duration: 83.716559ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:17.660939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.247019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:17.660985Z","caller":"traceutil/trace.go:171","msg":"trace[765825717] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1061; }","duration":"204.34755ms","start":"2026-06-02T06:58:17.456624Z","end":"2026-06-02T06:58:17.660971Z","steps":["trace[765825717] 'agreement among raft nodes before linearized reading' (duration: 204.235588ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:18.797618Z","caller":"traceutil/trace.go:171","msg":"trace[1534619231] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"499.681399ms","start":"2026-06-02T06:58:18.297917Z","end":"2026-06-02T06:58:18.797598Z","steps":["trace[1534619231] 'read index received' (duration: 499.512255ms)","trace[1534619231] 'applied index is now lower than readState.Index' (duration: 168.144µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:18.797713Z","caller":"traceutil/trace.go:171","msg":"trace[1473754715] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"515.21746ms","start":"2026-06-02T06:58:18.282485Z","end":"2026-06-02T06:58:18.797702Z","steps":["trace[1473754715] 'process raft request' (duration: 514.946793ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.797807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.282467Z","time spent":"515.270251ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:18.798258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.309085ms","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-06-02T06:58:18.798341Z","caller":"traceutil/trace.go:171","msg":"trace[317353533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"500.445579ms","start":"2026-06-02T06:58:18.297876Z","end":"2026-06-02T06:58:18.798321Z","steps":["trace[317353533] 'agreement among raft nodes before linearized reading' (duration: 500.180812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.798392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.29786Z","time spent":"500.523581ms","remote":"127.0.0.1:59226","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-06-02T06:58:18.802247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.650291ms","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-06-02T06:58:18.802302Z","caller":"traceutil/trace.go:171","msg":"trace[1671843933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1063; }","duration":"503.720784ms","start":"2026-06-02T06:58:18.298569Z","end":"2026-06-02T06:58:18.80229Z","steps":["trace[1671843933] 'agreement among raft nodes before linearized reading' (duration: 503.58198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.802332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.298563Z","time spent":"503.762645ms","remote":"127.0.0.1:59226","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-06-02T06:58:18.8028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.783614ms","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-06-02T06:58:18.802842Z","caller":"traceutil/trace.go:171","msg":"trace[1205926493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1063; }","duration":"503.832296ms","start":"2026-06-02T06:58:18.298999Z","end":"2026-06-02T06:58:18.802831Z","steps":["trace[1205926493] 'agreement among raft nodes before linearized reading' (duration: 503.697952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.802866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.298994Z","time spent":"503.867376ms","remote":"127.0.0.1:59226","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-06-02T06:58:18.803111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.69782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:18.803171Z","caller":"traceutil/trace.go:171","msg":"trace[1121045251] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1063; }","duration":"342.795463ms","start":"2026-06-02T06:58:18.460359Z","end":"2026-06-02T06:58:18.803154Z","steps":["trace[1121045251] 'agreement among raft nodes before linearized reading' (duration: 342.720221ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.803234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.460338Z","time spent":"342.878685ms","remote":"127.0.0.1:59136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:58:18.803245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.268385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:58:18.803587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.558195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:58:18.80367Z","caller":"traceutil/trace.go:171","msg":"trace[842327368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1063; }","duration":"504.648487ms","start":"2026-06-02T06:58:18.29901Z","end":"2026-06-02T06:58:18.803659Z","steps":["trace[842327368] 'agreement among raft nodes before linearized reading' (duration: 504.185895ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:18.803783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:18.299008Z","time spent":"504.76376ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-02T06:58:18.803283Z","caller":"traceutil/trace.go:171","msg":"trace[1651964860] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1063; }","duration":"191.390538ms","start":"2026-06-02T06:58:18.611884Z","end":"2026-06-02T06:58:18.803274Z","steps":["trace[1651964860] 'agreement among raft nodes before linearized reading' (duration: 191.289625ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:33.026419Z","caller":"traceutil/trace.go:171","msg":"trace[1679815005] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"162.43367ms","start":"2026-06-02T06:58:32.863956Z","end":"2026-06-02T06:58:33.02639Z","steps":["trace[1679815005] 'read index received' (duration: 162.190124ms)","trace[1679815005] 'applied index is now lower than readState.Index' (duration: 242.356µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:33.026509Z","caller":"traceutil/trace.go:171","msg":"trace[1033393388] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"179.180732ms","start":"2026-06-02T06:58:32.847304Z","end":"2026-06-02T06:58:33.026485Z","steps":["trace[1033393388] 'process raft request' (duration: 178.911815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:33.026713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.744898ms","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-06-02T06:58:33.026763Z","caller":"traceutil/trace.go:171","msg":"trace[25468836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"162.845261ms","start":"2026-06-02T06:58:32.863903Z","end":"2026-06-02T06:58:33.026748Z","steps":["trace[25468836] 'agreement among raft nodes before linearized reading' (duration: 162.670196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:33.026841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.106042ms","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-06-02T06:58:33.026901Z","caller":"traceutil/trace.go:171","msg":"trace[1288129097] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"162.207254ms","start":"2026-06-02T06:58:32.86468Z","end":"2026-06-02T06:58:33.026887Z","steps":["trace[1288129097] 'agreement among raft nodes before linearized reading' (duration: 162.03839ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:33.02698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.134602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-02T06:58:33.027019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.763671ms","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-06-02T06:58:33.027023Z","caller":"traceutil/trace.go:171","msg":"trace[366116703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"131.201654ms","start":"2026-06-02T06:58:32.895808Z","end":"2026-06-02T06:58:33.02701Z","steps":["trace[366116703] 'agreement among raft nodes before linearized reading' (duration: 131.086422ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:33.027034Z","caller":"traceutil/trace.go:171","msg":"trace[1167803450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"133.803952ms","start":"2026-06-02T06:58:32.893226Z","end":"2026-06-02T06:58:33.02703Z","steps":["trace[1167803450] 'agreement among raft nodes before linearized reading' (duration: 133.752701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:33.027112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.239795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:58:33.027127Z","caller":"traceutil/trace.go:171","msg":"trace[1264704109] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"131.270096ms","start":"2026-06-02T06:58:32.895854Z","end":"2026-06-02T06:58:33.027124Z","steps":["trace[1264704109] 'agreement among raft nodes before linearized reading' (duration: 131.231875ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:35.538129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910272025,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:58:36.10486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.963759ms","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-06-02T06:58:36.104937Z","caller":"traceutil/trace.go:171","msg":"trace[998671215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1165; }","duration":"326.051151ms","start":"2026-06-02T06:58:35.778875Z","end":"2026-06-02T06:58:36.104926Z","steps":["trace[998671215] 'range keys from in-memory index tree' (duration: 325.951208ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.104964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182552993s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:36.105034Z","caller":"traceutil/trace.go:171","msg":"trace[1132796494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"1.182667406s","start":"2026-06-02T06:58:34.92235Z","end":"2026-06-02T06:58:36.105018Z","steps":["trace[1132796494] 'range keys from in-memory index tree' (duration: 1.182472931s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.105071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:34.922332Z","time spent":"1.182730477s","remote":"127.0.0.1:58960","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-06-02T06:58:36.105278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.600393ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:58:36.105317Z","caller":"traceutil/trace.go:171","msg":"trace[2022979278] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1213; }","duration":"1.067897925s","start":"2026-06-02T06:58:35.037413Z","end":"2026-06-02T06:58:36.105311Z","steps":["trace[2022979278] 'read index received' (duration: 842.927863ms)","trace[2022979278] 'applied index is now lower than readState.Index' (duration: 224.969422ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:36.10542Z","caller":"traceutil/trace.go:171","msg":"trace[637982864] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"1.072894784s","start":"2026-06-02T06:58:35.032519Z","end":"2026-06-02T06:58:36.105414Z","steps":["trace[637982864] 'process raft request' (duration: 848.118956ms)","trace[637982864] 'compare' (duration: 224.44945ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:36.105455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.032503Z","time spent":"1.072935966s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:36.105543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062036454s","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-06-02T06:58:36.105585Z","caller":"traceutil/trace.go:171","msg":"trace[752201938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1166; }","duration":"1.062098456s","start":"2026-06-02T06:58:35.043476Z","end":"2026-06-02T06:58:36.105574Z","steps":["trace[752201938] 'agreement among raft nodes before linearized reading' (duration: 1.061963663s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.105606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.04346Z","time spent":"1.062141467s","remote":"127.0.0.1:59226","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-06-02T06:58:36.105819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061659415s","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-06-02T06:58:36.105847Z","caller":"traceutil/trace.go:171","msg":"trace[685217073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.061726627s","start":"2026-06-02T06:58:35.044113Z","end":"2026-06-02T06:58:36.10584Z","steps":["trace[685217073] 'agreement among raft nodes before linearized reading' (duration: 1.061655155s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.105866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.044093Z","time spent":"1.061768047s","remote":"127.0.0.1:59226","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-06-02T06:58:36.106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062437195s","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-06-02T06:58:36.106021Z","caller":"traceutil/trace.go:171","msg":"trace[856102415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.062481475s","start":"2026-06-02T06:58:35.043533Z","end":"2026-06-02T06:58:36.106014Z","steps":["trace[856102415] 'agreement among raft nodes before linearized reading' (duration: 1.062417324s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.106047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.043515Z","time spent":"1.062525487s","remote":"127.0.0.1:59226","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-06-02T06:58:36.106084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057886948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:58:36.106183Z","caller":"traceutil/trace.go:171","msg":"trace[412226600] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1166; }","duration":"1.058258417s","start":"2026-06-02T06:58:35.047904Z","end":"2026-06-02T06:58:36.106163Z","steps":["trace[412226600] 'agreement among raft nodes before linearized reading' (duration: 1.057764405s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.106259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.047892Z","time spent":"1.05835196s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T06:58:36.106405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.068972943s","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-06-02T06:58:36.106521Z","caller":"traceutil/trace.go:171","msg":"trace[1207220871] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1166; }","duration":"1.069136337s","start":"2026-06-02T06:58:35.037364Z","end":"2026-06-02T06:58:36.106501Z","steps":["trace[1207220871] 'agreement among raft nodes before linearized reading' (duration: 1.06887567s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:36.106567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:35.037344Z","time spent":"1.069208969s","remote":"127.0.0.1:59226","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-06-02T06:58:36.616005Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910272029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:58:37.120642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910272029,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:58:37.231074Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.119302282s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:58:37.231642Z","caller":"traceutil/trace.go:171","msg":"trace[1194700461] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"1.12001328s","start":"2026-06-02T06:58:36.1116Z","end":"2026-06-02T06:58:37.231614Z","steps":["trace[1194700461] 'process raft request' (duration: 1.119734383s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.231797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.111579Z","time spent":"1.120130333s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.238246Z","caller":"traceutil/trace.go:171","msg":"trace[301378239] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"1.126536158s","start":"2026-06-02T06:58:36.11169Z","end":"2026-06-02T06:58:37.238226Z","steps":["trace[301378239] 'process raft request' (duration: 1.126455226s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.238361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.111683Z","time spent":"1.12662424s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.238383Z","caller":"traceutil/trace.go:171","msg":"trace[1587129730] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"1.1266098s","start":"2026-06-02T06:58:36.111763Z","end":"2026-06-02T06:58:37.238372Z","steps":["trace[1587129730] 'process raft request' (duration: 1.126419685s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.238435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.111756Z","time spent":"1.126652251s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.238441Z","caller":"traceutil/trace.go:171","msg":"trace[898299081] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"1.126788855s","start":"2026-06-02T06:58:36.111627Z","end":"2026-06-02T06:58:37.238416Z","steps":["trace[898299081] 'process raft request' (duration: 1.126427106s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.23857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.111605Z","time spent":"1.126895227s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.238723Z","caller":"traceutil/trace.go:171","msg":"trace[822752909] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"1.126807825s","start":"2026-06-02T06:58:36.111799Z","end":"2026-06-02T06:58:37.238607Z","steps":["trace[822752909] 'process raft request' (duration: 1.126532628s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.238811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.111779Z","time spent":"1.12697481s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.238841Z","caller":"traceutil/trace.go:171","msg":"trace[385968742] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"930.312497ms","start":"2026-06-02T06:58:36.308512Z","end":"2026-06-02T06:58:37.238825Z","steps":["trace[385968742] 'process raft request' (duration: 930.050151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.238939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.308491Z","time spent":"930.39645ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:37.247723Z","caller":"traceutil/trace.go:171","msg":"trace[990171799] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1214; }","duration":"1.132067071s","start":"2026-06-02T06:58:36.115634Z","end":"2026-06-02T06:58:37.247701Z","steps":["trace[990171799] 'read index received' (duration: 1.115798661s)","trace[990171799] 'applied index is now lower than readState.Index' (duration: 16.26637ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:37.247991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132348918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:58:37.248024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.680352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:37.248048Z","caller":"traceutil/trace.go:171","msg":"trace[1883825816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1172; }","duration":"1.132440471s","start":"2026-06-02T06:58:36.115594Z","end":"2026-06-02T06:58:37.248035Z","steps":["trace[1883825816] 'agreement among raft nodes before linearized reading' (duration: 1.132344468s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:37.248068Z","caller":"traceutil/trace.go:171","msg":"trace[1889956788] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1172; }","duration":"251.817766ms","start":"2026-06-02T06:58:36.996237Z","end":"2026-06-02T06:58:37.248055Z","steps":["trace[1889956788] 'agreement among raft nodes before linearized reading' (duration: 251.674453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:37.248077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:36.115584Z","time spent":"1.132488292s","remote":"127.0.0.1:58960","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-06-02T06:58:51.633949Z","caller":"traceutil/trace.go:171","msg":"trace[673746377] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"110.163391ms","start":"2026-06-02T06:58:51.523757Z","end":"2026-06-02T06:58:51.633921Z","steps":["trace[673746377] 'process raft request' (duration: 110.055828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:52.211743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:51.775792Z","time spent":"435.947874ms","remote":"127.0.0.1:59000","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-02T06:58:52.211823Z","caller":"traceutil/trace.go:171","msg":"trace[1992793434] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1285; }","duration":"330.627748ms","start":"2026-06-02T06:58:51.881149Z","end":"2026-06-02T06:58:52.211777Z","steps":["trace[1992793434] 'read index received' (duration: 330.604628ms)","trace[1992793434] 'applied index is now lower than readState.Index' (duration: 20.15µs)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:52.212011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.841753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tnx5k\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-06-02T06:58:52.212053Z","caller":"traceutil/trace.go:171","msg":"trace[1492218613] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tnx5k; range_end:; response_count:1; response_revision:1233; }","duration":"330.929676ms","start":"2026-06-02T06:58:51.881114Z","end":"2026-06-02T06:58:52.212044Z","steps":["trace[1492218613] 'agreement among raft nodes before linearized reading' (duration: 330.739481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:52.212093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:51.881097Z","time spent":"330.981857ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11083,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-tnx5k\" "} {"level":"warn","ts":"2026-06-02T06:58:53.049391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.25106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:58:53.049488Z","caller":"traceutil/trace.go:171","msg":"trace[83636922] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"837.580115ms","start":"2026-06-02T06:58:52.211892Z","end":"2026-06-02T06:58:53.049473Z","steps":["trace[83636922] 'read index received' (duration: 77.170611ms)","trace[83636922] 'applied index is now lower than readState.Index' (duration: 760.408424ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:53.049668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"985.161941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:53.049654Z","caller":"traceutil/trace.go:171","msg":"trace[1682164987] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"895.300013ms","start":"2026-06-02T06:58:52.154317Z","end":"2026-06-02T06:58:53.049617Z","steps":["trace[1682164987] 'process raft request' (duration: 134.748405ms)","trace[1682164987] 'compare' (duration: 759.667265ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:53.049704Z","caller":"traceutil/trace.go:171","msg":"trace[1580906506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"985.225933ms","start":"2026-06-02T06:58:52.064468Z","end":"2026-06-02T06:58:53.049694Z","steps":["trace[1580906506] 'agreement among raft nodes before linearized reading' (duration: 985.172082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:53.049736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:52.064452Z","time spent":"985.276804ms","remote":"127.0.0.1:58960","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-06-02T06:58:53.049821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:52.154289Z","time spent":"895.438607ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:58:53.225298Z","caller":"traceutil/trace.go:171","msg":"trace[170321776] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"1.012553189s","start":"2026-06-02T06:58:52.21272Z","end":"2026-06-02T06:58:53.225273Z","steps":["trace[170321776] 'process raft request' (duration: 1.001866353s)","trace[170321776] 'compare' (duration: 10.494961ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:53.225393Z","caller":"traceutil/trace.go:171","msg":"trace[243147178] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"1.000166649s","start":"2026-06-02T06:58:52.225201Z","end":"2026-06-02T06:58:53.225368Z","steps":["trace[243147178] 'process raft request' (duration: 999.990395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:53.225451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:52.212702Z","time spent":"1.012677381s","remote":"127.0.0.1:59000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:53.225517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:52.225182Z","time spent":"1.000269522s","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:53.225718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.724482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:53.225745Z","caller":"traceutil/trace.go:171","msg":"trace[1686924958] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1236; }","duration":"306.787903ms","start":"2026-06-02T06:58:52.918952Z","end":"2026-06-02T06:58:53.22574Z","steps":["trace[1686924958] 'agreement among raft nodes before linearized reading' (duration: 306.733132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:53.225762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:52.918932Z","time spent":"306.826374ms","remote":"127.0.0.1:58962","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-06-02T06:58:53.225307Z","caller":"traceutil/trace.go:171","msg":"trace[1369303909] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"175.776665ms","start":"2026-06-02T06:58:53.049512Z","end":"2026-06-02T06:58:53.225289Z","steps":["trace[1369303909] 'read index received' (duration: 165.1052ms)","trace[1369303909] 'applied index is now lower than readState.Index' (duration: 10.670795ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:58.659881Z","caller":"traceutil/trace.go:171","msg":"trace[1130862217] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"523.142565ms","start":"2026-06-02T06:58:58.136708Z","end":"2026-06-02T06:58:58.65985Z","steps":["trace[1130862217] 'read index received' (duration: 482.224379ms)","trace[1130862217] 'applied index is now lower than readState.Index' (duration: 40.917326ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:58:58.660051Z","caller":"traceutil/trace.go:171","msg":"trace[1271588891] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"599.71275ms","start":"2026-06-02T06:58:58.060331Z","end":"2026-06-02T06:58:58.660043Z","steps":["trace[1271588891] 'process raft request' (duration: 558.63196ms)","trace[1271588891] 'compare' (duration: 40.760322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:58:58.66012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:58.060321Z","time spent":"599.75091ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:58:58.660297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.891649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:58.660319Z","caller":"traceutil/trace.go:171","msg":"trace[2080184772] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1287; }","duration":"108.951571ms","start":"2026-06-02T06:58:58.551362Z","end":"2026-06-02T06:58:58.660314Z","steps":["trace[2080184772] 'agreement among raft nodes before linearized reading' (duration: 108.91145ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:58.660407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.707469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:58.660423Z","caller":"traceutil/trace.go:171","msg":"trace[408379580] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1287; }","duration":"523.75954ms","start":"2026-06-02T06:58:58.13666Z","end":"2026-06-02T06:58:58.660419Z","steps":["trace[408379580] 'agreement among raft nodes before linearized reading' (duration: 523.7347ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:58.660435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:58.136641Z","time spent":"523.791081ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-02T06:58:58.660636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.416891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-02T06:58:58.660655Z","caller":"traceutil/trace.go:171","msg":"trace[1933985309] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1287; }","duration":"240.467172ms","start":"2026-06-02T06:58:58.420183Z","end":"2026-06-02T06:58:58.66065Z","steps":["trace[1933985309] 'agreement among raft nodes before linearized reading' (duration: 240.381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:58.660776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.623447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:58.660794Z","caller":"traceutil/trace.go:171","msg":"trace[1604145165] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1287; }","duration":"285.675219ms","start":"2026-06-02T06:58:58.375115Z","end":"2026-06-02T06:58:58.66079Z","steps":["trace[1604145165] 'agreement among raft nodes before linearized reading' (duration: 285.643128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:58.660883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.321706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:58.661056Z","caller":"traceutil/trace.go:171","msg":"trace[914893298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"388.630994ms","start":"2026-06-02T06:58:58.272399Z","end":"2026-06-02T06:58:58.66103Z","steps":["trace[914893298] 'agreement among raft nodes before linearized reading' (duration: 388.006498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:58.661193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:58.272388Z","time spent":"388.790039ms","remote":"127.0.0.1:58960","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-06-02T06:58:59.986562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.586305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:58:59.986652Z","caller":"traceutil/trace.go:171","msg":"trace[1151287395] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1295; }","duration":"466.714919ms","start":"2026-06-02T06:58:59.519918Z","end":"2026-06-02T06:58:59.986633Z","steps":["trace[1151287395] 'count revisions from in-memory index tree' (duration: 466.518904ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:59.986701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.519903Z","time spent":"466.785661ms","remote":"127.0.0.1:49798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:58:59.986894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.731523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:58:59.986926Z","caller":"traceutil/trace.go:171","msg":"trace[667793273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"572.787145ms","start":"2026-06-02T06:58:59.414131Z","end":"2026-06-02T06:58:59.986918Z","steps":["trace[667793273] 'range keys from in-memory index tree' (duration: 572.554989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:59.986946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.41412Z","time spent":"572.820666ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-02T06:58:59.986986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.973775ms","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-06-02T06:58:59.987058Z","caller":"traceutil/trace.go:171","msg":"trace[338898924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"586.081497ms","start":"2026-06-02T06:58:59.400959Z","end":"2026-06-02T06:58:59.987041Z","steps":["trace[338898924] 'range keys from in-memory index tree' (duration: 585.823811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:59.987122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.40094Z","time spent":"586.156779ms","remote":"127.0.0.1:59226","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-06-02T06:58:59.987176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.388848ms","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-06-02T06:58:59.987199Z","caller":"traceutil/trace.go:171","msg":"trace[1518844754] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"583.429989ms","start":"2026-06-02T06:58:59.403762Z","end":"2026-06-02T06:58:59.987192Z","steps":["trace[1518844754] 'range keys from in-memory index tree' (duration: 583.324856ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:59.987196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.764079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-02T06:58:59.98722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.403751Z","time spent":"583.46168ms","remote":"127.0.0.1:59226","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-06-02T06:58:59.987133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.220391ms","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-06-02T06:58:59.987242Z","caller":"traceutil/trace.go:171","msg":"trace[336367541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1295; }","duration":"590.864251ms","start":"2026-06-02T06:58:59.396365Z","end":"2026-06-02T06:58:59.987229Z","steps":["trace[336367541] 'range keys from in-memory index tree' (duration: 590.641745ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:58:59.987331Z","caller":"traceutil/trace.go:171","msg":"trace[1013216443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"584.426535ms","start":"2026-06-02T06:58:59.402876Z","end":"2026-06-02T06:58:59.987303Z","steps":["trace[1013216443] 'range keys from in-memory index tree' (duration: 584.121217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:58:59.987361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.396346Z","time spent":"591.005905ms","remote":"127.0.0.1:59226","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-06-02T06:58:59.987389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.402863Z","time spent":"584.508788ms","remote":"127.0.0.1:59226","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-06-02T06:59:00.338827Z","caller":"traceutil/trace.go:171","msg":"trace[1397330395] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"346.326513ms","start":"2026-06-02T06:58:59.99248Z","end":"2026-06-02T06:59:00.338806Z","steps":["trace[1397330395] 'process raft request' (duration: 346.191219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:00.339019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.99246Z","time spent":"346.495587ms","remote":"127.0.0.1:59226","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-06-02T06:59:00.600189Z","caller":"traceutil/trace.go:171","msg":"trace[1013313805] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"607.633834ms","start":"2026-06-02T06:58:59.992521Z","end":"2026-06-02T06:59:00.600155Z","steps":["trace[1013313805] 'process raft request' (duration: 561.993096ms)","trace[1013313805] 'compare' (duration: 45.427532ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:59:00.600392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.992497Z","time spent":"607.793938ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:59:00.600774Z","caller":"traceutil/trace.go:171","msg":"trace[1697236089] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"607.052229ms","start":"2026-06-02T06:58:59.993692Z","end":"2026-06-02T06:59:00.600744Z","steps":["trace[1697236089] 'process raft request' (duration: 606.409382ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:00.60095Z","caller":"traceutil/trace.go:171","msg":"trace[1487497160] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1348; }","duration":"465.977109ms","start":"2026-06-02T06:59:00.134956Z","end":"2026-06-02T06:59:00.600933Z","steps":["trace[1487497160] 'read index received' (duration: 203.747055ms)","trace[1487497160] 'applied index is now lower than readState.Index' (duration: 262.229154ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:59:00.600973Z","caller":"traceutil/trace.go:171","msg":"trace[1853224527] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"607.111351ms","start":"2026-06-02T06:58:59.993834Z","end":"2026-06-02T06:59:00.600945Z","steps":["trace[1853224527] 'process raft request' (duration: 607.009018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:00.601038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.993683Z","time spent":"607.165641ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:59:00.601069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.993805Z","time spent":"607.210373ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:59:00.601308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.352409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T06:59:00.60135Z","caller":"traceutil/trace.go:171","msg":"trace[673593140] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1301; }","duration":"466.438431ms","start":"2026-06-02T06:59:00.134899Z","end":"2026-06-02T06:59:00.601338Z","steps":["trace[673593140] 'agreement among raft nodes before linearized reading' (duration: 466.101002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:00.601386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:00.134872Z","time spent":"466.506723ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-02T06:59:00.601368Z","caller":"traceutil/trace.go:171","msg":"trace[1498174726] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"601.558387ms","start":"2026-06-02T06:58:59.999767Z","end":"2026-06-02T06:59:00.601325Z","steps":["trace[1498174726] 'process raft request' (duration: 601.117966ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:00.601442Z","caller":"traceutil/trace.go:171","msg":"trace[277889084] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"607.686595ms","start":"2026-06-02T06:58:59.99374Z","end":"2026-06-02T06:59:00.601427Z","steps":["trace[277889084] 'process raft request' (duration: 606.995457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:00.601533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.993726Z","time spent":"607.765927ms","remote":"127.0.0.1:59226","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-06-02T06:59:00.601611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:58:59.999748Z","time spent":"601.779463ms","remote":"127.0.0.1:59148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:59:00.778381Z","caller":"traceutil/trace.go:171","msg":"trace[1030241540] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"170.725934ms","start":"2026-06-02T06:59:00.607619Z","end":"2026-06-02T06:59:00.778345Z","steps":["trace[1030241540] 'process raft request' (duration: 116.030243ms)","trace[1030241540] 'compare' (duration: 54.544727ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:59:00.835615Z","caller":"traceutil/trace.go:171","msg":"trace[699367201] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"226.441652ms","start":"2026-06-02T06:59:00.609139Z","end":"2026-06-02T06:59:00.835581Z","steps":["trace[699367201] 'process raft request' (duration: 226.121584ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:00.836224Z","caller":"traceutil/trace.go:171","msg":"trace[516301591] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"223.909666ms","start":"2026-06-02T06:59:00.612295Z","end":"2026-06-02T06:59:00.836205Z","steps":["trace[516301591] 'process raft request' (duration: 223.709831ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:00.836365Z","caller":"traceutil/trace.go:171","msg":"trace[1791398393] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"227.087488ms","start":"2026-06-02T06:59:00.609256Z","end":"2026-06-02T06:59:00.836343Z","steps":["trace[1791398393] 'process raft request' (duration: 226.164755ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:01.106018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.649093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:59:01.106121Z","caller":"traceutil/trace.go:171","msg":"trace[1306413451] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"265.053396ms","start":"2026-06-02T06:59:00.841049Z","end":"2026-06-02T06:59:01.106103Z","steps":["trace[1306413451] 'process raft request' (duration: 42.218239ms)","trace[1306413451] 'compare' (duration: 222.54534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:59:01.652185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.114271ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:59:01.652372Z","caller":"traceutil/trace.go:171","msg":"trace[1048626186] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"554.805861ms","start":"2026-06-02T06:59:01.09755Z","end":"2026-06-02T06:59:01.652356Z","steps":["trace[1048626186] 'process raft request' (duration: 554.725059ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:01.652444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:01.097525Z","time spent":"554.885963ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:59:01.652511Z","caller":"traceutil/trace.go:171","msg":"trace[168966669] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"803.871196ms","start":"2026-06-02T06:59:00.848497Z","end":"2026-06-02T06:59:01.652368Z","steps":["trace[168966669] 'process raft request' (duration: 372.487399ms)","trace[168966669] 'compare' (duration: 430.971587ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:59:01.652835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:00.848485Z","time spent":"804.233725ms","remote":"127.0.0.1:59384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:59:02.148673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.610033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:59:02.148762Z","caller":"traceutil/trace.go:171","msg":"trace[37455022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"229.754857ms","start":"2026-06-02T06:59:01.918988Z","end":"2026-06-02T06:59:02.148743Z","steps":["trace[37455022] 'range keys from in-memory index tree' (duration: 229.491098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:02.148871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.335891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.142\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-02T06:59:02.148945Z","caller":"traceutil/trace.go:171","msg":"trace[1203583862] range","detail":"{range_begin:/registry/masterleases/199.19.213.142; range_end:; response_count:1; response_revision:1309; }","duration":"373.443473ms","start":"2026-06-02T06:59:01.775483Z","end":"2026-06-02T06:59:02.148926Z","steps":["trace[1203583862] 'range keys from in-memory index tree' (duration: 373.181745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:02.148982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:01.775469Z","time spent":"373.503715ms","remote":"127.0.0.1:59000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.142\" "} {"level":"info","ts":"2026-06-02T06:59:02.361229Z","caller":"traceutil/trace.go:171","msg":"trace[1817054086] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"199.456305ms","start":"2026-06-02T06:59:02.16174Z","end":"2026-06-02T06:59:02.361197Z","steps":["trace[1817054086] 'process raft request' (duration: 199.177347ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:04.632521Z","caller":"traceutil/trace.go:171","msg":"trace[1369399451] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"191.433679ms","start":"2026-06-02T06:59:04.441037Z","end":"2026-06-02T06:59:04.632471Z","steps":["trace[1369399451] 'process raft request' (duration: 191.272805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:05.174464Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4680540215910272351,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-02T06:59:05.98712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.367115ms","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-06-02T06:59:05.987274Z","caller":"traceutil/trace.go:171","msg":"trace[1654958035] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1320; }","duration":"208.530269ms","start":"2026-06-02T06:59:05.77872Z","end":"2026-06-02T06:59:05.987251Z","steps":["trace[1654958035] 'range keys from in-memory index tree' (duration: 208.349304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:05.988056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.944302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T06:59:05.988231Z","caller":"traceutil/trace.go:171","msg":"trace[320735857] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.348926064s","start":"2026-06-02T06:59:04.639283Z","end":"2026-06-02T06:59:05.988209Z","steps":["trace[320735857] 'process raft request' (duration: 770.736816ms)","trace[320735857] 'compare' (duration: 577.042679ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:59:05.988357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.639263Z","time spent":"1.349058037s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:59:06.919714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000804694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-06-02T06:59:06.919813Z","caller":"traceutil/trace.go:171","msg":"trace[440797521] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000961478s","start":"2026-06-02T06:59:04.918829Z","end":"2026-06-02T06:59:06.91979Z","steps":["trace[440797521] 'agreement among raft nodes before linearized reading' (duration: 2.000844365s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:06.919874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.918807Z","time spent":"2.00105267s","remote":"127.0.0.1:58960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/06/02 06:59:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-06-02T06:59:07.160863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.750760021s","expected-duration":"1s"} {"level":"info","ts":"2026-06-02T06:59:07.16163Z","caller":"traceutil/trace.go:171","msg":"trace[957058091] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"2.520153612s","start":"2026-06-02T06:59:04.641453Z","end":"2026-06-02T06:59:07.161607Z","steps":["trace[957058091] 'process raft request' (duration: 2.52007176s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:07.161669Z","caller":"traceutil/trace.go:171","msg":"trace[98139965] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"2.522191664s","start":"2026-06-02T06:59:04.639453Z","end":"2026-06-02T06:59:07.161644Z","steps":["trace[98139965] 'process raft request' (duration: 2.52203249s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:07.16165Z","caller":"traceutil/trace.go:171","msg":"trace[1121084378] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"2.522195744s","start":"2026-06-02T06:59:04.639426Z","end":"2026-06-02T06:59:07.161622Z","steps":["trace[1121084378] 'process raft request' (duration: 2.521909407s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:07.161832Z","caller":"traceutil/trace.go:171","msg":"trace[1706935237] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1374; }","duration":"2.48811483s","start":"2026-06-02T06:59:04.673684Z","end":"2026-06-02T06:59:07.161799Z","steps":["trace[1706935237] 'read index received' (duration: 736.427686ms)","trace[1706935237] 'applied index is now lower than readState.Index' (duration: 1.751684564s)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:59:07.161906Z","caller":"traceutil/trace.go:171","msg":"trace[1850767178] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"2.519283029s","start":"2026-06-02T06:59:04.642601Z","end":"2026-06-02T06:59:07.161884Z","steps":["trace[1850767178] 'process raft request' (duration: 2.518958781s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.161872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.641441Z","time spent":"2.520258235s","remote":"127.0.0.1:59226","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-06-02T06:59:07.162052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.642592Z","time spent":"2.519389112s","remote":"127.0.0.1:59226","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-06-02T06:59:07.162144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488418808s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:59:07.162173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.055925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-02T06:59:07.162204Z","caller":"traceutil/trace.go:171","msg":"trace[459171041] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"470.085605ms","start":"2026-06-02T06:59:06.69211Z","end":"2026-06-02T06:59:07.162195Z","steps":["trace[459171041] 'agreement among raft nodes before linearized reading' (duration: 469.995803ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:07.162029Z","caller":"traceutil/trace.go:171","msg":"trace[1973677890] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.498743878s","start":"2026-06-02T06:59:05.663273Z","end":"2026-06-02T06:59:07.162017Z","steps":["trace[1973677890] 'process raft request' (duration: 1.498553213s)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:07.162229Z","caller":"traceutil/trace.go:171","msg":"trace[2112714788] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1326; }","duration":"2.488572972s","start":"2026-06-02T06:59:04.673637Z","end":"2026-06-02T06:59:07.16221Z","steps":["trace[2112714788] 'agreement among raft nodes before linearized reading' (duration: 2.488274004s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.162303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.673617Z","time spent":"2.488644004s","remote":"127.0.0.1:36874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:59:07.162313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:05.663251Z","time spent":"1.499003075s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-02T06:59:07.162341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329325802s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-02T06:59:07.162227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:06.692046Z","time spent":"470.176158ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-02T06:59:07.162389Z","caller":"traceutil/trace.go:171","msg":"trace[813358876] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1326; }","duration":"1.329427684s","start":"2026-06-02T06:59:05.832947Z","end":"2026-06-02T06:59:07.162375Z","steps":["trace[813358876] 'agreement among raft nodes before linearized reading' (duration: 1.329311031s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.162452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:05.832897Z","time spent":"1.329542067s","remote":"127.0.0.1:36760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-02T06:59:07.162547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.024249154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-06-02T06:59:07.162642Z","caller":"traceutil/trace.go:171","msg":"trace[1954392145] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1326; }","duration":"2.024389687s","start":"2026-06-02T06:59:05.138235Z","end":"2026-06-02T06:59:07.162625Z","steps":["trace[1954392145] 'agreement among raft nodes before linearized reading' (duration: 2.023864484s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.16273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:05.138217Z","time spent":"2.02448841s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-02T06:59:07.16305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.639408Z","time spent":"2.52358707s","remote":"127.0.0.1:59226","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-06-02T06:59:07.162263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.254372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:59:07.163185Z","caller":"traceutil/trace.go:171","msg":"trace[2134676631] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"243.195056ms","start":"2026-06-02T06:59:06.919982Z","end":"2026-06-02T06:59:07.163177Z","steps":["trace[2134676631] 'agreement among raft nodes before linearized reading' (duration: 242.265833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.162944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38108592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T06:59:07.163286Z","caller":"traceutil/trace.go:171","msg":"trace[972744665] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"1.381472681s","start":"2026-06-02T06:59:05.781799Z","end":"2026-06-02T06:59:07.163272Z","steps":["trace[972744665] 'agreement among raft nodes before linearized reading' (duration: 1.380950966s)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.163357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:05.781775Z","time spent":"1.381570063s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-02T06:59:07.162953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.06449ms","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-06-02T06:59:07.163416Z","caller":"traceutil/trace.go:171","msg":"trace[690949153] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1326; }","duration":"526.566384ms","start":"2026-06-02T06:59:06.636842Z","end":"2026-06-02T06:59:07.163409Z","steps":["trace[690949153] 'agreement among raft nodes before linearized reading' (duration: 525.904736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:07.164994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:06.636823Z","time spent":"528.159024ms","remote":"127.0.0.1:59226","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-06-02T06:59:07.161973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T06:59:04.639431Z","time spent":"2.522291316s","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T06:59:57.038048Z","caller":"traceutil/trace.go:171","msg":"trace[2141318120] linearizableReadLoop","detail":"{readStateIndex:1618; appliedIndex:1617; }","duration":"120.640243ms","start":"2026-06-02T06:59:56.917385Z","end":"2026-06-02T06:59:57.038025Z","steps":["trace[2141318120] 'read index received' (duration: 33.800476ms)","trace[2141318120] 'applied index is now lower than readState.Index' (duration: 86.838447ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T06:59:57.038186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.781018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T06:59:57.038233Z","caller":"traceutil/trace.go:171","msg":"trace[503495083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1553; }","duration":"120.869869ms","start":"2026-06-02T06:59:56.917352Z","end":"2026-06-02T06:59:57.038222Z","steps":["trace[503495083] 'agreement among raft nodes before linearized reading' (duration: 120.781157ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:57.578611Z","caller":"traceutil/trace.go:171","msg":"trace[733324326] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"161.332406ms","start":"2026-06-02T06:59:57.41725Z","end":"2026-06-02T06:59:57.578582Z","steps":["trace[733324326] 'process raft request' (duration: 161.172572ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:57.582946Z","caller":"traceutil/trace.go:171","msg":"trace[247380892] linearizableReadLoop","detail":"{readStateIndex:1623; appliedIndex:1621; }","duration":"162.361554ms","start":"2026-06-02T06:59:57.420563Z","end":"2026-06-02T06:59:57.582924Z","steps":["trace[247380892] 'read index received' (duration: 157.9244ms)","trace[247380892] 'applied index is now lower than readState.Index' (duration: 4.435984ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T06:59:57.583176Z","caller":"traceutil/trace.go:171","msg":"trace[622710377] transaction","detail":"{read_only:false; response_revision:1558; number_of_response:1; }","duration":"164.471978ms","start":"2026-06-02T06:59:57.41869Z","end":"2026-06-02T06:59:57.583162Z","steps":["trace[622710377] 'process raft request' (duration: 164.056128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:57.583487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.68676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-02T06:59:57.583545Z","caller":"traceutil/trace.go:171","msg":"trace[1637524890] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1558; }","duration":"158.781993ms","start":"2026-06-02T06:59:57.424751Z","end":"2026-06-02T06:59:57.583533Z","steps":["trace[1637524890] 'agreement among raft nodes before linearized reading' (duration: 158.635589ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:57.583607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.428993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-02T06:59:57.583674Z","caller":"traceutil/trace.go:171","msg":"trace[280720011] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1558; }","duration":"158.519216ms","start":"2026-06-02T06:59:57.425138Z","end":"2026-06-02T06:59:57.583657Z","steps":["trace[280720011] 'agreement among raft nodes before linearized reading' (duration: 158.360612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T06:59:57.583582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.99767ms","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-06-02T06:59:57.583833Z","caller":"traceutil/trace.go:171","msg":"trace[1554101373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1558; }","duration":"163.260877ms","start":"2026-06-02T06:59:57.420558Z","end":"2026-06-02T06:59:57.583819Z","steps":["trace[1554101373] 'agreement among raft nodes before linearized reading' (duration: 162.891618ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:57.705107Z","caller":"traceutil/trace.go:171","msg":"trace[1054568742] transaction","detail":"{read_only:false; response_revision:1561; number_of_response:1; }","duration":"115.396009ms","start":"2026-06-02T06:59:57.589693Z","end":"2026-06-02T06:59:57.705089Z","steps":["trace[1054568742] 'process raft request' (duration: 115.352888ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:57.705153Z","caller":"traceutil/trace.go:171","msg":"trace[1742345576] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"118.174421ms","start":"2026-06-02T06:59:57.586957Z","end":"2026-06-02T06:59:57.705132Z","steps":["trace[1742345576] 'process raft request' (duration: 115.940263ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T06:59:57.705236Z","caller":"traceutil/trace.go:171","msg":"trace[1476152547] transaction","detail":"{read_only:false; response_revision:1560; number_of_response:1; }","duration":"115.642975ms","start":"2026-06-02T06:59:57.589585Z","end":"2026-06-02T06:59:57.705228Z","steps":["trace[1476152547] 'process raft request' (duration: 115.413239ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:08.040616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.451106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-02T07:00:08.040923Z","caller":"traceutil/trace.go:171","msg":"trace[244364627] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"287.184169ms","start":"2026-06-02T07:00:07.753704Z","end":"2026-06-02T07:00:08.040888Z","steps":["trace[244364627] 'process raft request' (duration: 117.305792ms)","trace[244364627] 'compare' (duration: 168.669326ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:08.041582Z","caller":"traceutil/trace.go:171","msg":"trace[1447673398] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"285.461704ms","start":"2026-06-02T07:00:07.75611Z","end":"2026-06-02T07:00:08.041571Z","steps":["trace[1447673398] 'process raft request' (duration: 285.268119ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:08.041535Z","caller":"traceutil/trace.go:171","msg":"trace[789142449] linearizableReadLoop","detail":"{readStateIndex:1674; appliedIndex:1671; }","duration":"124.68225ms","start":"2026-06-02T07:00:07.916839Z","end":"2026-06-02T07:00:08.041521Z","steps":["trace[789142449] 'read index received' (duration: 51.360994ms)","trace[789142449] 'applied index is now lower than readState.Index' (duration: 73.320206ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:08.041781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.921286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:08.041862Z","caller":"traceutil/trace.go:171","msg":"trace[1043587290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1607; }","duration":"125.043139ms","start":"2026-06-02T07:00:07.916799Z","end":"2026-06-02T07:00:08.041842Z","steps":["trace[1043587290] 'agreement among raft nodes before linearized reading' (duration: 124.871105ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:08.041596Z","caller":"traceutil/trace.go:171","msg":"trace[258632804] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"287.854175ms","start":"2026-06-02T07:00:07.753709Z","end":"2026-06-02T07:00:08.041564Z","steps":["trace[258632804] 'process raft request' (duration: 287.096946ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:09.872732Z","caller":"traceutil/trace.go:171","msg":"trace[204427978] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"138.381162ms","start":"2026-06-02T07:00:09.734336Z","end":"2026-06-02T07:00:09.872717Z","steps":["trace[204427978] 'process raft request' (duration: 137.432767ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:26.790457Z","caller":"traceutil/trace.go:171","msg":"trace[432833029] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"102.679858ms","start":"2026-06-02T07:00:26.687753Z","end":"2026-06-02T07:00:26.790433Z","steps":["trace[432833029] 'process raft request' (duration: 102.537684ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:42.479729Z","caller":"traceutil/trace.go:171","msg":"trace[1839206528] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"122.426942ms","start":"2026-06-02T07:00:42.357282Z","end":"2026-06-02T07:00:42.479709Z","steps":["trace[1839206528] 'process raft request' (duration: 98.817608ms)","trace[1839206528] 'compare' (duration: 23.401779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:42.479973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.523464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-02T07:00:42.480067Z","caller":"traceutil/trace.go:171","msg":"trace[1591362456] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1763; }","duration":"113.665118ms","start":"2026-06-02T07:00:42.366381Z","end":"2026-06-02T07:00:42.480046Z","steps":["trace[1591362456] 'agreement among raft nodes before linearized reading' (duration: 113.440022ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:42.480345Z","caller":"traceutil/trace.go:171","msg":"trace[280913469] linearizableReadLoop","detail":"{readStateIndex:1837; appliedIndex:1836; }","duration":"113.232337ms","start":"2026-06-02T07:00:42.366431Z","end":"2026-06-02T07:00:42.479663Z","steps":["trace[280913469] 'read index received' (duration: 89.688295ms)","trace[280913469] 'applied index is now lower than readState.Index' (duration: 23.543122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:44.264274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.376931ms","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-06-02T07:00:44.264344Z","caller":"traceutil/trace.go:171","msg":"trace[497422115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1771; }","duration":"138.505414ms","start":"2026-06-02T07:00:44.125824Z","end":"2026-06-02T07:00:44.26433Z","steps":["trace[497422115] 'range keys from in-memory index tree' (duration: 138.212796ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:44.402886Z","caller":"traceutil/trace.go:171","msg":"trace[876811276] transaction","detail":"{read_only:false; response_revision:1772; number_of_response:1; }","duration":"134.749588ms","start":"2026-06-02T07:00:44.268099Z","end":"2026-06-02T07:00:44.402848Z","steps":["trace[876811276] 'process raft request' (duration: 134.571513ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:44.613706Z","caller":"traceutil/trace.go:171","msg":"trace[40139212] linearizableReadLoop","detail":"{readStateIndex:1847; appliedIndex:1846; }","duration":"135.519447ms","start":"2026-06-02T07:00:44.478158Z","end":"2026-06-02T07:00:44.613678Z","steps":["trace[40139212] 'read index received' (duration: 76.415405ms)","trace[40139212] 'applied index is now lower than readState.Index' (duration: 59.103152ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:44.613986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.784683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:44.614071Z","caller":"traceutil/trace.go:171","msg":"trace[1176057547] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1773; }","duration":"135.945248ms","start":"2026-06-02T07:00:44.478106Z","end":"2026-06-02T07:00:44.614052Z","steps":["trace[1176057547] 'agreement among raft nodes before linearized reading' (duration: 135.752764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:44.614098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.005873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T07:00:44.614133Z","caller":"traceutil/trace.go:171","msg":"trace[1175928554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1773; }","duration":"131.078554ms","start":"2026-06-02T07:00:44.483045Z","end":"2026-06-02T07:00:44.614123Z","steps":["trace[1175928554] 'agreement among raft nodes before linearized reading' (duration: 130.948071ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:44.614267Z","caller":"traceutil/trace.go:171","msg":"trace[1949755519] transaction","detail":"{read_only:false; response_revision:1773; number_of_response:1; }","duration":"205.582961ms","start":"2026-06-02T07:00:44.408673Z","end":"2026-06-02T07:00:44.614256Z","steps":["trace[1949755519] 'process raft request' (duration: 145.959235ms)","trace[1949755519] 'compare' (duration: 58.948038ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:46.119841Z","caller":"traceutil/trace.go:171","msg":"trace[1572227383] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"107.082091ms","start":"2026-06-02T07:00:46.012737Z","end":"2026-06-02T07:00:46.119819Z","steps":["trace[1572227383] 'process raft request' (duration: 106.932147ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:47.316247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.829024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:00:47.316334Z","caller":"traceutil/trace.go:171","msg":"trace[2047018646] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1783; }","duration":"140.982799ms","start":"2026-06-02T07:00:47.175333Z","end":"2026-06-02T07:00:47.316316Z","steps":["trace[2047018646] 'count revisions from in-memory index tree' (duration: 140.664369ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:47.800856Z","caller":"traceutil/trace.go:171","msg":"trace[1566182416] transaction","detail":"{read_only:false; response_revision:1786; number_of_response:1; }","duration":"132.177972ms","start":"2026-06-02T07:00:47.668642Z","end":"2026-06-02T07:00:47.80082Z","steps":["trace[1566182416] 'process raft request' (duration: 80.14453ms)","trace[1566182416] 'compare' (duration: 51.908119ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:48.878562Z","caller":"traceutil/trace.go:171","msg":"trace[1285402901] linearizableReadLoop","detail":"{readStateIndex:1864; appliedIndex:1863; }","duration":"180.285203ms","start":"2026-06-02T07:00:48.698262Z","end":"2026-06-02T07:00:48.878547Z","steps":["trace[1285402901] 'read index received' (duration: 180.15324ms)","trace[1285402901] 'applied index is now lower than readState.Index' (duration: 131.423µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:48.878641Z","caller":"traceutil/trace.go:171","msg":"trace[778165866] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"185.807295ms","start":"2026-06-02T07:00:48.692826Z","end":"2026-06-02T07:00:48.878633Z","steps":["trace[778165866] 'process raft request' (duration: 185.62781ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:48.878813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.50857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-02T07:00:48.878887Z","caller":"traceutil/trace.go:171","msg":"trace[1246420977] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1789; }","duration":"180.630222ms","start":"2026-06-02T07:00:48.69824Z","end":"2026-06-02T07:00:48.87887Z","steps":["trace[1246420977] 'agreement among raft nodes before linearized reading' (duration: 180.443948ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:49.876757Z","caller":"traceutil/trace.go:171","msg":"trace[1132313888] transaction","detail":"{read_only:false; response_revision:1795; number_of_response:1; }","duration":"141.948992ms","start":"2026-06-02T07:00:49.734781Z","end":"2026-06-02T07:00:49.87673Z","steps":["trace[1132313888] 'process raft request' (duration: 141.772988ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:50.020127Z","caller":"traceutil/trace.go:171","msg":"trace[717393257] linearizableReadLoop","detail":"{readStateIndex:1871; appliedIndex:1870; }","duration":"101.110987ms","start":"2026-06-02T07:00:49.918997Z","end":"2026-06-02T07:00:50.020108Z","steps":["trace[717393257] 'read index received' (duration: 29.016912ms)","trace[717393257] 'applied index is now lower than readState.Index' (duration: 72.093275ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:50.020245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.23054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:50.020269Z","caller":"traceutil/trace.go:171","msg":"trace[262776789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1796; }","duration":"101.339533ms","start":"2026-06-02T07:00:49.918921Z","end":"2026-06-02T07:00:50.02026Z","steps":["trace[262776789] 'agreement among raft nodes before linearized reading' (duration: 101.268861ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:50.020335Z","caller":"traceutil/trace.go:171","msg":"trace[198288279] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"214.471258ms","start":"2026-06-02T07:00:49.80582Z","end":"2026-06-02T07:00:50.020291Z","steps":["trace[198288279] 'process raft request' (duration: 142.183258ms)","trace[198288279] 'compare' (duration: 71.990253ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:51.121679Z","caller":"traceutil/trace.go:171","msg":"trace[2057292197] linearizableReadLoop","detail":"{readStateIndex:1875; appliedIndex:1874; }","duration":"231.349589ms","start":"2026-06-02T07:00:50.890312Z","end":"2026-06-02T07:00:51.121661Z","steps":["trace[2057292197] 'read index received' (duration: 231.245516ms)","trace[2057292197] 'applied index is now lower than readState.Index' (duration: 103.423µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:51.121739Z","caller":"traceutil/trace.go:171","msg":"trace[1267145256] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"236.07883ms","start":"2026-06-02T07:00:50.885624Z","end":"2026-06-02T07:00:51.121702Z","steps":["trace[1267145256] 'process raft request' (duration: 235.938747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:51.121871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.557284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-02T07:00:51.121897Z","caller":"traceutil/trace.go:171","msg":"trace[1937331119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1800; }","duration":"231.631316ms","start":"2026-06-02T07:00:50.890258Z","end":"2026-06-02T07:00:51.12189Z","steps":["trace[1937331119] 'agreement among raft nodes before linearized reading' (duration: 231.502383ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:51.121907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.557921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-02T07:00:51.121956Z","caller":"traceutil/trace.go:171","msg":"trace[718700594] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1800; }","duration":"225.648513ms","start":"2026-06-02T07:00:50.896298Z","end":"2026-06-02T07:00:51.121947Z","steps":["trace[718700594] 'agreement among raft nodes before linearized reading' (duration: 225.5335ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:51.264912Z","caller":"traceutil/trace.go:171","msg":"trace[1093310023] transaction","detail":"{read_only:false; response_revision:1801; number_of_response:1; }","duration":"138.549925ms","start":"2026-06-02T07:00:51.126338Z","end":"2026-06-02T07:00:51.264888Z","steps":["trace[1093310023] 'process raft request' (duration: 47.145677ms)","trace[1093310023] 'compare' (duration: 91.246604ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:51.265094Z","caller":"traceutil/trace.go:171","msg":"trace[1810840395] transaction","detail":"{read_only:false; response_revision:1802; number_of_response:1; }","duration":"138.247387ms","start":"2026-06-02T07:00:51.126828Z","end":"2026-06-02T07:00:51.265076Z","steps":["trace[1810840395] 'process raft request' (duration: 138.022072ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:52.797804Z","caller":"traceutil/trace.go:171","msg":"trace[1954084258] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"133.485976ms","start":"2026-06-02T07:00:52.664295Z","end":"2026-06-02T07:00:52.797781Z","steps":["trace[1954084258] 'read index received' (duration: 133.409763ms)","trace[1954084258] 'applied index is now lower than readState.Index' (duration: 75.013µs)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:52.797927Z","caller":"traceutil/trace.go:171","msg":"trace[321433340] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"154.818522ms","start":"2026-06-02T07:00:52.643075Z","end":"2026-06-02T07:00:52.797894Z","steps":["trace[321433340] 'process raft request' (duration: 154.588916ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:52.79807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.752532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-02T07:00:52.798115Z","caller":"traceutil/trace.go:171","msg":"trace[496331596] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1808; }","duration":"133.855316ms","start":"2026-06-02T07:00:52.664246Z","end":"2026-06-02T07:00:52.798102Z","steps":["trace[496331596] 'agreement among raft nodes before linearized reading' (duration: 133.63968ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:55.273159Z","caller":"traceutil/trace.go:171","msg":"trace[904267711] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"108.505737ms","start":"2026-06-02T07:00:55.164624Z","end":"2026-06-02T07:00:55.27313Z","steps":["trace[904267711] 'process raft request' (duration: 108.319543ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:00:57.160299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.449353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-02T07:00:57.44462Z","caller":"traceutil/trace.go:171","msg":"trace[1382136486] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"129.092113ms","start":"2026-06-02T07:00:57.315458Z","end":"2026-06-02T07:00:57.44455Z","steps":["trace[1382136486] 'process raft request' (duration: 66.332437ms)","trace[1382136486] 'compare' (duration: 62.556571ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:58.075121Z","caller":"traceutil/trace.go:171","msg":"trace[926273120] linearizableReadLoop","detail":"{readStateIndex:1908; appliedIndex:1907; }","duration":"156.096814ms","start":"2026-06-02T07:00:57.918961Z","end":"2026-06-02T07:00:58.075057Z","steps":["trace[926273120] 'read index received' (duration: 118.03503ms)","trace[926273120] 'applied index is now lower than readState.Index' (duration: 38.060294ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:00:58.075303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.313129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:00:58.075381Z","caller":"traceutil/trace.go:171","msg":"trace[1108959184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1831; }","duration":"156.483533ms","start":"2026-06-02T07:00:57.918883Z","end":"2026-06-02T07:00:58.075367Z","steps":["trace[1108959184] 'agreement among raft nodes before linearized reading' (duration: 156.324419ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:00:58.075713Z","caller":"traceutil/trace.go:171","msg":"trace[1235963684] transaction","detail":"{read_only:false; response_revision:1831; number_of_response:1; }","duration":"214.947029ms","start":"2026-06-02T07:00:57.86021Z","end":"2026-06-02T07:00:58.075157Z","steps":["trace[1235963684] 'process raft request' (duration: 176.812354ms)","trace[1235963684] 'compare' (duration: 37.880389ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:58.246297Z","caller":"traceutil/trace.go:171","msg":"trace[1917476390] transaction","detail":"{read_only:false; response_revision:1832; number_of_response:1; }","duration":"120.211885ms","start":"2026-06-02T07:00:58.12604Z","end":"2026-06-02T07:00:58.246252Z","steps":["trace[1917476390] 'process raft request' (duration: 59.161534ms)","trace[1917476390] 'compare' (duration: 60.867436ms)"],"step_count":2} {"level":"info","ts":"2026-06-02T07:00:59.813459Z","caller":"traceutil/trace.go:171","msg":"trace[480665748] transaction","detail":"{read_only:false; response_revision:1840; number_of_response:1; }","duration":"275.327845ms","start":"2026-06-02T07:00:59.538104Z","end":"2026-06-02T07:00:59.813432Z","steps":["trace[480665748] 'process raft request' (duration: 275.166201ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:01:00.084587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.03552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:01:00.084746Z","caller":"traceutil/trace.go:171","msg":"trace[1805381113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1840; }","duration":"166.266325ms","start":"2026-06-02T07:00:59.91846Z","end":"2026-06-02T07:01:00.084726Z","steps":["trace[1805381113] 'range keys from in-memory index tree' (duration: 165.922746ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:01:00.084615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.83521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-02T07:01:00.084973Z","caller":"traceutil/trace.go:171","msg":"trace[1223867552] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1840; }","duration":"101.21387ms","start":"2026-06-02T07:00:59.983691Z","end":"2026-06-02T07:01:00.084904Z","steps":["trace[1223867552] 'count revisions from in-memory index tree' (duration: 100.749578ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:01:47.411354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.097158ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-02T07:01:47.411473Z","caller":"traceutil/trace.go:171","msg":"trace[106170166] linearizableReadLoop","detail":"{readStateIndex:2142; appliedIndex:2141; }","duration":"359.787602ms","start":"2026-06-02T07:01:47.05166Z","end":"2026-06-02T07:01:47.411447Z","steps":["trace[106170166] 'read index received' (duration: 126.583791ms)","trace[106170166] 'applied index is now lower than readState.Index' (duration: 233.201811ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:01:47.411668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.991097ms","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-06-02T07:01:47.411714Z","caller":"traceutil/trace.go:171","msg":"trace[20483610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2055; }","duration":"360.07652ms","start":"2026-06-02T07:01:47.05162Z","end":"2026-06-02T07:01:47.411696Z","steps":["trace[20483610] 'agreement among raft nodes before linearized reading' (duration: 359.890205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-02T07:01:47.411763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:01:47.051597Z","time spent":"360.153081ms","remote":"127.0.0.1:59226","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":"info","ts":"2026-06-02T07:01:47.529664Z","caller":"traceutil/trace.go:171","msg":"trace[175881572] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"114.653746ms","start":"2026-06-02T07:01:47.414991Z","end":"2026-06-02T07:01:47.529645Z","steps":["trace[175881572] 'process raft request' (duration: 114.510883ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:01:48.003431Z","caller":"traceutil/trace.go:171","msg":"trace[1348396203] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"129.493215ms","start":"2026-06-02T07:01:47.873907Z","end":"2026-06-02T07:01:48.0034Z","steps":["trace[1348396203] 'process raft request' (duration: 129.189817ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:01:53.371373Z","caller":"traceutil/trace.go:171","msg":"trace[180025362] linearizableReadLoop","detail":"{readStateIndex:2172; appliedIndex:2171; }","duration":"187.056922ms","start":"2026-06-02T07:01:53.184296Z","end":"2026-06-02T07:01:53.371353Z","steps":["trace[180025362] 'read index received' (duration: 121.100919ms)","trace[180025362] 'applied index is now lower than readState.Index' (duration: 65.954893ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:01:53.371559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.244476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-06-02T07:01:53.371585Z","caller":"traceutil/trace.go:171","msg":"trace[1296770671] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2084; }","duration":"187.323729ms","start":"2026-06-02T07:01:53.184251Z","end":"2026-06-02T07:01:53.371574Z","steps":["trace[1296770671] 'agreement among raft nodes before linearized reading' (duration: 187.178306ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:01:53.371736Z","caller":"traceutil/trace.go:171","msg":"trace[1171681914] transaction","detail":"{read_only:false; response_revision:2084; number_of_response:1; }","duration":"304.16028ms","start":"2026-06-02T07:01:53.067537Z","end":"2026-06-02T07:01:53.371697Z","steps":["trace[1171681914] 'process raft request' (duration: 237.936441ms)","trace[1171681914] 'compare' (duration: 65.604294ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:01:53.371891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-02T07:01:53.067504Z","time spent":"304.304004ms","remote":"127.0.0.1:59226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-02T07:03:11.844452Z","caller":"traceutil/trace.go:171","msg":"trace[1016519840] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"101.972761ms","start":"2026-06-02T07:03:11.742449Z","end":"2026-06-02T07:03:11.844422Z","steps":["trace[1016519840] 'process raft request' (duration: 101.746834ms)"],"step_count":1} {"level":"info","ts":"2026-06-02T07:03:12.091304Z","caller":"traceutil/trace.go:171","msg":"trace[947818559] linearizableReadLoop","detail":"{readStateIndex:2574; appliedIndex:2573; }","duration":"172.660501ms","start":"2026-06-02T07:03:11.918593Z","end":"2026-06-02T07:03:12.091254Z","steps":["trace[947818559] 'read index received' (duration: 144.043137ms)","trace[947818559] 'applied index is now lower than readState.Index' (duration: 28.615453ms)"],"step_count":2} {"level":"warn","ts":"2026-06-02T07:03:12.091446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.833995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-02T07:03:12.091517Z","caller":"traceutil/trace.go:171","msg":"trace[1295876849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2469; }","duration":"172.951078ms","start":"2026-06-02T07:03:11.918545Z","end":"2026-06-02T07:03:12.091496Z","steps":["trace[1295876849] 'agreement among raft nodes before linearized reading' (duration: 172.828435ms)"],"step_count":1}