{"level":"warn","ts":"2026-04-27T16:06:17.711599Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:06:17.711698Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.30: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.30:2380","--initial-cluster=instance=https://199.19.213.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.30: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-04-27T16:06:17.711781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T16:06:17.711796Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-04-27T16:06:17.711816Z","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-04-27T16:06:17.712941Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"]} {"level":"info","ts":"2026-04-27T16:06:17.713147Z","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.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.30: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-04-27T16:06:17.716677Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.217498ms"} {"level":"info","ts":"2026-04-27T16:06:17.724038Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e2b5783e11defe7e","cluster-id":"5210a9231a779bf0"} {"level":"info","ts":"2026-04-27T16:06:17.724142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T16:06:17.724198Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 0"} {"level":"info","ts":"2026-04-27T16:06:17.724228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e2b5783e11defe7e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T16:06:17.724249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became follower at term 1"} {"level":"info","ts":"2026-04-27T16:06:17.72436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"warn","ts":"2026-04-27T16:06:17.729985Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T16:06:17.731726Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T16:06:17.733358Z","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-04-27T16:06:17.736725Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e2b5783e11defe7e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T16:06:17.737708Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e2b5783e11defe7e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T16:06:17.737956Z","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-04-27T16:06:17.738019Z","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-04-27T16:06:17.738872Z","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-04-27T16:06:17.740914Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e switched to configuration voters=(16336095431339474558)"} {"level":"info","ts":"2026-04-27T16:06:17.741069Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","added-peer-id":"e2b5783e11defe7e","added-peer-peer-urls":["https://199.19.213.30:2380"]} {"level":"info","ts":"2026-04-27T16:06:17.741793Z","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-04-27T16:06:17.741829Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-04-27T16:06:17.741868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.30:2380"} {"level":"info","ts":"2026-04-27T16:06:17.742121Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e2b5783e11defe7e","initial-advertise-peer-urls":["https://199.19.213.30:2380"],"listen-peer-urls":["https://199.19.213.30:2380"],"advertise-client-urls":["https://199.19.213.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T16:06:17.742165Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T16:06:18.328331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T16:06:18.328376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T16:06:18.328418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgPreVoteResp from e2b5783e11defe7e at term 1"} {"level":"info","ts":"2026-04-27T16:06:18.32844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became candidate at term 2"} {"level":"info","ts":"2026-04-27T16:06:18.328456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e received MsgVoteResp from e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-04-27T16:06:18.328468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e2b5783e11defe7e became leader at term 2"} {"level":"info","ts":"2026-04-27T16:06:18.32848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e2b5783e11defe7e elected leader e2b5783e11defe7e at term 2"} {"level":"info","ts":"2026-04-27T16:06:18.344462Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e2b5783e11defe7e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.30:2379]}","request-path":"/0/members/e2b5783e11defe7e/attributes","cluster-id":"5210a9231a779bf0","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T16:06:18.344687Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:18.345561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:06:18.345879Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T16:06:18.346392Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T16:06:18.346456Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T16:06:18.346513Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5210a9231a779bf0","local-member-id":"e2b5783e11defe7e","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:18.346609Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:18.346715Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T16:06:18.34804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T16:06:18.349397Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.30:2379"} {"level":"info","ts":"2026-04-27T16:06:21.033007Z","caller":"traceutil/trace.go:171","msg":"trace[32218012] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"142.124938ms","start":"2026-04-27T16:06:20.89085Z","end":"2026-04-27T16:06:21.032975Z","steps":["trace[32218012] 'read index received' (duration: 100.018242ms)","trace[32218012] 'applied index is now lower than readState.Index' (duration: 42.104796ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:21.033181Z","caller":"traceutil/trace.go:171","msg":"trace[563218423] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"144.030143ms","start":"2026-04-27T16:06:20.889135Z","end":"2026-04-27T16:06:21.033165Z","steps":["trace[563218423] 'process raft request' (duration: 101.816055ms)","trace[563218423] 'compare' (duration: 41.802548ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:21.033904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.579439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-27T16:06:21.034012Z","caller":"traceutil/trace.go:171","msg":"trace[1692598099] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"143.163003ms","start":"2026-04-27T16:06:20.890826Z","end":"2026-04-27T16:06:21.033989Z","steps":["trace[1692598099] 'agreement among raft nodes before linearized reading' (duration: 142.580279ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:21.611736Z","caller":"traceutil/trace.go:171","msg":"trace[1552924795] transaction","detail":"{read_only:false; response_revision:146; number_of_response:1; }","duration":"114.351578ms","start":"2026-04-27T16:06:21.497344Z","end":"2026-04-27T16:06:21.611695Z","steps":["trace[1552924795] 'process raft request' (duration: 52.157738ms)","trace[1552924795] 'compare' (duration: 62.018836ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:22.432027Z","caller":"traceutil/trace.go:171","msg":"trace[1772283312] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"114.188004ms","start":"2026-04-27T16:06:22.317798Z","end":"2026-04-27T16:06:22.431986Z","steps":["trace[1772283312] 'process raft request' (duration: 70.937051ms)","trace[1772283312] 'compare' (duration: 43.010277ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:22.689721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.48321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:22.689967Z","caller":"traceutil/trace.go:171","msg":"trace[344504971] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"239.863645ms","start":"2026-04-27T16:06:22.450073Z","end":"2026-04-27T16:06:22.689936Z","steps":["trace[344504971] 'process raft request' (duration: 117.454223ms)","trace[344504971] 'compare' (duration: 121.254564ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:22.690185Z","caller":"traceutil/trace.go:171","msg":"trace[522388615] linearizableReadLoop","detail":"{readStateIndex:180; appliedIndex:177; }","duration":"212.211258ms","start":"2026-04-27T16:06:22.477942Z","end":"2026-04-27T16:06:22.690154Z","steps":["trace[522388615] 'read index received' (duration: 89.598851ms)","trace[522388615] 'applied index is now lower than readState.Index' (duration: 122.610387ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:22.690313Z","caller":"traceutil/trace.go:171","msg":"trace[1944786487] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"238.772719ms","start":"2026-04-27T16:06:22.451513Z","end":"2026-04-27T16:06:22.690286Z","steps":["trace[1944786487] 'process raft request' (duration: 238.470161ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:22.69044Z","caller":"traceutil/trace.go:171","msg":"trace[278712848] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"238.883331ms","start":"2026-04-27T16:06:22.451476Z","end":"2026-04-27T16:06:22.69036Z","steps":["trace[278712848] 'process raft request' (duration: 238.43312ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:22.690782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.834833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:22.690844Z","caller":"traceutil/trace.go:171","msg":"trace[1951143545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:176; }","duration":"212.944746ms","start":"2026-04-27T16:06:22.477884Z","end":"2026-04-27T16:06:22.690829Z","steps":["trace[1951143545] 'agreement among raft nodes before linearized reading' (duration: 212.394392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:27.56906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.586897ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:27.569162Z","caller":"traceutil/trace.go:171","msg":"trace[1299400237] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"157.819256ms","start":"2026-04-27T16:06:27.411328Z","end":"2026-04-27T16:06:27.569147Z","steps":["trace[1299400237] 'process raft request' (duration: 42.096455ms)","trace[1299400237] 'compare' (duration: 115.411333ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:28.472984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.363235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:28.473239Z","caller":"traceutil/trace.go:171","msg":"trace[1276938911] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"262.135383ms","start":"2026-04-27T16:06:28.211087Z","end":"2026-04-27T16:06:28.473198Z","steps":["trace[1276938911] 'read index received' (duration: 261.62345ms)","trace[1276938911] 'applied index is now lower than readState.Index' (duration: 486.372µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:28.473421Z","caller":"traceutil/trace.go:171","msg":"trace[179539316] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"299.134774ms","start":"2026-04-27T16:06:28.174239Z","end":"2026-04-27T16:06:28.473373Z","steps":["trace[179539316] 'process raft request' (duration: 298.853637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.473419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.311587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-27T16:06:28.473561Z","caller":"traceutil/trace.go:171","msg":"trace[87939048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"262.499201ms","start":"2026-04-27T16:06:28.211047Z","end":"2026-04-27T16:06:28.473546Z","steps":["trace[87939048] 'agreement among raft nodes before linearized reading' (duration: 262.294876ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:28.473479Z","caller":"traceutil/trace.go:171","msg":"trace[1321284129] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"300.857956ms","start":"2026-04-27T16:06:28.172587Z","end":"2026-04-27T16:06:28.473445Z","steps":["trace[1321284129] 'process raft request' (duration: 28.968818ms)","trace[1321284129] 'compare' (duration: 271.233372ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:28.473883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.172565Z","time spent":"301.268736ms","remote":"127.0.0.1:36706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:06:28.474205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.426077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-27T16:06:28.474509Z","caller":"traceutil/trace.go:171","msg":"trace[788809224] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"213.763726ms","start":"2026-04-27T16:06:28.260704Z","end":"2026-04-27T16:06:28.474467Z","steps":["trace[788809224] 'agreement among raft nodes before linearized reading' (duration: 213.429687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.474315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.405317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-27T16:06:28.475185Z","caller":"traceutil/trace.go:171","msg":"trace[1453545311] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:348; }","duration":"163.307118ms","start":"2026-04-27T16:06:28.311865Z","end":"2026-04-27T16:06:28.475172Z","steps":["trace[1453545311] 'agreement among raft nodes before linearized reading' (duration: 162.389716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.474335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.853965ms","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-04-27T16:06:28.47535Z","caller":"traceutil/trace.go:171","msg":"trace[1832605336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:348; }","duration":"126.87546ms","start":"2026-04-27T16:06:28.348442Z","end":"2026-04-27T16:06:28.475317Z","steps":["trace[1832605336] 'agreement among raft nodes before linearized reading' (duration: 125.852725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.47432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.573979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-27T16:06:28.475551Z","caller":"traceutil/trace.go:171","msg":"trace[1593970537] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:348; }","duration":"114.86461ms","start":"2026-04-27T16:06:28.360676Z","end":"2026-04-27T16:06:28.475541Z","steps":["trace[1593970537] 'agreement among raft nodes before linearized reading' (duration: 113.516678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.474395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.720087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-04-27T16:06:28.475761Z","caller":"traceutil/trace.go:171","msg":"trace[1189961294] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:348; }","duration":"201.09569ms","start":"2026-04-27T16:06:28.274648Z","end":"2026-04-27T16:06:28.475743Z","steps":["trace[1189961294] 'agreement among raft nodes before linearized reading' (duration: 199.720177ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:28.63041Z","caller":"traceutil/trace.go:171","msg":"trace[1451926986] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"145.424967ms","start":"2026-04-27T16:06:28.484948Z","end":"2026-04-27T16:06:28.630373Z","steps":["trace[1451926986] 'process raft request' (duration: 145.326105ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:28.630401Z","caller":"traceutil/trace.go:171","msg":"trace[1276166879] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"146.533564ms","start":"2026-04-27T16:06:28.483853Z","end":"2026-04-27T16:06:28.630386Z","steps":["trace[1276166879] 'process raft request' (duration: 119.4124ms)","trace[1276166879] 'compare' (duration: 26.868837ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:28.630639Z","caller":"traceutil/trace.go:171","msg":"trace[323023949] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"135.982689ms","start":"2026-04-27T16:06:28.494648Z","end":"2026-04-27T16:06:28.630631Z","steps":["trace[323023949] 'process raft request' (duration: 135.676962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:28.630779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.307517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-27T16:06:28.630811Z","caller":"traceutil/trace.go:171","msg":"trace[1034615999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:354; }","duration":"119.387649ms","start":"2026-04-27T16:06:28.511415Z","end":"2026-04-27T16:06:28.630803Z","steps":["trace[1034615999] 'agreement among raft nodes before linearized reading' (duration: 119.302967ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:28.630792Z","caller":"traceutil/trace.go:171","msg":"trace[1712767292] linearizableReadLoop","detail":"{readStateIndex:365; appliedIndex:362; }","duration":"118.899147ms","start":"2026-04-27T16:06:28.511457Z","end":"2026-04-27T16:06:28.630356Z","steps":["trace[1712767292] 'read index received' (duration: 91.757583ms)","trace[1712767292] 'applied index is now lower than readState.Index' (duration: 27.140574ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:29.017648Z","caller":"traceutil/trace.go:171","msg":"trace[1421423528] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"381.709077ms","start":"2026-04-27T16:06:28.635712Z","end":"2026-04-27T16:06:29.017421Z","steps":["trace[1421423528] 'process raft request' (duration: 381.41853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.01777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.63569Z","time spent":"382.020795ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:29.017938Z","caller":"traceutil/trace.go:171","msg":"trace[1605992384] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"380.104898ms","start":"2026-04-27T16:06:28.63782Z","end":"2026-04-27T16:06:29.017925Z","steps":["trace[1605992384] 'process raft request' (duration: 379.624446ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.017956Z","caller":"traceutil/trace.go:171","msg":"trace[1231172223] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"376.42794ms","start":"2026-04-27T16:06:28.641496Z","end":"2026-04-27T16:06:29.017924Z","steps":["trace[1231172223] 'process raft request' (duration: 376.336418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.018024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.63781Z","time spent":"380.161389ms","remote":"127.0.0.1:37152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:29.018159Z","caller":"traceutil/trace.go:171","msg":"trace[1248625589] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:365; }","duration":"357.458093ms","start":"2026-04-27T16:06:28.660669Z","end":"2026-04-27T16:06:29.018127Z","steps":["trace[1248625589] 'read index received' (duration: 12.037271ms)","trace[1248625589] 'applied index is now lower than readState.Index' (duration: 345.419042ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:29.018332Z","caller":"traceutil/trace.go:171","msg":"trace[2135696898] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"376.020729ms","start":"2026-04-27T16:06:28.642286Z","end":"2026-04-27T16:06:29.018307Z","steps":["trace[2135696898] 'process raft request' (duration: 375.593769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.018417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.641486Z","time spent":"376.562803ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:29.018482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.642277Z","time spent":"376.116352ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:29.018798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.119849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-27T16:06:29.018827Z","caller":"traceutil/trace.go:171","msg":"trace[674293203] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"379.77496ms","start":"2026-04-27T16:06:28.639039Z","end":"2026-04-27T16:06:29.018814Z","steps":["trace[674293203] 'process raft request' (duration: 378.644223ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.018855Z","caller":"traceutil/trace.go:171","msg":"trace[1948489706] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:362; }","duration":"358.19562ms","start":"2026-04-27T16:06:28.660644Z","end":"2026-04-27T16:06:29.01884Z","steps":["trace[1948489706] 'agreement among raft nodes before linearized reading' (duration: 358.037226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.018886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.639021Z","time spent":"379.829211ms","remote":"127.0.0.1:36882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:29.018896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.660608Z","time spent":"358.279462ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-04-27T16:06:29.01918Z","caller":"traceutil/trace.go:171","msg":"trace[98384036] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"377.714941ms","start":"2026-04-27T16:06:28.641453Z","end":"2026-04-27T16:06:29.019168Z","steps":["trace[98384036] 'process raft request' (duration: 376.322147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.019251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.641437Z","time spent":"377.779012ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:29.019543Z","caller":"traceutil/trace.go:171","msg":"trace[1415009369] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"380.695913ms","start":"2026-04-27T16:06:28.637266Z","end":"2026-04-27T16:06:29.017962Z","steps":["trace[1415009369] 'process raft request' (duration: 380.075468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.019735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.637247Z","time spent":"382.407594ms","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:29.060258Z","caller":"traceutil/trace.go:171","msg":"trace[1091527185] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"383.924611ms","start":"2026-04-27T16:06:28.676308Z","end":"2026-04-27T16:06:29.060232Z","steps":["trace[1091527185] 'process raft request' (duration: 383.824048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.060452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.676266Z","time spent":"384.108195ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T16:06:29.061114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.840885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:06:29.061176Z","caller":"traceutil/trace.go:171","msg":"trace[204503352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:363; }","duration":"290.941538ms","start":"2026-04-27T16:06:28.770221Z","end":"2026-04-27T16:06:29.061162Z","steps":["trace[204503352] 'agreement among raft nodes before linearized reading' (duration: 290.781364ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.06113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.909446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T16:06:29.061176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.583032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:06:29.061257Z","caller":"traceutil/trace.go:171","msg":"trace[831307144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:363; }","duration":"349.694955ms","start":"2026-04-27T16:06:28.711539Z","end":"2026-04-27T16:06:29.061234Z","steps":["trace[831307144] 'agreement among raft nodes before linearized reading' (duration: 349.575572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.061325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:28.711521Z","time spent":"349.791408ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:06:29.061295Z","caller":"traceutil/trace.go:171","msg":"trace[444461069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:363; }","duration":"201.066109ms","start":"2026-04-27T16:06:28.860155Z","end":"2026-04-27T16:06:29.061221Z","steps":["trace[444461069] 'agreement among raft nodes before linearized reading' (duration: 200.803773ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.247015Z","caller":"traceutil/trace.go:171","msg":"trace[2117744637] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"181.080617ms","start":"2026-04-27T16:06:29.065899Z","end":"2026-04-27T16:06:29.24698Z","steps":["trace[2117744637] 'process raft request' (duration: 99.639943ms)","trace[2117744637] 'compare' (duration: 81.040555ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:29.247142Z","caller":"traceutil/trace.go:171","msg":"trace[274434730] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"127.406483ms","start":"2026-04-27T16:06:29.119704Z","end":"2026-04-27T16:06:29.24711Z","steps":["trace[274434730] 'process raft request' (duration: 127.355801ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.247598Z","caller":"traceutil/trace.go:171","msg":"trace[572317761] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"181.399915ms","start":"2026-04-27T16:06:29.066182Z","end":"2026-04-27T16:06:29.247582Z","steps":["trace[572317761] 'process raft request' (duration: 180.694908ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.247665Z","caller":"traceutil/trace.go:171","msg":"trace[1234300062] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:382; }","duration":"133.282455ms","start":"2026-04-27T16:06:29.114363Z","end":"2026-04-27T16:06:29.247646Z","steps":["trace[1234300062] 'read index received' (duration: 51.058041ms)","trace[1234300062] 'applied index is now lower than readState.Index' (duration: 82.221863ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:29.247653Z","caller":"traceutil/trace.go:171","msg":"trace[1074792185] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"181.357954ms","start":"2026-04-27T16:06:29.066261Z","end":"2026-04-27T16:06:29.247619Z","steps":["trace[1074792185] 'process raft request' (duration: 180.741049ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.247818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.435958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-z5cfc\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-27T16:06:29.24787Z","caller":"traceutil/trace.go:171","msg":"trace[1608794109] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-z5cfc; range_end:; response_count:1; response_revision:375; }","duration":"133.553911ms","start":"2026-04-27T16:06:29.114306Z","end":"2026-04-27T16:06:29.24786Z","steps":["trace[1608794109] 'agreement among raft nodes before linearized reading' (duration: 133.446418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.247985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.899065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-27T16:06:29.248063Z","caller":"traceutil/trace.go:171","msg":"trace[307414282] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"132.997508ms","start":"2026-04-27T16:06:29.115026Z","end":"2026-04-27T16:06:29.248023Z","steps":["trace[307414282] 'agreement among raft nodes before linearized reading' (duration: 132.826814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.248168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.481113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3475"} {"level":"warn","ts":"2026-04-27T16:06:29.248192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.549109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:2278"} {"level":"info","ts":"2026-04-27T16:06:29.248212Z","caller":"traceutil/trace.go:171","msg":"trace[1579898751] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:375; }","duration":"129.537604ms","start":"2026-04-27T16:06:29.118663Z","end":"2026-04-27T16:06:29.2482Z","steps":["trace[1579898751] 'agreement among raft nodes before linearized reading' (duration: 129.323749ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:29.248226Z","caller":"traceutil/trace.go:171","msg":"trace[938108457] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:375; }","duration":"130.59938ms","start":"2026-04-27T16:06:29.117617Z","end":"2026-04-27T16:06:29.248216Z","steps":["trace[938108457] 'agreement among raft nodes before linearized reading' (duration: 130.538479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.248409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.390969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-27T16:06:29.248454Z","caller":"traceutil/trace.go:171","msg":"trace[250288155] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:375; }","duration":"131.509152ms","start":"2026-04-27T16:06:29.116935Z","end":"2026-04-27T16:06:29.248444Z","steps":["trace[250288155] 'agreement among raft nodes before linearized reading' (duration: 131.391949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:29.249248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.285164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-27T16:06:29.249273Z","caller":"traceutil/trace.go:171","msg":"trace[2080869832] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:375; }","duration":"128.311035ms","start":"2026-04-27T16:06:29.120955Z","end":"2026-04-27T16:06:29.249266Z","steps":["trace[2080869832] 'agreement among raft nodes before linearized reading' (duration: 128.260623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:30.253743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.811256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:30.253999Z","caller":"traceutil/trace.go:171","msg":"trace[1668899157] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:386; }","duration":"993.8698ms","start":"2026-04-27T16:06:29.260115Z","end":"2026-04-27T16:06:30.253985Z","steps":["trace[1668899157] 'read index received' (duration: 121.650524ms)","trace[1668899157] 'applied index is now lower than readState.Index' (duration: 872.218336ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:30.254071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.951272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-27T16:06:30.254096Z","caller":"traceutil/trace.go:171","msg":"trace[868998249] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"993.995963ms","start":"2026-04-27T16:06:29.260092Z","end":"2026-04-27T16:06:30.254088Z","steps":["trace[868998249] 'agreement among raft nodes before linearized reading' (duration: 993.936411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:30.254137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:29.260083Z","time spent":"994.045964ms","remote":"127.0.0.1:37142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2924,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-04-27T16:06:30.254149Z","caller":"traceutil/trace.go:171","msg":"trace[2066558024] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"992.297992ms","start":"2026-04-27T16:06:29.261841Z","end":"2026-04-27T16:06:30.254139Z","steps":["trace[2066558024] 'process raft request' (duration: 992.077837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:30.254273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:29.261831Z","time spent":"992.386694ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:06:30.254175Z","caller":"traceutil/trace.go:171","msg":"trace[1945405538] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"992.23723ms","start":"2026-04-27T16:06:29.261925Z","end":"2026-04-27T16:06:30.254163Z","steps":["trace[1945405538] 'process raft request' (duration: 992.031095ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:30.254416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:29.261913Z","time spent":"992.477626ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4447,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:30.25442Z","caller":"traceutil/trace.go:171","msg":"trace[1444983466] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"997.423505ms","start":"2026-04-27T16:06:29.256987Z","end":"2026-04-27T16:06:30.25441Z","steps":["trace[1444983466] 'process raft request' (duration: 124.864211ms)","trace[1444983466] 'compare' (duration: 871.508289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:30.254474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:29.256972Z","time spent":"997.472517ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:30.254019Z","caller":"traceutil/trace.go:171","msg":"trace[531287051] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"994.208228ms","start":"2026-04-27T16:06:29.25979Z","end":"2026-04-27T16:06:30.253998Z","steps":["trace[531287051] 'process raft request' (duration: 994.053995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:30.254875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:29.259761Z","time spent":"995.058918ms","remote":"127.0.0.1:37128","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:35.24804Z","caller":"traceutil/trace.go:171","msg":"trace[1791422593] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"283.444967ms","start":"2026-04-27T16:06:34.964559Z","end":"2026-04-27T16:06:35.248004Z","steps":["trace[1791422593] 'process raft request' (duration: 283.297853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:35.386852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.280397ms","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-04-27T16:06:35.386944Z","caller":"traceutil/trace.go:171","msg":"trace[1731317838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:409; }","duration":"102.43515ms","start":"2026-04-27T16:06:35.284489Z","end":"2026-04-27T16:06:35.386925Z","steps":["trace[1731317838] 'range keys from in-memory index tree' (duration: 102.118973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:35.387053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.099188ms","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-04-27T16:06:35.387169Z","caller":"traceutil/trace.go:171","msg":"trace[873580622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:409; }","duration":"101.256632ms","start":"2026-04-27T16:06:35.285886Z","end":"2026-04-27T16:06:35.387143Z","steps":["trace[873580622] 'range keys from in-memory index tree' (duration: 100.931764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:35.387275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.434201ms","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-04-27T16:06:35.387322Z","caller":"traceutil/trace.go:171","msg":"trace[685918653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"102.525233ms","start":"2026-04-27T16:06:35.284783Z","end":"2026-04-27T16:06:35.387309Z","steps":["trace[685918653] 'range keys from in-memory index tree' (duration: 102.293167ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.186231Z","caller":"traceutil/trace.go:171","msg":"trace[329075845] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"155.770917ms","start":"2026-04-27T16:06:40.030434Z","end":"2026-04-27T16:06:40.186205Z","steps":["trace[329075845] 'process raft request' (duration: 149.514976ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.186668Z","caller":"traceutil/trace.go:171","msg":"trace[453867036] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"155.749756ms","start":"2026-04-27T16:06:40.030867Z","end":"2026-04-27T16:06:40.186616Z","steps":["trace[453867036] 'process raft request' (duration: 155.544931ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.186728Z","caller":"traceutil/trace.go:171","msg":"trace[1516593252] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"155.687176ms","start":"2026-04-27T16:06:40.031022Z","end":"2026-04-27T16:06:40.186709Z","steps":["trace[1516593252] 'process raft request' (duration: 155.479071ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.191573Z","caller":"traceutil/trace.go:171","msg":"trace[1093466085] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"159.009255ms","start":"2026-04-27T16:06:40.032541Z","end":"2026-04-27T16:06:40.19155Z","steps":["trace[1093466085] 'process raft request' (duration: 154.062616ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.192623Z","caller":"traceutil/trace.go:171","msg":"trace[1969026440] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"158.727418ms","start":"2026-04-27T16:06:40.033875Z","end":"2026-04-27T16:06:40.192602Z","steps":["trace[1969026440] 'process raft request' (duration: 157.888108ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:40.193738Z","caller":"traceutil/trace.go:171","msg":"trace[1754280041] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"161.057654ms","start":"2026-04-27T16:06:40.032662Z","end":"2026-04-27T16:06:40.193719Z","steps":["trace[1754280041] 'process raft request' (duration: 158.37177ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:41.021717Z","caller":"traceutil/trace.go:171","msg":"trace[596112703] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"416.984706ms","start":"2026-04-27T16:06:40.604716Z","end":"2026-04-27T16:06:41.021701Z","steps":["trace[596112703] 'process raft request' (duration: 416.888604ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:41.021824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:40.604687Z","time spent":"417.079809ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:06:41.022039Z","caller":"traceutil/trace.go:171","msg":"trace[2090394215] linearizableReadLoop","detail":"{readStateIndex:472; appliedIndex:472; }","duration":"345.731178ms","start":"2026-04-27T16:06:40.676276Z","end":"2026-04-27T16:06:41.022007Z","steps":["trace[2090394215] 'read index received' (duration: 345.688587ms)","trace[2090394215] 'applied index is now lower than readState.Index' (duration: 40.281µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:41.022196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.907582ms","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-04-27T16:06:41.022281Z","caller":"traceutil/trace.go:171","msg":"trace[1321973816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:455; }","duration":"346.015925ms","start":"2026-04-27T16:06:40.676244Z","end":"2026-04-27T16:06:41.02226Z","steps":["trace[1321973816] 'agreement among raft nodes before linearized reading' (duration: 345.869841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:41.022331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:40.676228Z","time spent":"346.090976ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:06:41.336707Z","caller":"traceutil/trace.go:171","msg":"trace[1036192051] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"310.653612ms","start":"2026-04-27T16:06:41.02603Z","end":"2026-04-27T16:06:41.336683Z","steps":["trace[1036192051] 'process raft request' (duration: 309.890103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:41.336846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:06:41.026006Z","time spent":"310.770065ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:06:45.799988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.553666ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:06:45.800113Z","caller":"traceutil/trace.go:171","msg":"trace[930505997] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"261.63074ms","start":"2026-04-27T16:06:45.538461Z","end":"2026-04-27T16:06:45.800092Z","steps":["trace[930505997] 'read index received' (duration: 70.9021ms)","trace[930505997] 'applied index is now lower than readState.Index' (duration: 190.7265ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:45.800145Z","caller":"traceutil/trace.go:171","msg":"trace[1561034617] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"262.781007ms","start":"2026-04-27T16:06:45.537335Z","end":"2026-04-27T16:06:45.800115Z","steps":["trace[1561034617] 'process raft request' (duration: 71.967065ms)","trace[1561034617] 'compare' (duration: 190.271929ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:45.800363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.169432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T16:06:45.800446Z","caller":"traceutil/trace.go:171","msg":"trace[923799391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"252.286175ms","start":"2026-04-27T16:06:45.548143Z","end":"2026-04-27T16:06:45.800429Z","steps":["trace[923799391] 'agreement among raft nodes before linearized reading' (duration: 252.08727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:45.800376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.901906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:06:45.80062Z","caller":"traceutil/trace.go:171","msg":"trace[168009921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"262.135703ms","start":"2026-04-27T16:06:45.538445Z","end":"2026-04-27T16:06:45.80058Z","steps":["trace[168009921] 'agreement among raft nodes before linearized reading' (duration: 261.752273ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.266578Z","caller":"traceutil/trace.go:171","msg":"trace[10817451] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"136.340701ms","start":"2026-04-27T16:06:54.130215Z","end":"2026-04-27T16:06:54.266555Z","steps":["trace[10817451] 'process raft request' (duration: 136.219048ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.473532Z","caller":"traceutil/trace.go:171","msg":"trace[2077274440] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"108.977118ms","start":"2026-04-27T16:06:54.364538Z","end":"2026-04-27T16:06:54.473515Z","steps":["trace[2077274440] 'process raft request' (duration: 108.879215ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.602939Z","caller":"traceutil/trace.go:171","msg":"trace[820943759] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"217.833271ms","start":"2026-04-27T16:06:54.38508Z","end":"2026-04-27T16:06:54.602913Z","steps":["trace[820943759] 'process raft request' (duration: 217.634546ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.603304Z","caller":"traceutil/trace.go:171","msg":"trace[167307734] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"217.642327ms","start":"2026-04-27T16:06:54.385654Z","end":"2026-04-27T16:06:54.603297Z","steps":["trace[167307734] 'process raft request' (duration: 217.186306ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.603633Z","caller":"traceutil/trace.go:171","msg":"trace[1672794042] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"214.259644ms","start":"2026-04-27T16:06:54.389341Z","end":"2026-04-27T16:06:54.6036Z","steps":["trace[1672794042] 'process raft request' (duration: 213.580768ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.603744Z","caller":"traceutil/trace.go:171","msg":"trace[878223974] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"163.85873ms","start":"2026-04-27T16:06:54.439867Z","end":"2026-04-27T16:06:54.603726Z","steps":["trace[878223974] 'process raft request' (duration: 163.396129ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.673114Z","caller":"traceutil/trace.go:171","msg":"trace[314238306] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:523; }","duration":"148.516937ms","start":"2026-04-27T16:06:54.524558Z","end":"2026-04-27T16:06:54.673075Z","steps":["trace[314238306] 'read index received' (duration: 78.288391ms)","trace[314238306] 'applied index is now lower than readState.Index' (duration: 70.227556ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:54.673141Z","caller":"traceutil/trace.go:171","msg":"trace[1868098280] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"193.100281ms","start":"2026-04-27T16:06:54.480005Z","end":"2026-04-27T16:06:54.673105Z","steps":["trace[1868098280] 'process raft request' (duration: 193.000038ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.673154Z","caller":"traceutil/trace.go:171","msg":"trace[1343546778] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"194.71982ms","start":"2026-04-27T16:06:54.4784Z","end":"2026-04-27T16:06:54.673119Z","steps":["trace[1343546778] 'process raft request' (duration: 194.473964ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.673336Z","caller":"traceutil/trace.go:171","msg":"trace[1951087304] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"191.987554ms","start":"2026-04-27T16:06:54.481336Z","end":"2026-04-27T16:06:54.673324Z","steps":["trace[1951087304] 'process raft request' (duration: 191.708457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:54.673505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.932638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:54.673534Z","caller":"traceutil/trace.go:171","msg":"trace[996220001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"149.00913ms","start":"2026-04-27T16:06:54.524515Z","end":"2026-04-27T16:06:54.673524Z","steps":["trace[996220001] 'agreement among raft nodes before linearized reading' (duration: 148.940598ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.784275Z","caller":"traceutil/trace.go:171","msg":"trace[1250943499] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"111.083569ms","start":"2026-04-27T16:06:54.673164Z","end":"2026-04-27T16:06:54.784248Z","steps":["trace[1250943499] 'read index received' (duration: 18.551301ms)","trace[1250943499] 'applied index is now lower than readState.Index' (duration: 92.530778ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:54.784352Z","caller":"traceutil/trace.go:171","msg":"trace[1335360509] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"176.538188ms","start":"2026-04-27T16:06:54.607775Z","end":"2026-04-27T16:06:54.784313Z","steps":["trace[1335360509] 'process raft request' (duration: 83.924098ms)","trace[1335360509] 'compare' (duration: 92.361384ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:06:54.784566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.767416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cm487\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-27T16:06:54.784649Z","caller":"traceutil/trace.go:171","msg":"trace[1550828878] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cm487; range_end:; response_count:1; response_revision:510; }","duration":"174.882618ms","start":"2026-04-27T16:06:54.609745Z","end":"2026-04-27T16:06:54.784628Z","steps":["trace[1550828878] 'agreement among raft nodes before linearized reading' (duration: 174.659542ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.794981Z","caller":"traceutil/trace.go:171","msg":"trace[1408573565] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"117.699689ms","start":"2026-04-27T16:06:54.677258Z","end":"2026-04-27T16:06:54.794958Z","steps":["trace[1408573565] 'process raft request' (duration: 117.545715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:54.795303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.849771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:54.795381Z","caller":"traceutil/trace.go:171","msg":"trace[1551422589] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"119.960033ms","start":"2026-04-27T16:06:54.675403Z","end":"2026-04-27T16:06:54.795363Z","steps":["trace[1551422589] 'agreement among raft nodes before linearized reading' (duration: 119.82709ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.795405Z","caller":"traceutil/trace.go:171","msg":"trace[216074750] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"117.838282ms","start":"2026-04-27T16:06:54.677543Z","end":"2026-04-27T16:06:54.795381Z","steps":["trace[216074750] 'process raft request' (duration: 117.37221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:54.795747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.111506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-04-27T16:06:54.796159Z","caller":"traceutil/trace.go:171","msg":"trace[996669458] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:513; }","duration":"115.506935ms","start":"2026-04-27T16:06:54.680599Z","end":"2026-04-27T16:06:54.796106Z","steps":["trace[996669458] 'agreement among raft nodes before linearized reading' (duration: 114.908541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:54.795796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.520681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-04-27T16:06:54.796612Z","caller":"traceutil/trace.go:171","msg":"trace[853123475] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:513; }","duration":"110.33931ms","start":"2026-04-27T16:06:54.686252Z","end":"2026-04-27T16:06:54.796591Z","steps":["trace[853123475] 'agreement among raft nodes before linearized reading' (duration: 109.364137ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:54.795834Z","caller":"traceutil/trace.go:171","msg":"trace[1927282820] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"116.202023ms","start":"2026-04-27T16:06:54.679624Z","end":"2026-04-27T16:06:54.795826Z","steps":["trace[1927282820] 'process raft request' (duration: 115.509196ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.222605Z","caller":"traceutil/trace.go:171","msg":"trace[1314817215] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"179.563ms","start":"2026-04-27T16:06:56.043008Z","end":"2026-04-27T16:06:56.222571Z","steps":["trace[1314817215] 'read index received' (duration: 179.282404ms)","trace[1314817215] 'applied index is now lower than readState.Index' (duration: 279.196µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:06:56.222631Z","caller":"traceutil/trace.go:171","msg":"trace[1841264480] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"254.012268ms","start":"2026-04-27T16:06:55.96859Z","end":"2026-04-27T16:06:56.222602Z","steps":["trace[1841264480] 'process raft request' (duration: 253.782173ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:56.222897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.335315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-27T16:06:56.222903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.017412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:06:56.222967Z","caller":"traceutil/trace.go:171","msg":"trace[466651101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"179.472929ms","start":"2026-04-27T16:06:56.043477Z","end":"2026-04-27T16:06:56.22295Z","steps":["trace[466651101] 'agreement among raft nodes before linearized reading' (duration: 179.304845ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.222974Z","caller":"traceutil/trace.go:171","msg":"trace[1572880941] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:524; }","duration":"166.151245ms","start":"2026-04-27T16:06:56.056807Z","end":"2026-04-27T16:06:56.222958Z","steps":["trace[1572880941] 'agreement among raft nodes before linearized reading' (duration: 165.988751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:06:56.223441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.423241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:06:56.223521Z","caller":"traceutil/trace.go:171","msg":"trace[1986996020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"180.537624ms","start":"2026-04-27T16:06:56.042969Z","end":"2026-04-27T16:06:56.223506Z","steps":["trace[1986996020] 'agreement among raft nodes before linearized reading' (duration: 179.777716ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.507461Z","caller":"traceutil/trace.go:171","msg":"trace[1918542818] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"280.067613ms","start":"2026-04-27T16:06:56.22737Z","end":"2026-04-27T16:06:56.507438Z","steps":["trace[1918542818] 'process raft request' (duration: 271.868224ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:56.507483Z","caller":"traceutil/trace.go:171","msg":"trace[1768995333] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"279.688454ms","start":"2026-04-27T16:06:56.227761Z","end":"2026-04-27T16:06:56.50745Z","steps":["trace[1768995333] 'process raft request' (duration: 279.376246ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:06:59.337442Z","caller":"traceutil/trace.go:171","msg":"trace[708189619] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"144.803717ms","start":"2026-04-27T16:06:59.19262Z","end":"2026-04-27T16:06:59.337424Z","steps":["trace[708189619] 'process raft request' (duration: 138.770131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:04.022355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268248052826478,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:07:04.077645Z","caller":"traceutil/trace.go:171","msg":"trace[1938271388] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"555.594815ms","start":"2026-04-27T16:07:03.522027Z","end":"2026-04-27T16:07:04.077622Z","steps":["trace[1938271388] 'read index received' (duration: 553.420102ms)","trace[1938271388] 'applied index is now lower than readState.Index' (duration: 2.173563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:04.077973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.904843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:07:04.078002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.67875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T16:07:04.077989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.480202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cm487\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-27T16:07:04.078066Z","caller":"traceutil/trace.go:171","msg":"trace[1273828604] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"306.774591ms","start":"2026-04-27T16:07:03.771274Z","end":"2026-04-27T16:07:04.078048Z","steps":["trace[1273828604] 'agreement among raft nodes before linearized reading' (duration: 306.516945ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:04.078023Z","caller":"traceutil/trace.go:171","msg":"trace[1184310635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"556.022515ms","start":"2026-04-27T16:07:03.521989Z","end":"2026-04-27T16:07:04.078011Z","steps":["trace[1184310635] 'agreement among raft nodes before linearized reading' (duration: 555.760879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:04.078126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:03.771256Z","time spent":"306.857324ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T16:07:04.078183Z","caller":"traceutil/trace.go:171","msg":"trace[638894474] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cm487; range_end:; response_count:1; response_revision:625; }","duration":"217.712118ms","start":"2026-04-27T16:07:03.860443Z","end":"2026-04-27T16:07:04.078155Z","steps":["trace[638894474] 'agreement among raft nodes before linearized reading' (duration: 217.363949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:04.078225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:03.521918Z","time spent":"556.219561ms","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:07:04.078622Z","caller":"traceutil/trace.go:171","msg":"trace[228552342] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"719.050006ms","start":"2026-04-27T16:07:03.359561Z","end":"2026-04-27T16:07:04.078611Z","steps":["trace[228552342] 'process raft request' (duration: 716.547055ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:04.078703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:03.359533Z","time spent":"719.119518ms","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:04.306858Z","caller":"traceutil/trace.go:171","msg":"trace[361356255] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"218.466436ms","start":"2026-04-27T16:07:04.088344Z","end":"2026-04-27T16:07:04.306811Z","steps":["trace[361356255] 'process raft request' (duration: 127.759213ms)","trace[361356255] 'compare' (duration: 90.482488ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:04.334593Z","caller":"traceutil/trace.go:171","msg":"trace[1745331297] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"240.552554ms","start":"2026-04-27T16:07:04.094016Z","end":"2026-04-27T16:07:04.334569Z","steps":["trace[1745331297] 'process raft request' (duration: 240.154714ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:04.334667Z","caller":"traceutil/trace.go:171","msg":"trace[148605709] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"194.73255ms","start":"2026-04-27T16:07:04.139901Z","end":"2026-04-27T16:07:04.334633Z","steps":["trace[148605709] 'process raft request' (duration: 194.359801ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.670845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145098225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:05.670947Z","caller":"traceutil/trace.go:171","msg":"trace[1495087331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.145217458s","start":"2026-04-27T16:07:04.525705Z","end":"2026-04-27T16:07:05.670923Z","steps":["trace[1495087331] 'range keys from in-memory index tree' (duration: 1.145023763s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.670988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:04.525654Z","time spent":"1.145326041s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:07:05.671536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109361117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T16:07:05.671615Z","caller":"traceutil/trace.go:171","msg":"trace[832374119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.109495061s","start":"2026-04-27T16:07:04.562102Z","end":"2026-04-27T16:07:05.671597Z","steps":["trace[832374119] 'range keys from in-memory index tree' (duration: 1.109130261s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.671665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:04.56208Z","time spent":"1.109574572s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:07:05.671588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109324426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:07:05.671905Z","caller":"traceutil/trace.go:171","msg":"trace[1922061189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"1.109630254s","start":"2026-04-27T16:07:04.562169Z","end":"2026-04-27T16:07:05.671799Z","steps":["trace[1922061189] 'range keys from in-memory index tree' (duration: 1.108996228s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.672658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:04.56215Z","time spent":"1.110468354s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:07:05.676336Z","caller":"traceutil/trace.go:171","msg":"trace[1549535185] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"875.907316ms","start":"2026-04-27T16:07:04.800405Z","end":"2026-04-27T16:07:05.676312Z","steps":["trace[1549535185] 'process raft request' (duration: 875.293231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:05.676501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:04.800375Z","time spent":"876.03669ms","remote":"127.0.0.1:36698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:05.682955Z","caller":"traceutil/trace.go:171","msg":"trace[1563497753] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"219.352418ms","start":"2026-04-27T16:07:05.46359Z","end":"2026-04-27T16:07:05.682942Z","steps":["trace[1563497753] 'process raft request' (duration: 218.326623ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:18.585032Z","caller":"traceutil/trace.go:171","msg":"trace[493530055] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"146.278254ms","start":"2026-04-27T16:07:18.438733Z","end":"2026-04-27T16:07:18.585011Z","steps":["trace[493530055] 'read index received' (duration: 145.832413ms)","trace[493530055] 'applied index is now lower than readState.Index' (duration: 444.891µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:18.585162Z","caller":"traceutil/trace.go:171","msg":"trace[1955607071] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"197.938258ms","start":"2026-04-27T16:07:18.387175Z","end":"2026-04-27T16:07:18.585113Z","steps":["trace[1955607071] 'process raft request' (duration: 197.690602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:18.585335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.574201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:07:18.585423Z","caller":"traceutil/trace.go:171","msg":"trace[1640842969] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"146.682083ms","start":"2026-04-27T16:07:18.438727Z","end":"2026-04-27T16:07:18.585409Z","steps":["trace[1640842969] 'agreement among raft nodes before linearized reading' (duration: 146.443737ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:21.643285Z","caller":"traceutil/trace.go:171","msg":"trace[955021161] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"327.871504ms","start":"2026-04-27T16:07:21.315394Z","end":"2026-04-27T16:07:21.643266Z","steps":["trace[955021161] 'read index received' (duration: 327.789112ms)","trace[955021161] 'applied index is now lower than readState.Index' (duration: 81.422µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:21.643341Z","caller":"traceutil/trace.go:171","msg":"trace[1618388063] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"679.210077ms","start":"2026-04-27T16:07:20.964106Z","end":"2026-04-27T16:07:21.643316Z","steps":["trace[1618388063] 'process raft request' (duration: 679.040133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:21.64347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:20.964065Z","time spent":"679.34138ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:07:21.643572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.157861ms","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-04-27T16:07:21.643613Z","caller":"traceutil/trace.go:171","msg":"trace[2100038732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:800; }","duration":"328.257974ms","start":"2026-04-27T16:07:21.315345Z","end":"2026-04-27T16:07:21.643603Z","steps":["trace[2100038732] 'agreement among raft nodes before linearized reading' (duration: 328.019028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:21.643651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:21.315322Z","time spent":"328.321015ms","remote":"127.0.0.1:36884","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-04-27T16:07:21.725335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.869644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:21.72537Z","caller":"traceutil/trace.go:171","msg":"trace[57151678] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"156.86945ms","start":"2026-04-27T16:07:21.568469Z","end":"2026-04-27T16:07:21.725338Z","steps":["trace[57151678] 'process raft request' (duration: 155.511848ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:21.725396Z","caller":"traceutil/trace.go:171","msg":"trace[218998393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"201.966977ms","start":"2026-04-27T16:07:21.523414Z","end":"2026-04-27T16:07:21.725381Z","steps":["trace[218998393] 'agreement among raft nodes before linearized reading' (duration: 201.858494ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:31.941835Z","caller":"traceutil/trace.go:171","msg":"trace[1587675828] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"132.063566ms","start":"2026-04-27T16:07:31.809738Z","end":"2026-04-27T16:07:31.941801Z","steps":["trace[1587675828] 'process raft request' (duration: 130.877798ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:35.532497Z","caller":"traceutil/trace.go:171","msg":"trace[248633520] linearizableReadLoop","detail":"{readStateIndex:966; appliedIndex:965; }","duration":"107.746826ms","start":"2026-04-27T16:07:35.424728Z","end":"2026-04-27T16:07:35.532475Z","steps":["trace[248633520] 'read index received' (duration: 107.587692ms)","trace[248633520] 'applied index is now lower than readState.Index' (duration: 155.944µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:07:35.532537Z","caller":"traceutil/trace.go:171","msg":"trace[624462445] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"108.973066ms","start":"2026-04-27T16:07:35.42355Z","end":"2026-04-27T16:07:35.532523Z","steps":["trace[624462445] 'process raft request' (duration: 108.793931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:35.532678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.93765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-gghg5\" ","response":"range_response_count:1 size:5673"} {"level":"info","ts":"2026-04-27T16:07:35.532713Z","caller":"traceutil/trace.go:171","msg":"trace[2062508263] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-gghg5; range_end:; response_count:1; response_revision:934; }","duration":"108.004712ms","start":"2026-04-27T16:07:35.424698Z","end":"2026-04-27T16:07:35.532703Z","steps":["trace[2062508263] 'agreement among raft nodes before linearized reading' (duration: 107.868669ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:56.041826Z","caller":"traceutil/trace.go:171","msg":"trace[880279585] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"105.557044ms","start":"2026-04-27T16:07:55.936235Z","end":"2026-04-27T16:07:56.041792Z","steps":["trace[880279585] 'process raft request' (duration: 105.357699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.459739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.910834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:07:57.459869Z","caller":"traceutil/trace.go:171","msg":"trace[544641494] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"817.116958ms","start":"2026-04-27T16:07:56.642738Z","end":"2026-04-27T16:07:57.459855Z","steps":["trace[544641494] 'process raft request' (duration: 817.078547ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:57.45984Z","caller":"traceutil/trace.go:171","msg":"trace[1847242549] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.045456613s","start":"2026-04-27T16:07:56.414357Z","end":"2026-04-27T16:07:57.459814Z","steps":["trace[1847242549] 'process raft request' (duration: 61.423537ms)","trace[1847242549] 'compare' (duration: 983.75691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:57.45992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:56.642714Z","time spent":"817.186361ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:57.459954Z","caller":"traceutil/trace.go:171","msg":"trace[1288296958] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"937.284536ms","start":"2026-04-27T16:07:56.522649Z","end":"2026-04-27T16:07:57.459934Z","steps":["trace[1288296958] 'read index received' (duration: 28.981µs)","trace[1288296958] 'applied index is now lower than readState.Index' (duration: 937.253495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:57.46009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.4372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:57.460128Z","caller":"traceutil/trace.go:171","msg":"trace[688767982] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"937.505882ms","start":"2026-04-27T16:07:56.522613Z","end":"2026-04-27T16:07:57.460119Z","steps":["trace[688767982] 'agreement among raft nodes before linearized reading' (duration: 937.4428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.460164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.666925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-27T16:07:57.460187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.762973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:57.460226Z","caller":"traceutil/trace.go:171","msg":"trace[421067678] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1016; }","duration":"261.864405ms","start":"2026-04-27T16:07:57.198354Z","end":"2026-04-27T16:07:57.460218Z","steps":["trace[421067678] 'agreement among raft nodes before linearized reading' (duration: 261.804764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.460314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.544939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:07:57.460193Z","caller":"traceutil/trace.go:171","msg":"trace[257205789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1016; }","duration":"646.740616ms","start":"2026-04-27T16:07:56.813445Z","end":"2026-04-27T16:07:57.460186Z","steps":["trace[257205789] 'agreement among raft nodes before linearized reading' (duration: 646.655654ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:57.460351Z","caller":"traceutil/trace.go:171","msg":"trace[1660848451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1016; }","duration":"638.64078ms","start":"2026-04-27T16:07:56.8217Z","end":"2026-04-27T16:07:57.460341Z","steps":["trace[1660848451] 'agreement among raft nodes before linearized reading' (duration: 638.495497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.460383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:56.813424Z","time spent":"646.952222ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:07:57.460388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:56.821684Z","time spent":"638.696312ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:07:57.460162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:56.522597Z","time spent":"937.559143ms","remote":"127.0.0.1:36632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:07:57.459984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:56.414337Z","time spent":"1.045575646s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:57.845746Z","caller":"traceutil/trace.go:171","msg":"trace[260044516] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"381.049909ms","start":"2026-04-27T16:07:57.464675Z","end":"2026-04-27T16:07:57.845725Z","steps":["trace[260044516] 'process raft request' (duration: 380.927316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.845877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:57.464655Z","time spent":"381.153941ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:57.846163Z","caller":"traceutil/trace.go:171","msg":"trace[231617830] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"343.398518ms","start":"2026-04-27T16:07:57.502753Z","end":"2026-04-27T16:07:57.846152Z","steps":["trace[231617830] 'read index received' (duration: 342.798264ms)","trace[231617830] 'applied index is now lower than readState.Index' (duration: 599.464µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:07:57.846292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.538032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:07:57.84633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.178511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:07:57.846353Z","caller":"traceutil/trace.go:171","msg":"trace[569046341] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"321.235142ms","start":"2026-04-27T16:07:57.52511Z","end":"2026-04-27T16:07:57.846345Z","steps":["trace[569046341] 'agreement among raft nodes before linearized reading' (duration: 321.194621ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:07:57.846358Z","caller":"traceutil/trace.go:171","msg":"trace[2129772337] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"381.131591ms","start":"2026-04-27T16:07:57.465217Z","end":"2026-04-27T16:07:57.846349Z","steps":["trace[2129772337] 'process raft request' (duration: 380.890505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.846372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:57.525089Z","time spent":"321.278133ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:07:57.846407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:57.465199Z","time spent":"381.178312ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:07:57.846329Z","caller":"traceutil/trace.go:171","msg":"trace[456012245] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1018; }","duration":"343.604934ms","start":"2026-04-27T16:07:57.502714Z","end":"2026-04-27T16:07:57.846319Z","steps":["trace[456012245] 'agreement among raft nodes before linearized reading' (duration: 343.546452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:07:57.846482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:07:57.502698Z","time spent":"343.776607ms","remote":"127.0.0.1:36828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:16.402696Z","caller":"traceutil/trace.go:171","msg":"trace[275515472] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"121.08758ms","start":"2026-04-27T16:08:16.281546Z","end":"2026-04-27T16:08:16.402633Z","steps":["trace[275515472] 'process raft request' (duration: 120.910195ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:20.304922Z","caller":"traceutil/trace.go:171","msg":"trace[873583973] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"133.670034ms","start":"2026-04-27T16:08:20.171207Z","end":"2026-04-27T16:08:20.304877Z","steps":["trace[873583973] 'process raft request' (duration: 133.50951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:21.098251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.729212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:21.098371Z","caller":"traceutil/trace.go:171","msg":"trace[1180528730] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"663.358159ms","start":"2026-04-27T16:08:20.434992Z","end":"2026-04-27T16:08:21.098351Z","steps":["trace[1180528730] 'read index received' (duration: 320.446453ms)","trace[1180528730] 'applied index is now lower than readState.Index' (duration: 342.909686ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:21.098638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.626336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T16:08:21.098692Z","caller":"traceutil/trace.go:171","msg":"trace[1523002557] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1092; }","duration":"663.721638ms","start":"2026-04-27T16:08:20.434958Z","end":"2026-04-27T16:08:21.09868Z","steps":["trace[1523002557] 'agreement among raft nodes before linearized reading' (duration: 663.520033ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:21.098716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:20.434942Z","time spent":"663.769089ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T16:08:21.098843Z","caller":"traceutil/trace.go:171","msg":"trace[629450123] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"789.628215ms","start":"2026-04-27T16:08:20.309188Z","end":"2026-04-27T16:08:21.098816Z","steps":["trace[629450123] 'process raft request' (duration: 446.213486ms)","trace[629450123] 'compare' (duration: 342.176689ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:21.098959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:20.309164Z","time spent":"789.740337ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:21.098954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.348796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:21.099184Z","caller":"traceutil/trace.go:171","msg":"trace[300706953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"574.674004ms","start":"2026-04-27T16:08:20.524406Z","end":"2026-04-27T16:08:21.09908Z","steps":["trace[300706953] 'agreement among raft nodes before linearized reading' (duration: 574.269284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:21.099444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:20.524382Z","time spent":"575.046852ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:08:22.25154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:21.87117Z","time spent":"380.360711ms","remote":"127.0.0.1:53692","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T16:08:22.251656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.994201ms","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-04-27T16:08:22.251807Z","caller":"traceutil/trace.go:171","msg":"trace[1145471216] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1092; }","duration":"537.160925ms","start":"2026-04-27T16:08:21.714624Z","end":"2026-04-27T16:08:22.251785Z","steps":["trace[1145471216] 'range keys from in-memory index tree' (duration: 536.974561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:22.25213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.422169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:22.252202Z","caller":"traceutil/trace.go:171","msg":"trace[2060545195] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"1.148502157s","start":"2026-04-27T16:08:21.103676Z","end":"2026-04-27T16:08:22.252178Z","steps":["trace[2060545195] 'process raft request' (duration: 687.948205ms)","trace[2060545195] 'compare' (duration: 459.929527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:22.252245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:21.103654Z","time spent":"1.148571148s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:22.32304Z","caller":"traceutil/trace.go:171","msg":"trace[1888816024] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1135; }","duration":"295.815787ms","start":"2026-04-27T16:08:22.027204Z","end":"2026-04-27T16:08:22.32302Z","steps":["trace[1888816024] 'read index received' (duration: 295.679024ms)","trace[1888816024] 'applied index is now lower than readState.Index' (duration: 136.083µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:22.323154Z","caller":"traceutil/trace.go:171","msg":"trace[1422454150] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"735.73885ms","start":"2026-04-27T16:08:21.587368Z","end":"2026-04-27T16:08:22.323107Z","steps":["trace[1422454150] 'process raft request' (duration: 735.534175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:22.323384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.321231ms","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-04-27T16:08:22.323384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.189477ms","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-04-27T16:08:22.323424Z","caller":"traceutil/trace.go:171","msg":"trace[434928925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1094; }","duration":"263.363482ms","start":"2026-04-27T16:08:22.06005Z","end":"2026-04-27T16:08:22.323414Z","steps":["trace[434928925] 'agreement among raft nodes before linearized reading' (duration: 263.250329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:22.323475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:21.587344Z","time spent":"735.919574ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:22.323429Z","caller":"traceutil/trace.go:171","msg":"trace[806553527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"296.281878ms","start":"2026-04-27T16:08:22.027136Z","end":"2026-04-27T16:08:22.323418Z","steps":["trace[806553527] 'agreement among raft nodes before linearized reading' (duration: 295.998751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:22.986748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.585193ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:22.986834Z","caller":"traceutil/trace.go:171","msg":"trace[1858695411] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"658.162464ms","start":"2026-04-27T16:08:22.328657Z","end":"2026-04-27T16:08:22.98682Z","steps":["trace[1858695411] 'process raft request' (duration: 240.434367ms)","trace[1858695411] 'compare' (duration: 417.279166ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:22.986878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:22.328635Z","time spent":"658.223395ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:23.018862Z","caller":"traceutil/trace.go:171","msg":"trace[1980965158] linearizableReadLoop","detail":"{readStateIndex:1139; appliedIndex:1136; }","duration":"580.202077ms","start":"2026-04-27T16:08:22.438642Z","end":"2026-04-27T16:08:23.018844Z","steps":["trace[1980965158] 'read index received' (duration: 130.414905ms)","trace[1980965158] 'applied index is now lower than readState.Index' (duration: 449.786152ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:23.018955Z","caller":"traceutil/trace.go:171","msg":"trace[1710148974] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"690.263401ms","start":"2026-04-27T16:08:22.328684Z","end":"2026-04-27T16:08:23.018947Z","steps":["trace[1710148974] 'process raft request' (duration: 689.977744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.01904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:22.328661Z","time spent":"690.315642ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:23.019142Z","caller":"traceutil/trace.go:171","msg":"trace[1508489208] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"690.170268ms","start":"2026-04-27T16:08:22.328953Z","end":"2026-04-27T16:08:23.019123Z","steps":["trace[1508489208] 'process raft request' (duration: 689.860831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.019269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.603217ms","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":"warn","ts":"2026-04-27T16:08:23.019282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:22.328945Z","time spent":"690.24964ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:23.019371Z","caller":"traceutil/trace.go:171","msg":"trace[721702494] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1097; }","duration":"580.752711ms","start":"2026-04-27T16:08:22.438595Z","end":"2026-04-27T16:08:23.019348Z","steps":["trace[721702494] 'agreement among raft nodes before linearized reading' (duration: 580.548696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.019431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:22.438576Z","time spent":"580.842893ms","remote":"127.0.0.1:36974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:23.519425Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268248052827503,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:08:23.644587Z","caller":"traceutil/trace.go:171","msg":"trace[999337851] linearizableReadLoop","detail":"{readStateIndex:1140; appliedIndex:1139; }","duration":"625.649617ms","start":"2026-04-27T16:08:23.01892Z","end":"2026-04-27T16:08:23.64457Z","steps":["trace[999337851] 'read index received' (duration: 625.485713ms)","trace[999337851] 'applied index is now lower than readState.Index' (duration: 163.114µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:23.644823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.120179702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:23.644895Z","caller":"traceutil/trace.go:171","msg":"trace[2065643336] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"1.120296925s","start":"2026-04-27T16:08:22.524579Z","end":"2026-04-27T16:08:23.644876Z","steps":["trace[2065643336] 'agreement among raft nodes before linearized reading' (duration: 1.120172762s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.644971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:22.524559Z","time spent":"1.120399297s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:08:23.644964Z","caller":"traceutil/trace.go:171","msg":"trace[312855061] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"640.893356ms","start":"2026-04-27T16:08:23.004024Z","end":"2026-04-27T16:08:23.644917Z","steps":["trace[312855061] 'process raft request' (duration: 640.424344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.645028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.001406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:23.645079Z","caller":"traceutil/trace.go:171","msg":"trace[1849334071] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1098; }","duration":"545.088708ms","start":"2026-04-27T16:08:23.099982Z","end":"2026-04-27T16:08:23.64507Z","steps":["trace[1849334071] 'agreement among raft nodes before linearized reading' (duration: 545.000976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.645106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:23.099964Z","time spent":"545.135409ms","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:23.644945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.157251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T16:08:23.645147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.102212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:08:23.645253Z","caller":"traceutil/trace.go:171","msg":"trace[1018278767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"541.253125ms","start":"2026-04-27T16:08:23.103989Z","end":"2026-04-27T16:08:23.645242Z","steps":["trace[1018278767] 'agreement among raft nodes before linearized reading' (duration: 540.935508ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:23.645231Z","caller":"traceutil/trace.go:171","msg":"trace[2110415087] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:1098; }","duration":"586.483069ms","start":"2026-04-27T16:08:23.058713Z","end":"2026-04-27T16:08:23.645196Z","steps":["trace[2110415087] 'agreement among raft nodes before linearized reading' (duration: 586.08036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.645186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:23.004003Z","time spent":"641.07057ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":671,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:23.645343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:23.058699Z","time spent":"586.603642ms","remote":"127.0.0.1:36664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.30\" "} {"level":"warn","ts":"2026-04-27T16:08:23.645269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.632146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:23.645499Z","caller":"traceutil/trace.go:171","msg":"trace[1884302506] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1098; }","duration":"291.900382ms","start":"2026-04-27T16:08:23.353578Z","end":"2026-04-27T16:08:23.645478Z","steps":["trace[1884302506] 'agreement among raft nodes before linearized reading' (duration: 291.463452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:23.645892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:23.103976Z","time spent":"541.304976ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T16:08:23.905114Z","caller":"traceutil/trace.go:171","msg":"trace[1843337785] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1140; }","duration":"251.71746ms","start":"2026-04-27T16:08:23.653376Z","end":"2026-04-27T16:08:23.905093Z","steps":["trace[1843337785] 'read index received' (duration: 141.178866ms)","trace[1843337785] 'applied index is now lower than readState.Index' (duration: 110.537594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:23.905208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.819682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:23.905229Z","caller":"traceutil/trace.go:171","msg":"trace[1832609259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1099; }","duration":"251.890664ms","start":"2026-04-27T16:08:23.653332Z","end":"2026-04-27T16:08:23.905222Z","steps":["trace[1832609259] 'agreement among raft nodes before linearized reading' (duration: 251.835103ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:23.905433Z","caller":"traceutil/trace.go:171","msg":"trace[504717126] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"255.394829ms","start":"2026-04-27T16:08:23.650016Z","end":"2026-04-27T16:08:23.90541Z","steps":["trace[504717126] 'process raft request' (duration: 254.956118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:24.343941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.833187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:24.344083Z","caller":"traceutil/trace.go:171","msg":"trace[2069543827] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1100; }","duration":"321.97904ms","start":"2026-04-27T16:08:24.022019Z","end":"2026-04-27T16:08:24.343998Z","steps":["trace[2069543827] 'count revisions from in-memory index tree' (duration: 321.726474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:24.344127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:24.021994Z","time spent":"322.121933ms","remote":"127.0.0.1:36820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":43,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:24.49404Z","caller":"traceutil/trace.go:171","msg":"trace[1027965456] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"164.013958ms","start":"2026-04-27T16:08:24.330006Z","end":"2026-04-27T16:08:24.49402Z","steps":["trace[1027965456] 'process raft request' (duration: 163.880124ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:24.673904Z","caller":"traceutil/trace.go:171","msg":"trace[1598092944] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1143; }","duration":"308.564296ms","start":"2026-04-27T16:08:24.36532Z","end":"2026-04-27T16:08:24.673884Z","steps":["trace[1598092944] 'read index received' (duration: 128.569501ms)","trace[1598092944] 'applied index is now lower than readState.Index' (duration: 179.993735ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:24.674012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.687928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:24.674035Z","caller":"traceutil/trace.go:171","msg":"trace[1180794978] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1102; }","duration":"308.76507ms","start":"2026-04-27T16:08:24.365262Z","end":"2026-04-27T16:08:24.674027Z","steps":["trace[1180794978] 'agreement among raft nodes before linearized reading' (duration: 308.699209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:24.674058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:24.365236Z","time spent":"308.814852ms","remote":"127.0.0.1:49184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-04-27T16:08:24.67405Z","caller":"traceutil/trace.go:171","msg":"trace[1719958096] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"325.410273ms","start":"2026-04-27T16:08:24.348603Z","end":"2026-04-27T16:08:24.674013Z","steps":["trace[1719958096] 'process raft request' (duration: 231.607853ms)","trace[1719958096] 'compare' (duration: 93.594355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:24.674199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:24.348586Z","time spent":"325.534716ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:25.595189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.455503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:25.59527Z","caller":"traceutil/trace.go:171","msg":"trace[1149946501] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1102; }","duration":"201.577136ms","start":"2026-04-27T16:08:25.393676Z","end":"2026-04-27T16:08:25.595253Z","steps":["trace[1149946501] 'count revisions from in-memory index tree' (duration: 201.392082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:25.595287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.31103ms","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-04-27T16:08:25.595362Z","caller":"traceutil/trace.go:171","msg":"trace[284904729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"604.422612ms","start":"2026-04-27T16:08:24.990912Z","end":"2026-04-27T16:08:25.595335Z","steps":["trace[284904729] 'range keys from in-memory index tree' (duration: 604.120105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:25.595373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.599938ms","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-04-27T16:08:25.595451Z","caller":"traceutil/trace.go:171","msg":"trace[248504601] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1102; }","duration":"796.035918ms","start":"2026-04-27T16:08:24.799391Z","end":"2026-04-27T16:08:25.595426Z","steps":["trace[248504601] 'count revisions from in-memory index tree' (duration: 795.507526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:25.595502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:24.799366Z","time spent":"796.124211ms","remote":"127.0.0.1:37116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:25.595614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.65758ms","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-04-27T16:08:25.595638Z","caller":"traceutil/trace.go:171","msg":"trace[250436877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"571.714081ms","start":"2026-04-27T16:08:25.023918Z","end":"2026-04-27T16:08:25.595632Z","steps":["trace[250436877] 'range keys from in-memory index tree' (duration: 571.532777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:25.595657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:25.023898Z","time spent":"571.754522ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:08:25.595402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:24.990892Z","time spent":"604.503315ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:08:25.595919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.958137ms","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-04-27T16:08:25.595959Z","caller":"traceutil/trace.go:171","msg":"trace[342244471] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"572.031759ms","start":"2026-04-27T16:08:25.023918Z","end":"2026-04-27T16:08:25.595949Z","steps":["trace[342244471] 'range keys from in-memory index tree' (duration: 571.768782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:25.595987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:25.023897Z","time spent":"572.08163ms","remote":"127.0.0.1:36884","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-04-27T16:08:26.098357Z","caller":"traceutil/trace.go:171","msg":"trace[1173779624] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"497.928457ms","start":"2026-04-27T16:08:25.600404Z","end":"2026-04-27T16:08:26.098333Z","steps":["trace[1173779624] 'process raft request' (duration: 497.685481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:26.098519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:25.600348Z","time spent":"498.079471ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:26.112834Z","caller":"traceutil/trace.go:171","msg":"trace[677894722] linearizableReadLoop","detail":"{readStateIndex:1148; appliedIndex:1145; }","duration":"203.096074ms","start":"2026-04-27T16:08:25.909715Z","end":"2026-04-27T16:08:26.112811Z","steps":["trace[677894722] 'read index received' (duration: 188.405349ms)","trace[677894722] 'applied index is now lower than readState.Index' (duration: 14.688965ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:26.112866Z","caller":"traceutil/trace.go:171","msg":"trace[81016900] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"512.190932ms","start":"2026-04-27T16:08:25.600649Z","end":"2026-04-27T16:08:26.11284Z","steps":["trace[81016900] 'process raft request' (duration: 512.007297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:26.113035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.308039ms","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-04-27T16:08:26.113074Z","caller":"traceutil/trace.go:171","msg":"trace[453259961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1105; }","duration":"203.402221ms","start":"2026-04-27T16:08:25.909661Z","end":"2026-04-27T16:08:26.113063Z","steps":["trace[453259961] 'agreement among raft nodes before linearized reading' (duration: 203.242807ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:26.113055Z","caller":"traceutil/trace.go:171","msg":"trace[1803305000] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"512.331016ms","start":"2026-04-27T16:08:25.600711Z","end":"2026-04-27T16:08:26.113042Z","steps":["trace[1803305000] 'process raft request' (duration: 512.061259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:26.113169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:25.600706Z","time spent":"512.419968ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:26.113002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:25.600632Z","time spent":"512.295475ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:27.023107Z","caller":"traceutil/trace.go:171","msg":"trace[1413215531] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"230.147598ms","start":"2026-04-27T16:08:26.792932Z","end":"2026-04-27T16:08:27.023079Z","steps":["trace[1413215531] 'read index received' (duration: 229.903042ms)","trace[1413215531] 'applied index is now lower than readState.Index' (duration: 242.886µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:27.023195Z","caller":"traceutil/trace.go:171","msg":"trace[1388249970] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"244.211268ms","start":"2026-04-27T16:08:26.778944Z","end":"2026-04-27T16:08:27.023156Z","steps":["trace[1388249970] 'process raft request' (duration: 243.980653ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:27.02324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.290601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:27.024193Z","caller":"traceutil/trace.go:171","msg":"trace[1966239053] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1108; }","duration":"231.273645ms","start":"2026-04-27T16:08:26.7929Z","end":"2026-04-27T16:08:27.024173Z","steps":["trace[1966239053] 'agreement among raft nodes before linearized reading' (duration: 230.281671ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:35.253192Z","caller":"traceutil/trace.go:171","msg":"trace[1321316076] linearizableReadLoop","detail":"{readStateIndex:1180; appliedIndex:1179; }","duration":"171.157721ms","start":"2026-04-27T16:08:35.082012Z","end":"2026-04-27T16:08:35.25317Z","steps":["trace[1321316076] 'read index received' (duration: 170.977577ms)","trace[1321316076] 'applied index is now lower than readState.Index' (duration: 179.234µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:35.253356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.317505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:35.253336Z","caller":"traceutil/trace.go:171","msg":"trace[475073373] transaction","detail":"{read_only:false; response_revision:1135; number_of_response:1; }","duration":"177.947835ms","start":"2026-04-27T16:08:35.075351Z","end":"2026-04-27T16:08:35.253299Z","steps":["trace[475073373] 'process raft request' (duration: 177.684309ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:35.253402Z","caller":"traceutil/trace.go:171","msg":"trace[666441649] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1135; }","duration":"171.434268ms","start":"2026-04-27T16:08:35.081955Z","end":"2026-04-27T16:08:35.253389Z","steps":["trace[666441649] 'agreement among raft nodes before linearized reading' (duration: 171.314775ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:36.5607Z","caller":"traceutil/trace.go:171","msg":"trace[629679556] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"321.844925ms","start":"2026-04-27T16:08:36.238834Z","end":"2026-04-27T16:08:36.560679Z","steps":["trace[629679556] 'process raft request' (duration: 321.62299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.56085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.238824Z","time spent":"321.951529ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:36.560891Z","caller":"traceutil/trace.go:171","msg":"trace[218528003] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"321.983809ms","start":"2026-04-27T16:08:36.238894Z","end":"2026-04-27T16:08:36.560877Z","steps":["trace[218528003] 'process raft request' (duration: 321.743303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:36.56096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.238873Z","time spent":"322.059372ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:36.937947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.360356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:36.938077Z","caller":"traceutil/trace.go:171","msg":"trace[803077962] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"360.600915ms","start":"2026-04-27T16:08:36.577457Z","end":"2026-04-27T16:08:36.938058Z","steps":["trace[803077962] 'process raft request' (duration: 122.033863ms)","trace[803077962] 'compare' (duration: 238.237754ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:36.938134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:36.577425Z","time spent":"360.683036ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:37.398576Z","caller":"traceutil/trace.go:171","msg":"trace[1580921959] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"136.427561ms","start":"2026-04-27T16:08:37.262112Z","end":"2026-04-27T16:08:37.39854Z","steps":["trace[1580921959] 'process raft request' (duration: 136.239586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:38.520274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.96822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:08:38.520424Z","caller":"traceutil/trace.go:171","msg":"trace[1443669892] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"226.059799ms","start":"2026-04-27T16:08:38.294341Z","end":"2026-04-27T16:08:38.520401Z","steps":["trace[1443669892] 'process raft request' (duration: 108.866264ms)","trace[1443669892] 'compare' (duration: 116.876098ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:38.802101Z","caller":"traceutil/trace.go:171","msg":"trace[517163809] linearizableReadLoop","detail":"{readStateIndex:1189; appliedIndex:1188; }","duration":"236.123323ms","start":"2026-04-27T16:08:38.565825Z","end":"2026-04-27T16:08:38.801949Z","steps":["trace[517163809] 'read index received' (duration: 221.383016ms)","trace[517163809] 'applied index is now lower than readState.Index' (duration: 14.738887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:38.80251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.633565ms","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-04-27T16:08:38.802506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.669196ms","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-04-27T16:08:38.802563Z","caller":"traceutil/trace.go:171","msg":"trace[626742895] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"236.768389ms","start":"2026-04-27T16:08:38.565781Z","end":"2026-04-27T16:08:38.80255Z","steps":["trace[626742895] 'agreement among raft nodes before linearized reading' (duration: 236.557364ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:38.802657Z","caller":"traceutil/trace.go:171","msg":"trace[1355033779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1144; }","duration":"236.80806ms","start":"2026-04-27T16:08:38.565781Z","end":"2026-04-27T16:08:38.80259Z","steps":["trace[1355033779] 'agreement among raft nodes before linearized reading' (duration: 236.502362ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:38.802332Z","caller":"traceutil/trace.go:171","msg":"trace[878948399] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"260.457092ms","start":"2026-04-27T16:08:38.541823Z","end":"2026-04-27T16:08:38.80228Z","steps":["trace[878948399] 'process raft request' (duration: 245.452529ms)","trace[878948399] 'compare' (duration: 14.298786ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:39.359063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.318627ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-27T16:08:39.359427Z","caller":"traceutil/trace.go:171","msg":"trace[1233834025] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"415.285888ms","start":"2026-04-27T16:08:38.944108Z","end":"2026-04-27T16:08:39.359394Z","steps":["trace[1233834025] 'process raft request' (duration: 415.057722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:39.359595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:38.944086Z","time spent":"415.420721ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:39.687058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.720422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:08:39.68713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.909288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:39.68722Z","caller":"traceutil/trace.go:171","msg":"trace[371740330] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1147; }","duration":"143.029051ms","start":"2026-04-27T16:08:39.54417Z","end":"2026-04-27T16:08:39.687199Z","steps":["trace[371740330] 'count revisions from in-memory index tree' (duration: 142.810286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:39.68732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.640605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:39.687388Z","caller":"traceutil/trace.go:171","msg":"trace[1600231974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1147; }","duration":"162.759488ms","start":"2026-04-27T16:08:39.524611Z","end":"2026-04-27T16:08:39.687371Z","steps":["trace[1600231974] 'range keys from in-memory index tree' (duration: 162.556193ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:39.687508Z","caller":"traceutil/trace.go:171","msg":"trace[689456139] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1147; }","duration":"324.223645ms","start":"2026-04-27T16:08:39.363262Z","end":"2026-04-27T16:08:39.687486Z","steps":["trace[689456139] 'count revisions from in-memory index tree' (duration: 323.63597ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:39.687654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:39.363244Z","time spent":"324.388198ms","remote":"127.0.0.1:38180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:39.688006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.697958ms","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-04-27T16:08:39.68813Z","caller":"traceutil/trace.go:171","msg":"trace[463747587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1147; }","duration":"284.851282ms","start":"2026-04-27T16:08:39.403258Z","end":"2026-04-27T16:08:39.688109Z","steps":["trace[463747587] 'range keys from in-memory index tree' (duration: 283.97982ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:39.882942Z","caller":"traceutil/trace.go:171","msg":"trace[733108507] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"189.890724ms","start":"2026-04-27T16:08:39.693022Z","end":"2026-04-27T16:08:39.882912Z","steps":["trace[733108507] 'process raft request' (duration: 189.662028ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:40.645782Z","caller":"traceutil/trace.go:171","msg":"trace[168158839] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"105.389259ms","start":"2026-04-27T16:08:40.540373Z","end":"2026-04-27T16:08:40.645762Z","steps":["trace[168158839] 'process raft request' (duration: 105.238776ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:43.125976Z","caller":"traceutil/trace.go:171","msg":"trace[1768697837] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"133.405618ms","start":"2026-04-27T16:08:42.992554Z","end":"2026-04-27T16:08:43.125959Z","steps":["trace[1768697837] 'process raft request' (duration: 132.833524ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:08:45.566347Z","caller":"traceutil/trace.go:171","msg":"trace[2017716947] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"182.182118ms","start":"2026-04-27T16:08:45.384118Z","end":"2026-04-27T16:08:45.5663Z","steps":["trace[2017716947] 'process raft request' (duration: 181.87669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:47.479879Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268248052827798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:08:47.980454Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268248052827798,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T16:08:48.089427Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.39873081s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T16:08:48.090018Z","caller":"traceutil/trace.go:171","msg":"trace[1482220254] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.399356695s","start":"2026-04-27T16:08:46.69063Z","end":"2026-04-27T16:08:48.089987Z","steps":["trace[1482220254] 'process raft request' (duration: 1.399101499s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.090184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:46.690619Z","time spent":"1.399483528s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:08:48.903157Z","caller":"traceutil/trace.go:171","msg":"trace[645930595] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"1.924014758s","start":"2026-04-27T16:08:46.979116Z","end":"2026-04-27T16:08:48.90313Z","steps":["trace[645930595] 'read index received' (duration: 1.110681491s)","trace[645930595] 'applied index is now lower than readState.Index' (duration: 813.330827ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:08:48.903315Z","caller":"traceutil/trace.go:171","msg":"trace[1658482710] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.331104926s","start":"2026-04-27T16:08:47.572193Z","end":"2026-04-27T16:08:48.903298Z","steps":["trace[1658482710] 'process raft request' (duration: 1.325676404s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.903429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:47.572171Z","time spent":"1.331177267s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:08:48.929744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.950614083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:08:48.929805Z","caller":"traceutil/trace.go:171","msg":"trace[1803963287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1222; }","duration":"1.950718776s","start":"2026-04-27T16:08:46.979069Z","end":"2026-04-27T16:08:48.929788Z","steps":["trace[1803963287] 'agreement among raft nodes before linearized reading' (duration: 1.924616774s)","trace[1803963287] 'range keys from in-memory index tree' (duration: 25.945038ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:08:48.929831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:46.979049Z","time spent":"1.950777357s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-27T16:08:48.929901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.944531006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:08:48.929967Z","caller":"traceutil/trace.go:171","msg":"trace[449941786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1223; }","duration":"1.944631618s","start":"2026-04-27T16:08:46.985319Z","end":"2026-04-27T16:08:48.929951Z","steps":["trace[449941786] 'agreement among raft nodes before linearized reading' (duration: 1.944455533s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.930005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:46.985305Z","time spent":"1.94469236s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:48.930094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"728.173158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:08:48.930112Z","caller":"traceutil/trace.go:171","msg":"trace[1850392169] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1223; }","duration":"728.225599ms","start":"2026-04-27T16:08:48.201882Z","end":"2026-04-27T16:08:48.930108Z","steps":["trace[1850392169] 'agreement among raft nodes before linearized reading' (duration: 728.185648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.930129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:48.20186Z","time spent":"728.26388ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-04-27T16:08:48.930243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.962147ms","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-04-27T16:08:48.930255Z","caller":"traceutil/trace.go:171","msg":"trace[1283855088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1223; }","duration":"412.002598ms","start":"2026-04-27T16:08:48.518248Z","end":"2026-04-27T16:08:48.930251Z","steps":["trace[1283855088] 'agreement among raft nodes before linearized reading' (duration: 411.961567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.930273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:48.518232Z","time spent":"412.037579ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:08:48.930267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008286095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T16:08:48.930299Z","caller":"traceutil/trace.go:171","msg":"trace[1916524813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1223; }","duration":"1.008359557s","start":"2026-04-27T16:08:47.921931Z","end":"2026-04-27T16:08:48.93029Z","steps":["trace[1916524813] 'agreement among raft nodes before linearized reading' (duration: 1.008259454s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.930326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:47.921915Z","time spent":"1.008404807s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:08:48.930402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409476761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:08:48.930423Z","caller":"traceutil/trace.go:171","msg":"trace[350870002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"1.409519112s","start":"2026-04-27T16:08:47.520897Z","end":"2026-04-27T16:08:48.930416Z","steps":["trace[350870002] 'agreement among raft nodes before linearized reading' (duration: 1.409486141s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:08:48.93045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:08:47.520885Z","time spent":"1.409559043s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:09:02.961023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.931368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:02.961133Z","caller":"traceutil/trace.go:171","msg":"trace[1721003961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"436.084572ms","start":"2026-04-27T16:09:02.525023Z","end":"2026-04-27T16:09:02.961107Z","steps":["trace[1721003961] 'range keys from in-memory index tree' (duration: 435.815496ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:02.961181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:02.525003Z","time spent":"436.169213ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:09:04.365811Z","caller":"traceutil/trace.go:171","msg":"trace[1934470498] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"103.843251ms","start":"2026-04-27T16:09:04.261903Z","end":"2026-04-27T16:09:04.365746Z","steps":["trace[1934470498] 'process raft request' (duration: 103.430002ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:04.609022Z","caller":"traceutil/trace.go:171","msg":"trace[1572470681] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"239.057788ms","start":"2026-04-27T16:09:04.369931Z","end":"2026-04-27T16:09:04.608988Z","steps":["trace[1572470681] 'process raft request' (duration: 193.119256ms)","trace[1572470681] 'compare' (duration: 45.40844ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:04.966412Z","caller":"traceutil/trace.go:171","msg":"trace[1361974592] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"251.594058ms","start":"2026-04-27T16:09:04.714791Z","end":"2026-04-27T16:09:04.966385Z","steps":["trace[1361974592] 'process raft request' (duration: 251.419314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:05.201218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.142285ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:05.201351Z","caller":"traceutil/trace.go:171","msg":"trace[649339266] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"153.564739ms","start":"2026-04-27T16:09:05.047766Z","end":"2026-04-27T16:09:05.201331Z","steps":["trace[649339266] 'read index received' (duration: 50.174447ms)","trace[649339266] 'applied index is now lower than readState.Index' (duration: 103.388161ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:05.201376Z","caller":"traceutil/trace.go:171","msg":"trace[282735888] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"189.106166ms","start":"2026-04-27T16:09:05.012249Z","end":"2026-04-27T16:09:05.201355Z","steps":["trace[282735888] 'process raft request' (duration: 85.719205ms)","trace[282735888] 'compare' (duration: 102.966272ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:05.201618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.838134ms","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-04-27T16:09:05.201677Z","caller":"traceutil/trace.go:171","msg":"trace[1061087529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"153.940677ms","start":"2026-04-27T16:09:05.047719Z","end":"2026-04-27T16:09:05.20166Z","steps":["trace[1061087529] 'agreement among raft nodes before linearized reading' (duration: 153.748913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:05.201795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.76074ms","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-04-27T16:09:05.201841Z","caller":"traceutil/trace.go:171","msg":"trace[1148527747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"103.854622ms","start":"2026-04-27T16:09:05.097975Z","end":"2026-04-27T16:09:05.20183Z","steps":["trace[1148527747] 'agreement among raft nodes before linearized reading' (duration: 103.704108ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:05.330064Z","caller":"traceutil/trace.go:171","msg":"trace[717306487] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"123.78051ms","start":"2026-04-27T16:09:05.206265Z","end":"2026-04-27T16:09:05.330046Z","steps":["trace[717306487] 'process raft request' (duration: 123.702138ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:05.330188Z","caller":"traceutil/trace.go:171","msg":"trace[1392073771] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"124.68361ms","start":"2026-04-27T16:09:05.205497Z","end":"2026-04-27T16:09:05.33018Z","steps":["trace[1392073771] 'process raft request' (duration: 117.724974ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:05.330246Z","caller":"traceutil/trace.go:171","msg":"trace[1613471691] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"123.913332ms","start":"2026-04-27T16:09:05.206321Z","end":"2026-04-27T16:09:05.330234Z","steps":["trace[1613471691] 'process raft request' (duration: 123.700947ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:06.732822Z","caller":"traceutil/trace.go:171","msg":"trace[1515463965] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"115.024493ms","start":"2026-04-27T16:09:06.617764Z","end":"2026-04-27T16:09:06.732788Z","steps":["trace[1515463965] 'process raft request' (duration: 114.843139ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:07.439215Z","caller":"traceutil/trace.go:171","msg":"trace[11389405] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"100.994018ms","start":"2026-04-27T16:09:07.33819Z","end":"2026-04-27T16:09:07.439184Z","steps":["trace[11389405] 'process raft request' (duration: 87.180748ms)","trace[11389405] 'compare' (duration: 13.426311ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:07.613975Z","caller":"traceutil/trace.go:171","msg":"trace[1384525381] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"275.304211ms","start":"2026-04-27T16:09:07.338654Z","end":"2026-04-27T16:09:07.613958Z","steps":["trace[1384525381] 'process raft request' (duration: 275.097317ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:07.614115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.23885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hrxxr\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-04-27T16:09:07.614149Z","caller":"traceutil/trace.go:171","msg":"trace[123934152] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hrxxr; range_end:; response_count:1; response_revision:1300; }","duration":"275.303481ms","start":"2026-04-27T16:09:07.338839Z","end":"2026-04-27T16:09:07.614142Z","steps":["trace[123934152] 'agreement among raft nodes before linearized reading' (duration: 275.165218ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:07.613958Z","caller":"traceutil/trace.go:171","msg":"trace[1033690358] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1350; }","duration":"275.072697ms","start":"2026-04-27T16:09:07.338862Z","end":"2026-04-27T16:09:07.613935Z","steps":["trace[1033690358] 'read index received' (duration: 86.529983ms)","trace[1033690358] 'applied index is now lower than readState.Index' (duration: 188.541804ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:07.614334Z","caller":"traceutil/trace.go:171","msg":"trace[1986571765] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"275.127388ms","start":"2026-04-27T16:09:07.3392Z","end":"2026-04-27T16:09:07.614328Z","steps":["trace[1986571765] 'process raft request' (duration: 274.695668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.261166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.036735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:08.26124Z","caller":"traceutil/trace.go:171","msg":"trace[1645716266] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"341.204682ms","start":"2026-04-27T16:09:07.920022Z","end":"2026-04-27T16:09:08.261227Z","steps":["trace[1645716266] 'read index received' (duration: 56.482µs)","trace[1645716266] 'applied index is now lower than readState.Index' (duration: 341.14664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:08.261372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.356155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-27T16:09:08.261398Z","caller":"traceutil/trace.go:171","msg":"trace[908205713] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1301; }","duration":"341.381825ms","start":"2026-04-27T16:09:07.920008Z","end":"2026-04-27T16:09:08.26139Z","steps":["trace[908205713] 'agreement among raft nodes before linearized reading' (duration: 341.253943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.261424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.919955Z","time spent":"341.462107ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-27T16:09:08.261572Z","caller":"traceutil/trace.go:171","msg":"trace[416130844] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"637.491914ms","start":"2026-04-27T16:09:07.624071Z","end":"2026-04-27T16:09:08.261563Z","steps":["trace[416130844] 'process raft request' (duration: 244.004009ms)","trace[416130844] 'compare' (duration: 392.961143ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:08.261621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:07.624052Z","time spent":"637.544075ms","remote":"127.0.0.1:36794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:08.567095Z","caller":"traceutil/trace.go:171","msg":"trace[761085970] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"301.129921ms","start":"2026-04-27T16:09:08.265941Z","end":"2026-04-27T16:09:08.567071Z","steps":["trace[761085970] 'process raft request' (duration: 301.009228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.567233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.265924Z","time spent":"301.237493ms","remote":"127.0.0.1:36888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:08.836641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.41251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:08.836882Z","caller":"traceutil/trace.go:171","msg":"trace[1458998850] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"570.313135ms","start":"2026-04-27T16:09:08.266533Z","end":"2026-04-27T16:09:08.836846Z","steps":["trace[1458998850] 'process raft request' (duration: 406.594769ms)","trace[1458998850] 'compare' (duration: 163.150943ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:08.837043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.266505Z","time spent":"570.449888ms","remote":"127.0.0.1:36772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:08.837139Z","caller":"traceutil/trace.go:171","msg":"trace[543172216] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"567.278397ms","start":"2026-04-27T16:09:08.269846Z","end":"2026-04-27T16:09:08.837124Z","steps":["trace[543172216] 'process raft request' (duration: 566.880808ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:08.837189Z","caller":"traceutil/trace.go:171","msg":"trace[1401212368] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"562.930319ms","start":"2026-04-27T16:09:08.27423Z","end":"2026-04-27T16:09:08.83716Z","steps":["trace[1401212368] 'process raft request' (duration: 562.567401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.837208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.269831Z","time spent":"567.342498ms","remote":"127.0.0.1:36786","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5456,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:08.837292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.274218Z","time spent":"563.014191ms","remote":"127.0.0.1:37142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:08.842269Z","caller":"traceutil/trace.go:171","msg":"trace[1438086490] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1354; }","duration":"319.031853ms","start":"2026-04-27T16:09:08.523216Z","end":"2026-04-27T16:09:08.842248Z","steps":["trace[1438086490] 'read index received' (duration: 43.756762ms)","trace[1438086490] 'applied index is now lower than readState.Index' (duration: 275.273331ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:08.842432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.206967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:08.842464Z","caller":"traceutil/trace.go:171","msg":"trace[1666471085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"319.286529ms","start":"2026-04-27T16:09:08.523167Z","end":"2026-04-27T16:09:08.842454Z","steps":["trace[1666471085] 'agreement among raft nodes before linearized reading' (duration: 319.216587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.842459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.074852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T16:09:08.842491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:08.52314Z","time spent":"319.345209ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T16:09:08.842507Z","caller":"traceutil/trace.go:171","msg":"trace[199686838] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1305; }","duration":"261.166544ms","start":"2026-04-27T16:09:08.581327Z","end":"2026-04-27T16:09:08.842494Z","steps":["trace[199686838] 'agreement among raft nodes before linearized reading' (duration: 261.059672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:08.842637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.005738ms","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-04-27T16:09:08.842701Z","caller":"traceutil/trace.go:171","msg":"trace[1103648122] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1305; }","duration":"105.10784ms","start":"2026-04-27T16:09:08.73758Z","end":"2026-04-27T16:09:08.842688Z","steps":["trace[1103648122] 'agreement among raft nodes before linearized reading' (duration: 104.948586ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:09.426242Z","caller":"traceutil/trace.go:171","msg":"trace[2097034640] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"125.385555ms","start":"2026-04-27T16:09:09.300822Z","end":"2026-04-27T16:09:09.426207Z","steps":["trace[2097034640] 'process raft request' (duration: 91.887892ms)","trace[2097034640] 'compare' (duration: 33.35419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:09.965574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.205069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:09.965679Z","caller":"traceutil/trace.go:171","msg":"trace[529127240] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"440.837669ms","start":"2026-04-27T16:09:09.524826Z","end":"2026-04-27T16:09:09.965664Z","steps":["trace[529127240] 'read index received' (duration: 194.540218ms)","trace[529127240] 'applied index is now lower than readState.Index' (duration: 246.295761ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:09.965739Z","caller":"traceutil/trace.go:171","msg":"trace[20834922] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"519.779881ms","start":"2026-04-27T16:09:09.445936Z","end":"2026-04-27T16:09:09.965716Z","steps":["trace[20834922] 'process raft request' (duration: 273.347598ms)","trace[20834922] 'compare' (duration: 246.017924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:09.965779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.954611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:09.965804Z","caller":"traceutil/trace.go:171","msg":"trace[51107301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"441.040114ms","start":"2026-04-27T16:09:09.524755Z","end":"2026-04-27T16:09:09.965795Z","steps":["trace[51107301] 'agreement among raft nodes before linearized reading' (duration: 440.990573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.965838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.52472Z","time spent":"441.107544ms","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:09:09.965845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.44591Z","time spent":"519.877874ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:09.966061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.121147ms","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-04-27T16:09:09.966124Z","caller":"traceutil/trace.go:171","msg":"trace[1731989122] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"348.200668ms","start":"2026-04-27T16:09:09.61788Z","end":"2026-04-27T16:09:09.966081Z","steps":["trace[1731989122] 'agreement among raft nodes before linearized reading' (duration: 348.098296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.966147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.61785Z","time spent":"348.291881ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T16:09:09.966158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.680807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-27T16:09:09.966201Z","caller":"traceutil/trace.go:171","msg":"trace[1631202951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"347.757489ms","start":"2026-04-27T16:09:09.618432Z","end":"2026-04-27T16:09:09.96619Z","steps":["trace[1631202951] 'agreement among raft nodes before linearized reading' (duration: 347.584505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:09.966235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.618406Z","time spent":"347.81937ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T16:09:10.864109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.297243ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:10.86437Z","caller":"traceutil/trace.go:171","msg":"trace[1679995142] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"894.399333ms","start":"2026-04-27T16:09:09.969939Z","end":"2026-04-27T16:09:10.864338Z","steps":["trace[1679995142] 'process raft request' (duration: 316.749142ms)","trace[1679995142] 'compare' (duration: 577.211071ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:10.86454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.96992Z","time spent":"894.547787ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:10.86451Z","caller":"traceutil/trace.go:171","msg":"trace[1704708093] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"889.539794ms","start":"2026-04-27T16:09:09.974942Z","end":"2026-04-27T16:09:10.864482Z","steps":["trace[1704708093] 'process raft request' (duration: 889.280619ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:10.864667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:09.974934Z","time spent":"889.654397ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:11.069557Z","caller":"traceutil/trace.go:171","msg":"trace[153267044] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"187.091191ms","start":"2026-04-27T16:09:10.882446Z","end":"2026-04-27T16:09:11.069538Z","steps":["trace[153267044] 'process raft request' (duration: 186.957828ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:11.948959Z","caller":"traceutil/trace.go:171","msg":"trace[1356994272] linearizableReadLoop","detail":"{readStateIndex:1372; appliedIndex:1371; }","duration":"141.922557ms","start":"2026-04-27T16:09:11.80701Z","end":"2026-04-27T16:09:11.948933Z","steps":["trace[1356994272] 'read index received' (duration: 141.718223ms)","trace[1356994272] 'applied index is now lower than readState.Index' (duration: 203.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:11.948984Z","caller":"traceutil/trace.go:171","msg":"trace[1136797839] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"232.745406ms","start":"2026-04-27T16:09:11.716216Z","end":"2026-04-27T16:09:11.948961Z","steps":["trace[1136797839] 'process raft request' (duration: 232.581572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:11.949208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.165642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:11.94927Z","caller":"traceutil/trace.go:171","msg":"trace[1990596828] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1318; }","duration":"142.294475ms","start":"2026-04-27T16:09:11.806962Z","end":"2026-04-27T16:09:11.949257Z","steps":["trace[1990596828] 'agreement among raft nodes before linearized reading' (duration: 142.06565ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:12.249809Z","caller":"traceutil/trace.go:171","msg":"trace[566591882] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"279.578586ms","start":"2026-04-27T16:09:11.970207Z","end":"2026-04-27T16:09:12.249786Z","steps":["trace[566591882] 'read index received' (duration: 279.146227ms)","trace[566591882] 'applied index is now lower than readState.Index' (duration: 431.159µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:12.250112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.888663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-27T16:09:12.250154Z","caller":"traceutil/trace.go:171","msg":"trace[1168803667] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1319; }","duration":"279.983965ms","start":"2026-04-27T16:09:11.970159Z","end":"2026-04-27T16:09:12.250143Z","steps":["trace[1168803667] 'agreement among raft nodes before linearized reading' (duration: 279.721309ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:12.25021Z","caller":"traceutil/trace.go:171","msg":"trace[1004888325] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"297.022638ms","start":"2026-04-27T16:09:11.953156Z","end":"2026-04-27T16:09:12.250179Z","steps":["trace[1004888325] 'process raft request' (duration: 296.136898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:12.516139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.576232ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:12.516307Z","caller":"traceutil/trace.go:171","msg":"trace[2007654480] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"349.436026ms","start":"2026-04-27T16:09:12.166822Z","end":"2026-04-27T16:09:12.516258Z","steps":["trace[2007654480] 'process raft request' (duration: 230.676419ms)","trace[2007654480] 'compare' (duration: 118.45037ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:12.51644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:12.166799Z","time spent":"349.584759ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T16:09:12.516578Z","caller":"traceutil/trace.go:171","msg":"trace[1456478493] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1373; }","duration":"261.991392ms","start":"2026-04-27T16:09:12.254556Z","end":"2026-04-27T16:09:12.516547Z","steps":["trace[1456478493] 'read index received' (duration: 143.004631ms)","trace[1456478493] 'applied index is now lower than readState.Index' (duration: 118.982171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:12.516924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.347301ms","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-04-27T16:09:12.516776Z","caller":"traceutil/trace.go:171","msg":"trace[54905317] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"260.608261ms","start":"2026-04-27T16:09:12.256158Z","end":"2026-04-27T16:09:12.516767Z","steps":["trace[54905317] 'process raft request' (duration: 260.09391ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:12.516966Z","caller":"traceutil/trace.go:171","msg":"trace[1767614267] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1322; }","duration":"262.442062ms","start":"2026-04-27T16:09:12.254515Z","end":"2026-04-27T16:09:12.516957Z","steps":["trace[1767614267] 'agreement among raft nodes before linearized reading' (duration: 262.257548ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:12.51639Z","caller":"traceutil/trace.go:171","msg":"trace[1758630096] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"257.689796ms","start":"2026-04-27T16:09:12.258682Z","end":"2026-04-27T16:09:12.516372Z","steps":["trace[1758630096] 'process raft request' (duration: 257.656485ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:12.758374Z","caller":"traceutil/trace.go:171","msg":"trace[456968820] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"218.636569ms","start":"2026-04-27T16:09:12.539719Z","end":"2026-04-27T16:09:12.758356Z","steps":["trace[456968820] 'read index received' (duration: 218.491255ms)","trace[456968820] 'applied index is now lower than readState.Index' (duration: 144.604µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:12.758466Z","caller":"traceutil/trace.go:171","msg":"trace[1989907088] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"232.152653ms","start":"2026-04-27T16:09:12.526291Z","end":"2026-04-27T16:09:12.758444Z","steps":["trace[1989907088] 'process raft request' (duration: 231.942218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:12.758544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.801612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:12.758685Z","caller":"traceutil/trace.go:171","msg":"trace[278204878] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"218.992837ms","start":"2026-04-27T16:09:12.539686Z","end":"2026-04-27T16:09:12.758679Z","steps":["trace[278204878] 'agreement among raft nodes before linearized reading' (duration: 218.786512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:19.704197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.295339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:09:19.70468Z","caller":"traceutil/trace.go:171","msg":"trace[1839388970] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"464.163862ms","start":"2026-04-27T16:09:19.240496Z","end":"2026-04-27T16:09:19.70466Z","steps":["trace[1839388970] 'process raft request' (duration: 463.880125ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:09:19.704712Z","caller":"traceutil/trace.go:171","msg":"trace[253168888] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1437; }","duration":"506.713817ms","start":"2026-04-27T16:09:19.197978Z","end":"2026-04-27T16:09:19.704691Z","steps":["trace[253168888] 'read index received' (duration: 38.201µs)","trace[253168888] 'applied index is now lower than readState.Index' (duration: 506.673116ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:19.704865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.240467Z","time spent":"464.303665ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:19.704907Z","caller":"traceutil/trace.go:171","msg":"trace[683141257] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"563.186576ms","start":"2026-04-27T16:09:19.141647Z","end":"2026-04-27T16:09:19.704834Z","steps":["trace[683141257] 'process raft request' (duration: 15.14442ms)","trace[683141257] 'compare' (duration: 546.742466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:09:19.705035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.141627Z","time spent":"563.320209ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":492,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:19.70516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.171998ms","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-04-27T16:09:19.705225Z","caller":"traceutil/trace.go:171","msg":"trace[722420137] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1383; }","duration":"507.26718ms","start":"2026-04-27T16:09:19.19794Z","end":"2026-04-27T16:09:19.705207Z","steps":["trace[722420137] 'agreement among raft nodes before linearized reading' (duration: 507.105176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:19.705276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.197921Z","time spent":"507.343161ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-27T16:09:20.205122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18338268248052828131,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T16:09:20.642401Z","caller":"traceutil/trace.go:171","msg":"trace[677260379] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"1.33324681s","start":"2026-04-27T16:09:19.30912Z","end":"2026-04-27T16:09:20.642367Z","steps":["trace[677260379] 'process raft request' (duration: 1.332295048s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.64254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.309079Z","time spent":"1.333390703s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T16:09:20.643577Z","caller":"traceutil/trace.go:171","msg":"trace[72579602] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1439; }","duration":"938.763921ms","start":"2026-04-27T16:09:19.704794Z","end":"2026-04-27T16:09:20.643557Z","steps":["trace[72579602] 'read index received' (duration: 936.631663ms)","trace[72579602] 'applied index is now lower than readState.Index' (duration: 2.131278ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:09:20.643786Z","caller":"traceutil/trace.go:171","msg":"trace[1285895982] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"934.302332ms","start":"2026-04-27T16:09:19.709314Z","end":"2026-04-27T16:09:20.643616Z","steps":["trace[1285895982] 'process raft request' (duration: 934.091327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.643857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10484442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-27T16:09:20.643894Z","caller":"traceutil/trace.go:171","msg":"trace[1838887811] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1385; }","duration":"1.104915322s","start":"2026-04-27T16:09:19.538968Z","end":"2026-04-27T16:09:20.643883Z","steps":["trace[1838887811] 'agreement among raft nodes before linearized reading' (duration: 1.104752298s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.644267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.709291Z","time spent":"934.573867ms","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T16:09:20.644354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.047495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:122495"} {"level":"info","ts":"2026-04-27T16:09:20.644439Z","caller":"traceutil/trace.go:171","msg":"trace[1129449530] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1385; }","duration":"775.178158ms","start":"2026-04-27T16:09:19.869237Z","end":"2026-04-27T16:09:20.644415Z","steps":["trace[1129449530] 'agreement among raft nodes before linearized reading' (duration: 774.713318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.64474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.440424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-04-27T16:09:20.644754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.869216Z","time spent":"775.292201ms","remote":"127.0.0.1:36670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":122518,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-27T16:09:20.644818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.119870997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T16:09:20.644847Z","caller":"traceutil/trace.go:171","msg":"trace[1940047866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1385; }","duration":"1.11996807s","start":"2026-04-27T16:09:19.52487Z","end":"2026-04-27T16:09:20.644838Z","steps":["trace[1940047866] 'agreement among raft nodes before linearized reading' (duration: 1.119922719s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.644865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.524847Z","time spent":"1.12001394s","remote":"127.0.0.1:36628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T16:09:20.644789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.538956Z","time spent":"1.105818001s","remote":"127.0.0.1:36884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-27T16:09:20.644795Z","caller":"traceutil/trace.go:171","msg":"trace[1146202305] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1385; }","duration":"775.533817ms","start":"2026-04-27T16:09:19.869248Z","end":"2026-04-27T16:09:20.644781Z","steps":["trace[1146202305] 'agreement among raft nodes before linearized reading' (duration: 775.417754ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:09:20.645032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T16:09:19.869234Z","time spent":"775.790702ms","remote":"127.0.0.1:36674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-04-27T16:09:33.113729Z","caller":"traceutil/trace.go:171","msg":"trace[307536701] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"250.31294ms","start":"2026-04-27T16:09:32.86339Z","end":"2026-04-27T16:09:33.113703Z","steps":["trace[307536701] 'process raft request' (duration: 242.73658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:00.959755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.514215ms","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-04-27T16:10:00.959822Z","caller":"traceutil/trace.go:171","msg":"trace[50358587] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1568; }","duration":"148.634078ms","start":"2026-04-27T16:10:00.811173Z","end":"2026-04-27T16:10:00.959807Z","steps":["trace[50358587] 'range keys from in-memory index tree' (duration: 148.29147ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:10.006706Z","caller":"traceutil/trace.go:171","msg":"trace[1450382642] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"138.135127ms","start":"2026-04-27T16:10:09.86851Z","end":"2026-04-27T16:10:10.006645Z","steps":["trace[1450382642] 'process raft request' (duration: 137.787418ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.239004Z","caller":"traceutil/trace.go:171","msg":"trace[381180658] linearizableReadLoop","detail":"{readStateIndex:1690; appliedIndex:1689; }","duration":"196.92336ms","start":"2026-04-27T16:10:13.042047Z","end":"2026-04-27T16:10:13.238971Z","steps":["trace[381180658] 'read index received' (duration: 196.777907ms)","trace[381180658] 'applied index is now lower than readState.Index' (duration: 144.633µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:13.239065Z","caller":"traceutil/trace.go:171","msg":"trace[359437438] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"237.802166ms","start":"2026-04-27T16:10:13.001249Z","end":"2026-04-27T16:10:13.239051Z","steps":["trace[359437438] 'process raft request' (duration: 237.616152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:13.239233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.179496ms","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-04-27T16:10:13.23929Z","caller":"traceutil/trace.go:171","msg":"trace[626327336] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1624; }","duration":"197.260518ms","start":"2026-04-27T16:10:13.041997Z","end":"2026-04-27T16:10:13.239257Z","steps":["trace[626327336] 'agreement among raft nodes before linearized reading' (duration: 197.101914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:13.23938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.406425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-27T16:10:13.239438Z","caller":"traceutil/trace.go:171","msg":"trace[1364791644] range","detail":"{range_begin:/registry/masterleases/199.19.213.30; range_end:; response_count:1; response_revision:1624; }","duration":"172.495577ms","start":"2026-04-27T16:10:13.066934Z","end":"2026-04-27T16:10:13.239429Z","steps":["trace[1364791644] 'agreement among raft nodes before linearized reading' (duration: 172.361594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:13.239794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.864902ms","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-04-27T16:10:13.239903Z","caller":"traceutil/trace.go:171","msg":"trace[2121564332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1624; }","duration":"141.031746ms","start":"2026-04-27T16:10:13.098843Z","end":"2026-04-27T16:10:13.239874Z","steps":["trace[2121564332] 'agreement among raft nodes before linearized reading' (duration: 140.653827ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:13.240378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.941119ms","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-04-27T16:10:13.240543Z","caller":"traceutil/trace.go:171","msg":"trace[1004179856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1624; }","duration":"137.164263ms","start":"2026-04-27T16:10:13.103358Z","end":"2026-04-27T16:10:13.240523Z","steps":["trace[1004179856] 'agreement among raft nodes before linearized reading' (duration: 136.035806ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.425287Z","caller":"traceutil/trace.go:171","msg":"trace[2079353886] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"179.638558ms","start":"2026-04-27T16:10:13.245622Z","end":"2026-04-27T16:10:13.425261Z","steps":["trace[2079353886] 'process raft request' (duration: 137.902191ms)","trace[2079353886] 'compare' (duration: 41.473351ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:13.42544Z","caller":"traceutil/trace.go:171","msg":"trace[422652154] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"179.71663ms","start":"2026-04-27T16:10:13.245703Z","end":"2026-04-27T16:10:13.42542Z","steps":["trace[422652154] 'process raft request' (duration: 179.489524ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.425798Z","caller":"traceutil/trace.go:171","msg":"trace[908530484] transaction","detail":"{read_only:false; response_revision:1629; number_of_response:1; }","duration":"138.424734ms","start":"2026-04-27T16:10:13.28736Z","end":"2026-04-27T16:10:13.425785Z","steps":["trace[908530484] 'process raft request' (duration: 138.289901ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.4258Z","caller":"traceutil/trace.go:171","msg":"trace[2106513569] transaction","detail":"{read_only:false; response_revision:1628; number_of_response:1; }","duration":"180.048257ms","start":"2026-04-27T16:10:13.245729Z","end":"2026-04-27T16:10:13.425777Z","steps":["trace[2106513569] 'process raft request' (duration: 179.632287ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.429145Z","caller":"traceutil/trace.go:171","msg":"trace[537343093] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"183.468779ms","start":"2026-04-27T16:10:13.245656Z","end":"2026-04-27T16:10:13.429125Z","steps":["trace[537343093] 'process raft request' (duration: 179.483644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:10:13.668929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.003538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:10:13.669061Z","caller":"traceutil/trace.go:171","msg":"trace[1929411134] linearizableReadLoop","detail":"{readStateIndex:1698; appliedIndex:1696; }","duration":"238.653136ms","start":"2026-04-27T16:10:13.430395Z","end":"2026-04-27T16:10:13.669048Z","steps":["trace[1929411134] 'read index received' (duration: 117.479185ms)","trace[1929411134] 'applied index is now lower than readState.Index' (duration: 121.173131ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:13.669102Z","caller":"traceutil/trace.go:171","msg":"trace[2047264489] transaction","detail":"{read_only:false; response_revision:1631; number_of_response:1; }","duration":"239.65048ms","start":"2026-04-27T16:10:13.429431Z","end":"2026-04-27T16:10:13.669081Z","steps":["trace[2047264489] 'process raft request' (duration: 239.575908ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.669102Z","caller":"traceutil/trace.go:171","msg":"trace[378219494] transaction","detail":"{read_only:false; response_revision:1630; number_of_response:1; }","duration":"240.031789ms","start":"2026-04-27T16:10:13.429042Z","end":"2026-04-27T16:10:13.669074Z","steps":["trace[378219494] 'process raft request' (duration: 118.819986ms)","trace[378219494] 'compare' (duration: 120.907986ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T16:10:13.669322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.917232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-27T16:10:13.669322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.836605ms","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-04-27T16:10:13.669352Z","caller":"traceutil/trace.go:171","msg":"trace[1676549654] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1631; }","duration":"238.970443ms","start":"2026-04-27T16:10:13.430372Z","end":"2026-04-27T16:10:13.669342Z","steps":["trace[1676549654] 'agreement among raft nodes before linearized reading' (duration: 238.852351ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:13.669353Z","caller":"traceutil/trace.go:171","msg":"trace[740668233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1631; }","duration":"167.899147ms","start":"2026-04-27T16:10:13.501445Z","end":"2026-04-27T16:10:13.669344Z","steps":["trace[740668233] 'agreement among raft nodes before linearized reading' (duration: 167.789585ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:10:15.578021Z","caller":"traceutil/trace.go:171","msg":"trace[650371091] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"111.645135ms","start":"2026-04-27T16:10:15.46635Z","end":"2026-04-27T16:10:15.577995Z","steps":["trace[650371091] 'process raft request' (duration: 98.216324ms)","trace[650371091] 'compare' (duration: 13.271887ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:10:15.578261Z","caller":"traceutil/trace.go:171","msg":"trace[1001437627] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"111.84468ms","start":"2026-04-27T16:10:15.466404Z","end":"2026-04-27T16:10:15.578249Z","steps":["trace[1001437627] 'process raft request' (duration: 111.549513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:12:13.244391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.68016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T16:12:13.244728Z","caller":"traceutil/trace.go:171","msg":"trace[2025655045] transaction","detail":"{read_only:false; response_revision:2176; number_of_response:1; }","duration":"166.957448ms","start":"2026-04-27T16:12:13.077713Z","end":"2026-04-27T16:12:13.244671Z","steps":["trace[2025655045] 'process raft request' (duration: 33.849728ms)","trace[2025655045] 'compare' (duration: 132.429424ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:12:34.441881Z","caller":"traceutil/trace.go:171","msg":"trace[174133666] linearizableReadLoop","detail":"{readStateIndex:2390; appliedIndex:2389; }","duration":"125.172323ms","start":"2026-04-27T16:12:34.316691Z","end":"2026-04-27T16:12:34.441864Z","steps":["trace[174133666] 'read index received' (duration: 125.01126ms)","trace[174133666] 'applied index is now lower than readState.Index' (duration: 159.973µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T16:12:34.441998Z","caller":"traceutil/trace.go:171","msg":"trace[568547365] transaction","detail":"{read_only:false; response_revision:2294; number_of_response:1; }","duration":"163.511077ms","start":"2026-04-27T16:12:34.278478Z","end":"2026-04-27T16:12:34.441989Z","steps":["trace[568547365] 'process raft request' (duration: 163.243021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T16:12:34.442137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.439139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T16:12:34.442167Z","caller":"traceutil/trace.go:171","msg":"trace[101005564] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:2294; }","duration":"125.520981ms","start":"2026-04-27T16:12:34.316637Z","end":"2026-04-27T16:12:34.442158Z","steps":["trace[101005564] 'agreement among raft nodes before linearized reading' (duration: 125.45071ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:12:40.214485Z","caller":"traceutil/trace.go:171","msg":"trace[2021220849] transaction","detail":"{read_only:false; response_revision:2326; number_of_response:1; }","duration":"249.733571ms","start":"2026-04-27T16:12:39.964731Z","end":"2026-04-27T16:12:40.214465Z","steps":["trace[2021220849] 'process raft request' (duration: 249.588918ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T16:13:18.433447Z","caller":"traceutil/trace.go:171","msg":"trace[175786443] transaction","detail":"{read_only:false; response_revision:2509; number_of_response:1; }","duration":"104.895871ms","start":"2026-04-27T16:13:18.328529Z","end":"2026-04-27T16:13:18.433425Z","steps":["trace[175786443] 'process raft request' (duration: 104.706026ms)"],"step_count":1}