{"level":"warn","ts":"2026-04-22T16:25:40.917699Z","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-22T16:25:40.917789Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.171: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.171:2380","--initial-cluster=instance=https://199.19.213.171:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.171:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.171: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-22T16:25:40.917871Z","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-22T16:25:40.917888Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-04-22T16:25:40.917911Z","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-22T16:25:40.918693Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"]} {"level":"info","ts":"2026-04-22T16:25:40.918791Z","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.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.171: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-22T16:25:40.922818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.769718ms"} {"level":"info","ts":"2026-04-22T16:25:41.061052Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"837e89131cc6bd81","cluster-id":"8af92e75ab8f3e69"} {"level":"info","ts":"2026-04-22T16:25:41.061219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:25:41.061261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 0"} {"level":"info","ts":"2026-04-22T16:25:41.061325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 837e89131cc6bd81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-22T16:25:41.061351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 1"} {"level":"info","ts":"2026-04-22T16:25:41.061445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"warn","ts":"2026-04-22T16:25:41.199031Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:25:41.207234Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:25:41.208882Z","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-22T16:25:41.211413Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"837e89131cc6bd81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-22T16:25:41.220393Z","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-22T16:25:41.220681Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"837e89131cc6bd81","initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-22T16:25:41.220725Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:25:41.220783Z","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-22T16:25:41.220837Z","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-22T16:25:41.220849Z","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-22T16:25:41.220973Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-04-22T16:25:41.220992Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-04-22T16:25:41.222262Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"837e89131cc6bd81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-22T16:25:41.222797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"info","ts":"2026-04-22T16:25:41.222876Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","added-peer-id":"837e89131cc6bd81","added-peer-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-04-22T16:25:41.365052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:25:41.365142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:25:41.365167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgPreVoteResp from 837e89131cc6bd81 at term 1"} {"level":"info","ts":"2026-04-22T16:25:41.365186Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:25:41.365195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgVoteResp from 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-04-22T16:25:41.365208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became leader at term 2"} {"level":"info","ts":"2026-04-22T16:25:41.36522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 837e89131cc6bd81 elected leader 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-04-22T16:25:41.36642Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"837e89131cc6bd81","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.171:2379]}","request-path":"/0/members/837e89131cc6bd81/attributes","cluster-id":"8af92e75ab8f3e69","publish-timeout":"7s"} {"level":"info","ts":"2026-04-22T16:25:41.366468Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:25:41.366763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:25:41.366767Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:25:41.366894Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:25:41.366962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:25:41.369177Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:25:41.369934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.171:2379"} {"level":"info","ts":"2026-04-22T16:25:41.374493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:25:41.374609Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:25:41.374644Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:25:43.011232Z","caller":"traceutil/trace.go:171","msg":"trace[682717517] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"147.382963ms","start":"2026-04-22T16:25:42.863789Z","end":"2026-04-22T16:25:43.011172Z","steps":["trace[682717517] 'process raft request' (duration: 137.997302ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:43.011209Z","caller":"traceutil/trace.go:171","msg":"trace[721668308] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"101.414737ms","start":"2026-04-22T16:25:42.909759Z","end":"2026-04-22T16:25:43.011174Z","steps":["trace[721668308] 'process raft request' (duration: 101.22416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:43.3472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.050064ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-22T16:25:43.347355Z","caller":"traceutil/trace.go:171","msg":"trace[993287059] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"203.262459ms","start":"2026-04-22T16:25:43.144076Z","end":"2026-04-22T16:25:43.347338Z","steps":["trace[993287059] 'read index received' (duration: 47.872µs)","trace[993287059] 'applied index is now lower than readState.Index' (duration: 203.213267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:43.347426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.336551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:25:43.347414Z","caller":"traceutil/trace.go:171","msg":"trace[464778814] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"291.687031ms","start":"2026-04-22T16:25:43.055689Z","end":"2026-04-22T16:25:43.347376Z","steps":["trace[464778814] 'process raft request' (duration: 63.485318ms)","trace[464778814] 'compare' (duration: 226.868578ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:43.347465Z","caller":"traceutil/trace.go:171","msg":"trace[156932669] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:72; }","duration":"203.381433ms","start":"2026-04-22T16:25:43.144072Z","end":"2026-04-22T16:25:43.347453Z","steps":["trace[156932669] 'agreement among raft nodes before linearized reading' (duration: 203.31394ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:43.629829Z","caller":"traceutil/trace.go:171","msg":"trace[267313470] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"278.571688ms","start":"2026-04-22T16:25:43.351224Z","end":"2026-04-22T16:25:43.629796Z","steps":["trace[267313470] 'read index received' (duration: 230.82222ms)","trace[267313470] 'applied index is now lower than readState.Index' (duration: 47.747908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:43.63004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.792856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/global-default\" ","response":"range_response_count:1 size:1133"} {"level":"info","ts":"2026-04-22T16:25:43.630131Z","caller":"traceutil/trace.go:171","msg":"trace[1234918800] range","detail":"{range_begin:/registry/flowschemas/global-default; range_end:; response_count:1; response_revision:73; }","duration":"278.93172ms","start":"2026-04-22T16:25:43.351179Z","end":"2026-04-22T16:25:43.630111Z","steps":["trace[1234918800] 'agreement among raft nodes before linearized reading' (duration: 278.741734ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:43.630219Z","caller":"traceutil/trace.go:171","msg":"trace[1794281825] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"280.016235ms","start":"2026-04-22T16:25:43.350036Z","end":"2026-04-22T16:25:43.630052Z","steps":["trace[1794281825] 'process raft request' (duration: 231.957557ms)","trace[1794281825] 'compare' (duration: 47.601972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:43.91289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.334154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-04-22T16:25:43.913099Z","caller":"traceutil/trace.go:171","msg":"trace[1654683214] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"248.348849ms","start":"2026-04-22T16:25:43.664705Z","end":"2026-04-22T16:25:43.913054Z","steps":["trace[1654683214] 'process raft request' (duration: 86.737477ms)","trace[1654683214] 'compare' (duration: 161.204949ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:43.91301Z","caller":"traceutil/trace.go:171","msg":"trace[993831917] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:79; }","duration":"199.04145ms","start":"2026-04-22T16:25:43.713947Z","end":"2026-04-22T16:25:43.912989Z","steps":["trace[993831917] 'read index received' (duration: 37.528131ms)","trace[993831917] 'applied index is now lower than readState.Index' (duration: 161.511959ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:43.913376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.383771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:25:43.913403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.184224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:25:43.913581Z","caller":"traceutil/trace.go:171","msg":"trace[1993089642] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:76; }","duration":"199.383181ms","start":"2026-04-22T16:25:43.714184Z","end":"2026-04-22T16:25:43.913568Z","steps":["trace[1993089642] 'agreement among raft nodes before linearized reading' (duration: 199.180354ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:43.913507Z","caller":"traceutil/trace.go:171","msg":"trace[533902015] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:76; }","duration":"199.533335ms","start":"2026-04-22T16:25:43.71393Z","end":"2026-04-22T16:25:43.913463Z","steps":["trace[533902015] 'agreement among raft nodes before linearized reading' (duration: 199.302488ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:43.91358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.576969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:25:43.913744Z","caller":"traceutil/trace.go:171","msg":"trace[266104487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:76; }","duration":"170.831037ms","start":"2026-04-22T16:25:43.742899Z","end":"2026-04-22T16:25:43.91373Z","steps":["trace[266104487] 'agreement among raft nodes before linearized reading' (duration: 170.534357ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:44.135133Z","caller":"traceutil/trace.go:171","msg":"trace[969677362] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"149.799974ms","start":"2026-04-22T16:25:43.985312Z","end":"2026-04-22T16:25:44.135112Z","steps":["trace[969677362] 'process raft request' (duration: 79.756934ms)","trace[969677362] 'compare' (duration: 69.942327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:44.491087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.799177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-22T16:25:44.491333Z","caller":"traceutil/trace.go:171","msg":"trace[381123488] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"184.719108ms","start":"2026-04-22T16:25:44.306557Z","end":"2026-04-22T16:25:44.491276Z","steps":["trace[381123488] 'process raft request' (duration: 71.667923ms)","trace[381123488] 'compare' (duration: 112.628562ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:45.322288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223456558,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:45.699498Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.203335201s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:25:45.708682Z","caller":"traceutil/trace.go:171","msg":"trace[421537671] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"887.06226ms","start":"2026-04-22T16:25:44.821595Z","end":"2026-04-22T16:25:45.708657Z","steps":["trace[421537671] 'read index received' (duration: 878.053477ms)","trace[421537671] 'applied index is now lower than readState.Index' (duration: 9.007843ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:45.70883Z","caller":"traceutil/trace.go:171","msg":"trace[624273045] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"1.212754886s","start":"2026-04-22T16:25:44.496039Z","end":"2026-04-22T16:25:45.708794Z","steps":["trace[624273045] 'process raft request' (duration: 1.203757354s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:45.70899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.38477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-22T16:25:45.708983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.950237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:25:45.709104Z","caller":"traceutil/trace.go:171","msg":"trace[132955906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:113; }","duration":"865.124362ms","start":"2026-04-22T16:25:44.84396Z","end":"2026-04-22T16:25:45.709084Z","steps":["trace[132955906] 'agreement among raft nodes before linearized reading' (duration: 864.916256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:45.709156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:44.84394Z","time spent":"865.206354ms","remote":"127.0.0.1:44826","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-22T16:25:45.709039Z","caller":"traceutil/trace.go:171","msg":"trace[33369123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:113; }","duration":"887.492673ms","start":"2026-04-22T16:25:44.821536Z","end":"2026-04-22T16:25:45.709028Z","steps":["trace[33369123] 'agreement among raft nodes before linearized reading' (duration: 887.266076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:45.709287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:44.821513Z","time spent":"887.752152ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:25:45.709171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.596144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-04-22T16:25:45.709524Z","caller":"traceutil/trace.go:171","msg":"trace[1081316169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:113; }","duration":"886.976817ms","start":"2026-04-22T16:25:44.822532Z","end":"2026-04-22T16:25:45.709508Z","steps":["trace[1081316169] 'agreement among raft nodes before linearized reading' (duration: 886.305155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:45.709635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:44.822521Z","time spent":"887.09076ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:25:45.709433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:44.49602Z","time spent":"1.213100987s","remote":"127.0.0.1:45182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:25:46.267538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.262079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:25:46.26764Z","caller":"traceutil/trace.go:171","msg":"trace[376585228] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-account-controller; range_end:; response_count:0; response_revision:175; }","duration":"247.420694ms","start":"2026-04-22T16:25:46.020191Z","end":"2026-04-22T16:25:46.267612Z","steps":["trace[376585228] 'range keys from in-memory index tree' (duration: 247.143935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:46.578258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.202789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:25:46.578384Z","caller":"traceutil/trace.go:171","msg":"trace[754377776] transaction","detail":"{read_only:false; response_revision:182; number_of_response:1; }","duration":"232.805066ms","start":"2026-04-22T16:25:46.345562Z","end":"2026-04-22T16:25:46.578367Z","steps":["trace[754377776] 'process raft request' (duration: 110.427472ms)","trace[754377776] 'compare' (duration: 122.066864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:46.578499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.731554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:25:46.57838Z","caller":"traceutil/trace.go:171","msg":"trace[574275233] linearizableReadLoop","detail":"{readStateIndex:187; appliedIndex:186; }","duration":"222.60548ms","start":"2026-04-22T16:25:46.355754Z","end":"2026-04-22T16:25:46.578359Z","steps":["trace[574275233] 'read index received' (duration: 100.185744ms)","trace[574275233] 'applied index is now lower than readState.Index' (duration: 122.418386ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:46.578548Z","caller":"traceutil/trace.go:171","msg":"trace[809096984] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-controller; range_end:; response_count:0; response_revision:182; }","duration":"222.801066ms","start":"2026-04-22T16:25:46.35573Z","end":"2026-04-22T16:25:46.578531Z","steps":["trace[809096984] 'agreement among raft nodes before linearized reading' (duration: 222.682163ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:46.808087Z","caller":"traceutil/trace.go:171","msg":"trace[1231668657] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"131.160395ms","start":"2026-04-22T16:25:46.676904Z","end":"2026-04-22T16:25:46.808064Z","steps":["trace[1231668657] 'process raft request' (duration: 130.924538ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:46.808099Z","caller":"traceutil/trace.go:171","msg":"trace[1449466874] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"131.232108ms","start":"2026-04-22T16:25:46.676813Z","end":"2026-04-22T16:25:46.808045Z","steps":["trace[1449466874] 'process raft request' (duration: 91.529008ms)","trace[1449466874] 'compare' (duration: 39.375539ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:48.143267Z","caller":"traceutil/trace.go:171","msg":"trace[711389928] linearizableReadLoop","detail":"{readStateIndex:258; appliedIndex:257; }","duration":"421.958544ms","start":"2026-04-22T16:25:47.72128Z","end":"2026-04-22T16:25:48.143239Z","steps":["trace[711389928] 'read index received' (duration: 421.717307ms)","trace[711389928] 'applied index is now lower than readState.Index' (duration: 240.067µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:48.143681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.750627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-22T16:25:48.143659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.376317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T16:25:48.143736Z","caller":"traceutil/trace.go:171","msg":"trace[882206282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:252; }","duration":"421.864651ms","start":"2026-04-22T16:25:47.721857Z","end":"2026-04-22T16:25:48.143722Z","steps":["trace[882206282] 'agreement among raft nodes before linearized reading' (duration: 421.644414ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:48.143782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:47.721803Z","time spent":"421.969694ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:25:48.143784Z","caller":"traceutil/trace.go:171","msg":"trace[214417892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:252; }","duration":"422.555793ms","start":"2026-04-22T16:25:47.721195Z","end":"2026-04-22T16:25:48.143751Z","steps":["trace[214417892] 'agreement among raft nodes before linearized reading' (duration: 422.198172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:48.143842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:47.721174Z","time spent":"422.658095ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T16:25:48.143689Z","caller":"traceutil/trace.go:171","msg":"trace[1542529202] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"565.149115ms","start":"2026-04-22T16:25:47.578507Z","end":"2026-04-22T16:25:48.143656Z","steps":["trace[1542529202] 'process raft request' (duration: 564.599248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:48.144116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:47.578486Z","time spent":"565.500405ms","remote":"127.0.0.1:45012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:25:52.746759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.660249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:25:52.746966Z","caller":"traceutil/trace.go:171","msg":"trace[866068470] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"538.328809ms","start":"2026-04-22T16:25:52.208615Z","end":"2026-04-22T16:25:52.746943Z","steps":["trace[866068470] 'process raft request' (duration: 538.264027ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:52.74694Z","caller":"traceutil/trace.go:171","msg":"trace[55638392] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"566.549965ms","start":"2026-04-22T16:25:52.180343Z","end":"2026-04-22T16:25:52.746893Z","steps":["trace[55638392] 'process raft request' (duration: 240.662109ms)","trace[55638392] 'compare' (duration: 325.553716ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:52.747405Z","caller":"traceutil/trace.go:171","msg":"trace[81114257] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"542.777393ms","start":"2026-04-22T16:25:52.204208Z","end":"2026-04-22T16:25:52.746985Z","steps":["trace[81114257] 'read index received' (duration: 216.777734ms)","trace[81114257] 'applied index is now lower than readState.Index' (duration: 325.997579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:52.747797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:52.180319Z","time spent":"566.724751ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:25:52.748195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:52.208601Z","time spent":"538.410512ms","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:25:52.750843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.657248ms","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-22T16:25:52.7509Z","caller":"traceutil/trace.go:171","msg":"trace[252627734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:342; }","duration":"446.762061ms","start":"2026-04-22T16:25:52.304121Z","end":"2026-04-22T16:25:52.750883Z","steps":["trace[252627734] 'agreement among raft nodes before linearized reading' (duration: 445.02253ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:52.750957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:52.304098Z","time spent":"446.849524ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:25:52.751133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.616778ms","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-22T16:25:52.751417Z","caller":"traceutil/trace.go:171","msg":"trace[546874713] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:342; }","duration":"377.825044ms","start":"2026-04-22T16:25:52.373454Z","end":"2026-04-22T16:25:52.751279Z","steps":["trace[546874713] 'agreement among raft nodes before linearized reading' (duration: 377.151363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:52.751609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:52.373431Z","time spent":"378.056131ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:25:52.751674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.445782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-04-22T16:25:52.752274Z","caller":"traceutil/trace.go:171","msg":"trace[1726200995] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:342; }","duration":"548.076711ms","start":"2026-04-22T16:25:52.204159Z","end":"2026-04-22T16:25:52.752235Z","steps":["trace[1726200995] 'agreement among raft nodes before linearized reading' (duration: 543.04546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:52.752381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:52.204138Z","time spent":"548.227526ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5360,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-04-22T16:25:53.149835Z","caller":"traceutil/trace.go:171","msg":"trace[960479311] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"109.386547ms","start":"2026-04-22T16:25:53.040381Z","end":"2026-04-22T16:25:53.149767Z","steps":["trace[960479311] 'process raft request' (duration: 80.360206ms)","trace[960479311] 'compare' (duration: 28.678591ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:53.492822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.988345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:25:53.493043Z","caller":"traceutil/trace.go:171","msg":"trace[1393047063] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"319.129564ms","start":"2026-04-22T16:25:53.173896Z","end":"2026-04-22T16:25:53.493026Z","steps":["trace[1393047063] 'read index received' (duration: 6.772272ms)","trace[1393047063] 'applied index is now lower than readState.Index' (duration: 312.356492ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:53.493229Z","caller":"traceutil/trace.go:171","msg":"trace[1681297345] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"335.084996ms","start":"2026-04-22T16:25:53.158088Z","end":"2026-04-22T16:25:53.493173Z","steps":["trace[1681297345] 'process raft request' (duration: 131.666628ms)","trace[1681297345] 'compare' (duration: 202.849581ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:25:53.493418Z","caller":"traceutil/trace.go:171","msg":"trace[1593479497] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"316.637319ms","start":"2026-04-22T16:25:53.176441Z","end":"2026-04-22T16:25:53.493078Z","steps":["trace[1593479497] 'process raft request' (duration: 316.483945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.949338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-04-22T16:25:53.493522Z","caller":"traceutil/trace.go:171","msg":"trace[1322487211] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:364; }","duration":"287.052182ms","start":"2026-04-22T16:25:53.206456Z","end":"2026-04-22T16:25:53.493508Z","steps":["trace[1322487211] 'agreement among raft nodes before linearized reading' (duration: 286.956309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.176426Z","time spent":"317.062792ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:25:53.49372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.783331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-22T16:25:53.493734Z","caller":"traceutil/trace.go:171","msg":"trace[1089796046] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"313.872227ms","start":"2026-04-22T16:25:53.179838Z","end":"2026-04-22T16:25:53.49371Z","steps":["trace[1089796046] 'process raft request' (duration: 313.142564ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:25:53.493769Z","caller":"traceutil/trace.go:171","msg":"trace[2037237346] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:364; }","duration":"134.849723ms","start":"2026-04-22T16:25:53.358902Z","end":"2026-04-22T16:25:53.493752Z","steps":["trace[2037237346] 'agreement among raft nodes before linearized reading' (duration: 134.796612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.037937ms","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-22T16:25:53.493854Z","caller":"traceutil/trace.go:171","msg":"trace[426880364] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:364; }","duration":"235.150311ms","start":"2026-04-22T16:25:53.258693Z","end":"2026-04-22T16:25:53.493843Z","steps":["trace[426880364] 'agreement among raft nodes before linearized reading' (duration: 235.054908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.179798Z","time spent":"313.97893ms","remote":"127.0.0.1:45182","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:25:53.493916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.754893ms","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-22T16:25:53.493951Z","caller":"traceutil/trace.go:171","msg":"trace[1394957443] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:364; }","duration":"285.816805ms","start":"2026-04-22T16:25:53.208125Z","end":"2026-04-22T16:25:53.493942Z","steps":["trace[1394957443] 'agreement among raft nodes before linearized reading' (duration: 285.761563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.429955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-22T16:25:53.494064Z","caller":"traceutil/trace.go:171","msg":"trace[318225586] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:364; }","duration":"185.881419ms","start":"2026-04-22T16:25:53.30817Z","end":"2026-04-22T16:25:53.494051Z","steps":["trace[318225586] 'agreement among raft nodes before linearized reading' (duration: 185.423945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.493655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.684751ms","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-22T16:25:53.494175Z","caller":"traceutil/trace.go:171","msg":"trace[924186054] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:364; }","duration":"320.294279ms","start":"2026-04-22T16:25:53.17386Z","end":"2026-04-22T16:25:53.494154Z","steps":["trace[924186054] 'agreement among raft nodes before linearized reading' (duration: 319.410142ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:53.494219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.173841Z","time spent":"320.365ms","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"warn","ts":"2026-04-22T16:25:53.494597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.158071Z","time spent":"336.475078ms","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4002,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:25:54.003451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:54.504272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:55.00475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:55.505396Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:55.893574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000581251s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T16:25:55.893663Z","caller":"traceutil/trace.go:171","msg":"trace[275199977] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000719145s","start":"2026-04-22T16:25:53.892925Z","end":"2026-04-22T16:25:55.893644Z","steps":["trace[275199977] 'agreement among raft nodes before linearized reading' (duration: 2.000606792s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:55.893713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.892887Z","time spent":"2.000816168s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:25:56.00557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:56.506685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457090,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:25:56.816266Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.3136209s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T16:25:57.894284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999763318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T16:25:57.894351Z","caller":"traceutil/trace.go:171","msg":"trace[44677597] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999895354s","start":"2026-04-22T16:25:55.894441Z","end":"2026-04-22T16:25:57.894337Z","steps":["trace[44677597] 'agreement among raft nodes before linearized reading' (duration: 1.9998082s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:57.894397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:55.894412Z","time spent":"1.999969896s","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/22 16:25:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:25:58.176937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.020375402s","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-22T16:25:58.177109Z","caller":"traceutil/trace.go:171","msg":"trace[743579801] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:364; }","duration":"3.020562677s","start":"2026-04-22T16:25:55.156524Z","end":"2026-04-22T16:25:58.177087Z","steps":["trace[743579801] 'range keys from in-memory index tree' (duration: 3.020359122s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:58.177971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361441866s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:25:58.178195Z","caller":"traceutil/trace.go:171","msg":"trace[1273689895] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"4.675627632s","start":"2026-04-22T16:25:53.502539Z","end":"2026-04-22T16:25:58.178166Z","steps":["trace[1273689895] 'process raft request' (duration: 3.313887868s)","trace[1273689895] 'compare' (duration: 1.361098007s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:25:58.178298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.502514Z","time spent":"4.675740716s","remote":"127.0.0.1:45124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:25:59.772321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999524561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T16:25:59.772412Z","caller":"traceutil/trace.go:171","msg":"trace[1527653618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999703426s","start":"2026-04-22T16:25:54.772688Z","end":"2026-04-22T16:25:59.772391Z","steps":["trace[1527653618] 'agreement among raft nodes before linearized reading' (duration: 4.999561312s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:59.772465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:54.772657Z","time spent":"4.999796769s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/22 16:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:25:59.800144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99919327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T16:25:59.800249Z","caller":"traceutil/trace.go:171","msg":"trace[45105751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999356955s","start":"2026-04-22T16:25:54.800877Z","end":"2026-04-22T16:25:59.800234Z","steps":["trace[45105751] 'agreement among raft nodes before linearized reading' (duration: 4.999234022s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:59.800301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:54.800857Z","time spent":"4.999436157s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/22 16:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:25:59.900868Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.022311222s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T16:25:59.90275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000912016s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T16:25:59.902868Z","caller":"traceutil/trace.go:171","msg":"trace[1409032729] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00107529s","start":"2026-04-22T16:25:57.901775Z","end":"2026-04-22T16:25:59.90285Z","steps":["trace[1409032729] 'agreement among raft nodes before linearized reading' (duration: 2.000945037s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:25:59.902957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:57.901751Z","time spent":"2.001177343s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/22 16:25:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:26:00.5048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.504583Z","time spent":"7.000209276s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T16:26:00.50486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.504376Z","time spent":"7.000478174s","remote":"127.0.0.1:44948","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-22T16:26:01.903598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000685015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-22T16:26:01.903771Z","caller":"traceutil/trace.go:171","msg":"trace[3418704] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000907781s","start":"2026-04-22T16:25:59.902843Z","end":"2026-04-22T16:26:01.903751Z","steps":["trace[3418704] 'agreement among raft nodes before linearized reading' (duration: 2.000710816s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:01.903965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:59.902823Z","time spent":"2.001128747s","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/22 16:26:01 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:26:02.531889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000438572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T16:26:02.53195Z","caller":"traceutil/trace.go:171","msg":"trace[1858383372] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000513134s","start":"2026-04-22T16:26:00.531422Z","end":"2026-04-22T16:26:02.531935Z","steps":["trace[1858383372] 'agreement among raft nodes before linearized reading' (duration: 2.000434541s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:02.532013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:00.531384Z","time spent":"2.000621836s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/22 16:26:02 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-22T16:26:03.178068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.276987441s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:03.178309Z","caller":"traceutil/trace.go:171","msg":"trace[681902543] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"3.920340633s","start":"2026-04-22T16:25:59.257953Z","end":"2026-04-22T16:26:03.178294Z","steps":["trace[681902543] 'process raft request' (duration: 3.920299962s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.178377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:59.257934Z","time spent":"3.920406845s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:03.17841Z","caller":"traceutil/trace.go:171","msg":"trace[2055756052] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:378; }","duration":"9.675796491s","start":"2026-04-22T16:25:53.502597Z","end":"2026-04-22T16:26:03.178393Z","steps":["trace[2055756052] 'read index received' (duration: 3.313822067s)","trace[2055756052] 'applied index is now lower than readState.Index' (duration: 6.361972664s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:03.178538Z","caller":"traceutil/trace.go:171","msg":"trace[1405602822] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"5.276775625s","start":"2026-04-22T16:25:57.901754Z","end":"2026-04-22T16:26:03.178529Z","steps":["trace[1405602822] 'process raft request' (duration: 5.276464076s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.178568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.675949895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-22T16:26:03.17858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:57.901725Z","time spent":"5.276832876s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:03.178621Z","caller":"traceutil/trace.go:171","msg":"trace[1786295641] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:369; }","duration":"9.676037587s","start":"2026-04-22T16:25:53.502564Z","end":"2026-04-22T16:26:03.178602Z","steps":["trace[1786295641] 'agreement among raft nodes before linearized reading' (duration: 9.675907373s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.178665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.502549Z","time spent":"9.676105439s","remote":"127.0.0.1:45292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4041,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-22T16:26:03.181381Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.672244845s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:26:03.181573Z","caller":"traceutil/trace.go:171","msg":"trace[1863841082] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"2.672525563s","start":"2026-04-22T16:26:00.509035Z","end":"2026-04-22T16:26:03.18156Z","steps":["trace[1863841082] 'process raft request' (duration: 2.672462931s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.181674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:00.509023Z","time spent":"2.672604745s","remote":"127.0.0.1:44948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1739,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:03.52025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.688842ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T16:26:03.520631Z","caller":"traceutil/trace.go:171","msg":"trace[177139007] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"342.163152ms","start":"2026-04-22T16:26:03.178446Z","end":"2026-04-22T16:26:03.520609Z","steps":["trace[177139007] 'read index received' (duration: 3.052142ms)","trace[177139007] 'applied index is now lower than readState.Index' (duration: 339.10855ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:03.520641Z","caller":"traceutil/trace.go:171","msg":"trace[1897174165] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"336.301965ms","start":"2026-04-22T16:26:03.184321Z","end":"2026-04-22T16:26:03.520623Z","steps":["trace[1897174165] 'process raft request' (duration: 336.167631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.520854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.764328881s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T16:26:03.520905Z","caller":"traceutil/trace.go:171","msg":"trace[651467315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"8.764445485s","start":"2026-04-22T16:25:54.756447Z","end":"2026-04-22T16:26:03.520893Z","steps":["trace[651467315] 'agreement among raft nodes before linearized reading' (duration: 8.76427557s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.520884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.745861861s","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-22T16:26:03.520947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:54.756425Z","time spent":"8.764511477s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-22T16:26:03.520952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.586904144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:03.520978Z","caller":"traceutil/trace.go:171","msg":"trace[246383996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"1.745967223s","start":"2026-04-22T16:26:01.774989Z","end":"2026-04-22T16:26:03.520956Z","steps":["trace[246383996] 'agreement among raft nodes before linearized reading' (duration: 1.745777438s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.52101Z","caller":"traceutil/trace.go:171","msg":"trace[1060445681] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"1.587040717s","start":"2026-04-22T16:26:01.933951Z","end":"2026-04-22T16:26:03.520992Z","steps":["trace[1060445681] 'agreement among raft nodes before linearized reading' (duration: 1.586955115s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:01.774933Z","time spent":"1.746088246s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.013219097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-04-22T16:26:03.521092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.962266008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-22T16:26:03.521124Z","caller":"traceutil/trace.go:171","msg":"trace[965434763] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:372; }","duration":"3.01335526s","start":"2026-04-22T16:26:00.507756Z","end":"2026-04-22T16:26:03.521111Z","steps":["trace[965434763] 'agreement among raft nodes before linearized reading' (duration: 3.013203816s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.861360492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-22T16:26:03.521197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:00.50774Z","time spent":"3.013445863s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7135,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.811068429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"warn","ts":"2026-04-22T16:26:03.521204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.912728787s","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-22T16:26:03.521235Z","caller":"traceutil/trace.go:171","msg":"trace[108325081] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:372; }","duration":"9.811147052s","start":"2026-04-22T16:25:53.710077Z","end":"2026-04-22T16:26:03.521224Z","steps":["trace[108325081] 'agreement among raft nodes before linearized reading' (duration: 9.81107289s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.52124Z","caller":"traceutil/trace.go:171","msg":"trace[1716476216] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:372; }","duration":"9.861446734s","start":"2026-04-22T16:25:53.659771Z","end":"2026-04-22T16:26:03.521217Z","steps":["trace[1716476216] 'agreement among raft nodes before linearized reading' (duration: 9.861329391s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.710061Z","time spent":"9.811201373s","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:01.933925Z","time spent":"1.58713508s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.289769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-22T16:26:03.521361Z","caller":"traceutil/trace.go:171","msg":"trace[424229918] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5; range_end:; response_count:1; response_revision:372; }","duration":"338.35637ms","start":"2026-04-22T16:26:03.182993Z","end":"2026-04-22T16:26:03.521349Z","steps":["trace[424229918] 'agreement among raft nodes before linearized reading' (duration: 338.287378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.182976Z","time spent":"338.427602ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5\" "} {"level":"info","ts":"2026-04-22T16:26:03.521141Z","caller":"traceutil/trace.go:171","msg":"trace[1647416650] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:372; }","duration":"9.96233968s","start":"2026-04-22T16:25:53.558787Z","end":"2026-04-22T16:26:03.521127Z","steps":["trace[1647416650] 'agreement among raft nodes before linearized reading' (duration: 9.962266678s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.521246Z","caller":"traceutil/trace.go:171","msg":"trace[379805468] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:372; }","duration":"9.912771318s","start":"2026-04-22T16:25:53.608463Z","end":"2026-04-22T16:26:03.521234Z","steps":["trace[379805468] 'agreement among raft nodes before linearized reading' (duration: 9.912701206s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.558769Z","time spent":"9.962690149s","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.479093372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:03.521506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.608403Z","time spent":"9.913084136s","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-04-22T16:26:03.521539Z","caller":"traceutil/trace.go:171","msg":"trace[1960231283] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:0; response_revision:372; }","duration":"6.47940372s","start":"2026-04-22T16:25:57.04212Z","end":"2026-04-22T16:26:03.521524Z","steps":["trace[1960231283] 'agreement among raft nodes before linearized reading' (duration: 6.479104403s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.521287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.659753Z","time spent":"9.861521707s","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-22T16:26:03.520999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.719261253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-22T16:26:03.521629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.012766415s","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-22T16:26:03.521648Z","caller":"traceutil/trace.go:171","msg":"trace[1979042610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"1.719941181s","start":"2026-04-22T16:26:01.801691Z","end":"2026-04-22T16:26:03.521632Z","steps":["trace[1979042610] 'agreement among raft nodes before linearized reading' (duration: 1.719248273s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.521684Z","caller":"traceutil/trace.go:171","msg":"trace[1344288555] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:372; }","duration":"10.012854177s","start":"2026-04-22T16:25:53.508817Z","end":"2026-04-22T16:26:03.521671Z","steps":["trace[1344288555] 'agreement among raft nodes before linearized reading' (duration: 10.012769745s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.52171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:01.801677Z","time spent":"1.720014584s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:26:03.521738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:53.508795Z","time spent":"10.01292966s","remote":"127.0.0.1:45040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-22T16:26:03.5216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:57.042102Z","time spent":"6.479481692s","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"warn","ts":"2026-04-22T16:26:03.522101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.184299Z","time spent":"337.749893ms","remote":"127.0.0.1:44948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:03.52212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.340654278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T16:26:03.522172Z","caller":"traceutil/trace.go:171","msg":"trace[1012856802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"9.34076927s","start":"2026-04-22T16:25:54.181389Z","end":"2026-04-22T16:26:03.522158Z","steps":["trace[1012856802] 'agreement among raft nodes before linearized reading' (duration: 9.339359943s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.522217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:25:54.181339Z","time spent":"9.340869834s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T16:26:03.911372Z","caller":"traceutil/trace.go:171","msg":"trace[1732702566] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"384.583769ms","start":"2026-04-22T16:26:03.526755Z","end":"2026-04-22T16:26:03.911339Z","steps":["trace[1732702566] 'process raft request' (duration: 384.229119ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.911484Z","caller":"traceutil/trace.go:171","msg":"trace[1233466445] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"384.421774ms","start":"2026-04-22T16:26:03.527052Z","end":"2026-04-22T16:26:03.911474Z","steps":["trace[1233466445] 'process raft request' (duration: 384.114576ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.911589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.52704Z","time spent":"384.487957ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:03.911591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.526739Z","time spent":"384.777014ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:03.911437Z","caller":"traceutil/trace.go:171","msg":"trace[975089070] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"384.26432ms","start":"2026-04-22T16:26:03.527121Z","end":"2026-04-22T16:26:03.911385Z","steps":["trace[975089070] 'process raft request' (duration: 384.152527ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:03.911846Z","caller":"traceutil/trace.go:171","msg":"trace[1279601402] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:387; }","duration":"382.889484ms","start":"2026-04-22T16:26:03.528945Z","end":"2026-04-22T16:26:03.911835Z","steps":["trace[1279601402] 'read index received' (duration: 382.105242ms)","trace[1279601402] 'applied index is now lower than readState.Index' (duration: 783.392µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:03.911867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.527112Z","time spent":"384.725914ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:03.911948Z","caller":"traceutil/trace.go:171","msg":"trace[1716775600] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"383.634164ms","start":"2026-04-22T16:26:03.528282Z","end":"2026-04-22T16:26:03.911916Z","steps":["trace[1716775600] 'process raft request' (duration: 383.068728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.912017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.528274Z","time spent":"383.711006ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:03.912056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.576892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-04-22T16:26:03.912101Z","caller":"traceutil/trace.go:171","msg":"trace[709530505] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:377; }","duration":"383.637614ms","start":"2026-04-22T16:26:03.528452Z","end":"2026-04-22T16:26:03.91209Z","steps":["trace[709530505] 'agreement among raft nodes before linearized reading' (duration: 383.526861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.912134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.528445Z","time spent":"383.681305ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6989,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-04-22T16:26:03.912036Z","caller":"traceutil/trace.go:171","msg":"trace[1101871413] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"383.49036ms","start":"2026-04-22T16:26:03.528513Z","end":"2026-04-22T16:26:03.912003Z","steps":["trace[1101871413] 'process raft request' (duration: 383.13116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:03.912242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.528507Z","time spent":"383.708126ms","remote":"127.0.0.1:44948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:04.22946Z","caller":"traceutil/trace.go:171","msg":"trace[1262165999] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"699.42346ms","start":"2026-04-22T16:26:03.52998Z","end":"2026-04-22T16:26:04.229404Z","steps":["trace[1262165999] 'process raft request' (duration: 699.239955ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:04.229516Z","caller":"traceutil/trace.go:171","msg":"trace[30186679] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"699.319767ms","start":"2026-04-22T16:26:03.530176Z","end":"2026-04-22T16:26:04.229496Z","steps":["trace[30186679] 'process raft request' (duration: 699.149683ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.229619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.530166Z","time spent":"699.390079ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:04.229613Z","caller":"traceutil/trace.go:171","msg":"trace[637330560] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"698.152725ms","start":"2026-04-22T16:26:03.531433Z","end":"2026-04-22T16:26:04.229586Z","steps":["trace[637330560] 'process raft request' (duration: 698.005902ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.229625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.529968Z","time spent":"699.588494ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:04.229738Z","caller":"traceutil/trace.go:171","msg":"trace[15446410] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"699.572773ms","start":"2026-04-22T16:26:03.530159Z","end":"2026-04-22T16:26:04.229732Z","steps":["trace[15446410] 'process raft request' (duration: 699.132552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.229772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.53015Z","time spent":"699.608035ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:04.229774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.531425Z","time spent":"698.244508ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:04.229745Z","caller":"traceutil/trace.go:171","msg":"trace[1245857109] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:392; }","duration":"317.82968ms","start":"2026-04-22T16:26:03.91189Z","end":"2026-04-22T16:26:04.22972Z","steps":["trace[1245857109] 'read index received' (duration: 317.241115ms)","trace[1245857109] 'applied index is now lower than readState.Index' (duration: 586.805µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:04.229941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.920125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:04.230077Z","caller":"traceutil/trace.go:171","msg":"trace[123784995] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"699.571823ms","start":"2026-04-22T16:26:03.529748Z","end":"2026-04-22T16:26:04.22932Z","steps":["trace[123784995] 'process raft request' (duration: 699.334007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.230132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.529734Z","time spent":"700.367025ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:04.230005Z","caller":"traceutil/trace.go:171","msg":"trace[1146825399] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"604.063903ms","start":"2026-04-22T16:26:03.625933Z","end":"2026-04-22T16:26:04.229997Z","steps":["trace[1146825399] 'process raft request' (duration: 603.57543ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:04.230083Z","caller":"traceutil/trace.go:171","msg":"trace[90543755] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"696.09613ms","start":"2026-04-22T16:26:03.533966Z","end":"2026-04-22T16:26:04.230062Z","steps":["trace[90543755] 'agreement among raft nodes before linearized reading' (duration: 695.918215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.230437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.533958Z","time spent":"696.420798ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:04.229991Z","caller":"traceutil/trace.go:171","msg":"trace[879875433] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"697.614671ms","start":"2026-04-22T16:26:03.532359Z","end":"2026-04-22T16:26:04.229974Z","steps":["trace[879875433] 'process raft request' (duration: 697.113937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.230308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.625907Z","time spent":"604.335421ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:04.232568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.532346Z","time spent":"698.284639ms","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1251,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:04.230449Z","caller":"traceutil/trace.go:171","msg":"trace[1666116842] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"700.054886ms","start":"2026-04-22T16:26:03.530376Z","end":"2026-04-22T16:26:04.23043Z","steps":["trace[1666116842] 'process raft request' (duration: 699.008329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.233158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.530366Z","time spent":"702.663366ms","remote":"127.0.0.1:45330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:04.930979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.796876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:04.931481Z","caller":"traceutil/trace.go:171","msg":"trace[888417911] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:400; }","duration":"698.788368ms","start":"2026-04-22T16:26:04.232672Z","end":"2026-04-22T16:26:04.93146Z","steps":["trace[888417911] 'read index received' (duration: 499.480017ms)","trace[888417911] 'applied index is now lower than readState.Index' (duration: 199.307341ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:04.931537Z","caller":"traceutil/trace.go:171","msg":"trace[1702601237] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"1.015286882s","start":"2026-04-22T16:26:03.916202Z","end":"2026-04-22T16:26:04.931488Z","steps":["trace[1702601237] 'process raft request' (duration: 815.903039ms)","trace[1702601237] 'compare' (duration: 198.632622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:04.931806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.91618Z","time spent":"1.015450926s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:04.931897Z","caller":"traceutil/trace.go:171","msg":"trace[1935137682] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"1.011683123s","start":"2026-04-22T16:26:03.920136Z","end":"2026-04-22T16:26:04.931819Z","steps":["trace[1935137682] 'process raft request' (duration: 1.011232771s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.932235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:03.920125Z","time spent":"1.011946831s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:04.931974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.283451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-22T16:26:04.932456Z","caller":"traceutil/trace.go:171","msg":"trace[1456630538] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:387; }","duration":"699.765484ms","start":"2026-04-22T16:26:04.232667Z","end":"2026-04-22T16:26:04.932433Z","steps":["trace[1456630538] 'agreement among raft nodes before linearized reading' (duration: 699.113286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:04.932535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.23263Z","time spent":"699.880817ms","remote":"127.0.0.1:45002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-04-22T16:26:05.284298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.08682ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:05.284363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.282099ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:05.284421Z","caller":"traceutil/trace.go:171","msg":"trace[1356120793] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:387; }","duration":"127.232195ms","start":"2026-04-22T16:26:05.157172Z","end":"2026-04-22T16:26:05.284404Z","steps":["trace[1356120793] 'range keys from in-memory index tree' (duration: 127.063839ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.284566Z","caller":"traceutil/trace.go:171","msg":"trace[1444611719] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"1.049331799s","start":"2026-04-22T16:26:04.235204Z","end":"2026-04-22T16:26:05.284535Z","steps":["trace[1444611719] 'process raft request' (duration: 879.819913ms)","trace[1444611719] 'compare' (duration: 169.143915ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:05.284675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.235178Z","time spent":"1.049444592s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:05.28615Z","caller":"traceutil/trace.go:171","msg":"trace[867701978] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"1.049726569s","start":"2026-04-22T16:26:04.23641Z","end":"2026-04-22T16:26:05.286136Z","steps":["trace[867701978] 'process raft request' (duration: 1.049519864s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.286205Z","caller":"traceutil/trace.go:171","msg":"trace[610401189] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"1.042483737s","start":"2026-04-22T16:26:04.243704Z","end":"2026-04-22T16:26:05.286187Z","steps":["trace[610401189] 'process raft request' (duration: 1.042370374s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.286231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.236386Z","time spent":"1.049801791s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:05.286278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.243684Z","time spent":"1.042559549s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:05.286314Z","caller":"traceutil/trace.go:171","msg":"trace[680580030] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.031039913s","start":"2026-04-22T16:26:04.255259Z","end":"2026-04-22T16:26:05.286299Z","steps":["trace[680580030] 'process raft request' (duration: 1.030862328s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.286357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.255236Z","time spent":"1.031094354s","remote":"127.0.0.1:45118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2267,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:05.286336Z","caller":"traceutil/trace.go:171","msg":"trace[1113280477] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:402; }","duration":"354.722112ms","start":"2026-04-22T16:26:04.931591Z","end":"2026-04-22T16:26:05.286313Z","steps":["trace[1113280477] 'read index received' (duration: 183.413199ms)","trace[1113280477] 'applied index is now lower than readState.Index' (duration: 171.308003ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:05.286456Z","caller":"traceutil/trace.go:171","msg":"trace[1301596430] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.029993284s","start":"2026-04-22T16:26:04.256453Z","end":"2026-04-22T16:26:05.286447Z","steps":["trace[1301596430] 'process raft request' (duration: 1.02980472s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.286375Z","caller":"traceutil/trace.go:171","msg":"trace[1474164065] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.031080444s","start":"2026-04-22T16:26:04.255285Z","end":"2026-04-22T16:26:05.286365Z","steps":["trace[1474164065] 'process raft request' (duration: 1.03091664s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.2865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.255267Z","time spent":"1.031217527s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:05.286535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.385512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-22T16:26:05.286554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.256431Z","time spent":"1.030059657s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:05.286571Z","caller":"traceutil/trace.go:171","msg":"trace[1782236496] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:393; }","duration":"351.455854ms","start":"2026-04-22T16:26:04.935105Z","end":"2026-04-22T16:26:05.28656Z","steps":["trace[1782236496] 'agreement among raft nodes before linearized reading' (duration: 351.388793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.28658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.436488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-04-22T16:26:05.286613Z","caller":"traceutil/trace.go:171","msg":"trace[1588303074] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:393; }","duration":"350.51094ms","start":"2026-04-22T16:26:04.936093Z","end":"2026-04-22T16:26:05.286604Z","steps":["trace[1588303074] 'agreement among raft nodes before linearized reading' (duration: 350.453239ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.286612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.935087Z","time spent":"351.517886ms","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-04-22T16:26:05.286635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.936073Z","time spent":"350.556641ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-22T16:26:05.286582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.455717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:05.286669Z","caller":"traceutil/trace.go:171","msg":"trace[491217295] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:393; }","duration":"394.538509ms","start":"2026-04-22T16:26:04.892121Z","end":"2026-04-22T16:26:05.286659Z","steps":["trace[491217295] 'agreement among raft nodes before linearized reading' (duration: 394.444686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:05.286686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:04.892094Z","time spent":"394.58837ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:05.481632Z","caller":"traceutil/trace.go:171","msg":"trace[1691445244] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"187.072307ms","start":"2026-04-22T16:26:05.294512Z","end":"2026-04-22T16:26:05.481584Z","steps":["trace[1691445244] 'process raft request' (duration: 92.250734ms)","trace[1691445244] 'compare' (duration: 94.468474ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:05.481662Z","caller":"traceutil/trace.go:171","msg":"trace[1544112928] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"187.021906ms","start":"2026-04-22T16:26:05.294574Z","end":"2026-04-22T16:26:05.481596Z","steps":["trace[1544112928] 'process raft request' (duration: 186.882272ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.481706Z","caller":"traceutil/trace.go:171","msg":"trace[1206740019] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"187.061807ms","start":"2026-04-22T16:26:05.294603Z","end":"2026-04-22T16:26:05.481665Z","steps":["trace[1206740019] 'process raft request' (duration: 186.933843ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.481831Z","caller":"traceutil/trace.go:171","msg":"trace[1520986602] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"184.867469ms","start":"2026-04-22T16:26:05.296942Z","end":"2026-04-22T16:26:05.481809Z","steps":["trace[1520986602] 'process raft request' (duration: 184.820767ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.481934Z","caller":"traceutil/trace.go:171","msg":"trace[787626152] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"185.846464ms","start":"2026-04-22T16:26:05.29607Z","end":"2026-04-22T16:26:05.481916Z","steps":["trace[787626152] 'process raft request' (duration: 185.568917ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.482066Z","caller":"traceutil/trace.go:171","msg":"trace[49561266] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"186.221704ms","start":"2026-04-22T16:26:05.295832Z","end":"2026-04-22T16:26:05.482053Z","steps":["trace[49561266] 'process raft request' (duration: 185.744531ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:05.482062Z","caller":"traceutil/trace.go:171","msg":"trace[1872054318] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"185.29694ms","start":"2026-04-22T16:26:05.296722Z","end":"2026-04-22T16:26:05.482019Z","steps":["trace[1872054318] 'process raft request' (duration: 184.961781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.523974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457390,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:26:26.613414Z","caller":"traceutil/trace.go:171","msg":"trace[1075909640] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"630.576284ms","start":"2026-04-22T16:26:25.982808Z","end":"2026-04-22T16:26:26.613385Z","steps":["trace[1075909640] 'process raft request' (duration: 630.42471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.613562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:25.982791Z","time spent":"630.705636ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.623081Z","caller":"traceutil/trace.go:171","msg":"trace[918896560] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"623.301274ms","start":"2026-04-22T16:26:25.999751Z","end":"2026-04-22T16:26:26.623052Z","steps":["trace[918896560] 'process raft request' (duration: 623.158981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.623174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:25.999731Z","time spent":"623.399015ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.62332Z","caller":"traceutil/trace.go:171","msg":"trace[2027606506] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"599.473596ms","start":"2026-04-22T16:26:26.023839Z","end":"2026-04-22T16:26:26.623313Z","steps":["trace[2027606506] 'process raft request' (duration: 599.230851ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:26.623375Z","caller":"traceutil/trace.go:171","msg":"trace[88282368] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"595.426458ms","start":"2026-04-22T16:26:26.027936Z","end":"2026-04-22T16:26:26.623362Z","steps":["trace[88282368] 'process raft request' (duration: 595.192333ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:26.623373Z","caller":"traceutil/trace.go:171","msg":"trace[1026764348] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"599.546697ms","start":"2026-04-22T16:26:26.023805Z","end":"2026-04-22T16:26:26.623351Z","steps":["trace[1026764348] 'process raft request' (duration: 599.21661ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.623423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.027907Z","time spent":"595.48793ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.623506Z","caller":"traceutil/trace.go:171","msg":"trace[1084616988] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"592.25181ms","start":"2026-04-22T16:26:26.031249Z","end":"2026-04-22T16:26:26.623501Z","steps":["trace[1084616988] 'process raft request' (duration: 591.920053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.623498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.023788Z","time spent":"599.65071ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:26.623533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.031241Z","time spent":"592.279181ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.623111Z","caller":"traceutil/trace.go:171","msg":"trace[490197727] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"615.512024ms","start":"2026-04-22T16:26:26.007573Z","end":"2026-04-22T16:26:26.623085Z","steps":["trace[490197727] 'process raft request' (duration: 615.416501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.623734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.007556Z","time spent":"616.129857ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.624002Z","caller":"traceutil/trace.go:171","msg":"trace[778738307] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"584.475691ms","start":"2026-04-22T16:26:26.039512Z","end":"2026-04-22T16:26:26.623987Z","steps":["trace[778738307] 'process raft request' (duration: 583.694704ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:26.624111Z","caller":"traceutil/trace.go:171","msg":"trace[1976873514] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"580.335891ms","start":"2026-04-22T16:26:26.043767Z","end":"2026-04-22T16:26:26.624103Z","steps":["trace[1976873514] 'process raft request' (duration: 579.515494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.624143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.043761Z","time spent":"580.368972ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.624246Z","caller":"traceutil/trace.go:171","msg":"trace[1039782007] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"578.675015ms","start":"2026-04-22T16:26:26.045566Z","end":"2026-04-22T16:26:26.624241Z","steps":["trace[1039782007] 'process raft request' (duration: 578.401859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.624273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.045547Z","time spent":"578.715486ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.624339Z","caller":"traceutil/trace.go:171","msg":"trace[1823807854] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"600.701152ms","start":"2026-04-22T16:26:26.023626Z","end":"2026-04-22T16:26:26.624328Z","steps":["trace[1823807854] 'read index received' (duration: 589.613551ms)","trace[1823807854] 'applied index is now lower than readState.Index' (duration: 11.086931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:26.624069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.039504Z","time spent":"584.529392ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.624402Z","caller":"traceutil/trace.go:171","msg":"trace[1187808699] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"431.395983ms","start":"2026-04-22T16:26:26.193001Z","end":"2026-04-22T16:26:26.624397Z","steps":["trace[1187808699] 'process raft request' (duration: 431.233359ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:26.624385Z","caller":"traceutil/trace.go:171","msg":"trace[376056478] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"566.267136ms","start":"2026-04-22T16:26:26.058112Z","end":"2026-04-22T16:26:26.624379Z","steps":["trace[376056478] 'process raft request' (duration: 565.98894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.62445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.192954Z","time spent":"431.468874ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:26.624485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.05809Z","time spent":"566.368788ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:26.624563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.930457ms","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-22T16:26:26.624584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.023828Z","time spent":"599.507197ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:26.624593Z","caller":"traceutil/trace.go:171","msg":"trace[2083989337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"600.990989ms","start":"2026-04-22T16:26:26.023594Z","end":"2026-04-22T16:26:26.624585Z","steps":["trace[2083989337] 'agreement among raft nodes before linearized reading' (duration: 600.914167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.624616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.023578Z","time spent":"601.032929ms","remote":"127.0.0.1:45120","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-22T16:26:26.642707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.210337ms","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-22T16:26:26.642804Z","caller":"traceutil/trace.go:171","msg":"trace[1428719727] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"615.306309ms","start":"2026-04-22T16:26:26.02746Z","end":"2026-04-22T16:26:26.642766Z","steps":["trace[1428719727] 'agreement among raft nodes before linearized reading' (duration: 614.669735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.642834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.027453Z","time spent":"615.370671ms","remote":"127.0.0.1:45120","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-22T16:26:26.644268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.653444ms","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-22T16:26:26.644303Z","caller":"traceutil/trace.go:171","msg":"trace[1590100183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"268.734726ms","start":"2026-04-22T16:26:26.375555Z","end":"2026-04-22T16:26:26.64429Z","steps":["trace[1590100183] 'agreement among raft nodes before linearized reading' (duration: 268.523821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.64484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.337453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T16:26:26.644875Z","caller":"traceutil/trace.go:171","msg":"trace[283807877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"617.373905ms","start":"2026-04-22T16:26:26.02749Z","end":"2026-04-22T16:26:26.644864Z","steps":["trace[283807877] 'agreement among raft nodes before linearized reading' (duration: 617.122608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:26.644954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.027486Z","time spent":"617.443296ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-22T16:26:27.153711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.169325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:27.153823Z","caller":"traceutil/trace.go:171","msg":"trace[1224775670] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"463.673554ms","start":"2026-04-22T16:26:26.690134Z","end":"2026-04-22T16:26:27.153807Z","steps":["trace[1224775670] 'read index received' (duration: 5.359166ms)","trace[1224775670] 'applied index is now lower than readState.Index' (duration: 458.312958ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:27.153902Z","caller":"traceutil/trace.go:171","msg":"trace[724525059] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"465.950304ms","start":"2026-04-22T16:26:26.687943Z","end":"2026-04-22T16:26:27.153893Z","steps":["trace[724525059] 'compare' (duration: 457.92536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:27.153971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.687922Z","time spent":"466.012725ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:27.154125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.96536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:1019"} {"level":"info","ts":"2026-04-22T16:26:27.154278Z","caller":"traceutil/trace.go:171","msg":"trace[1877373785] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:549; }","duration":"464.157565ms","start":"2026-04-22T16:26:26.6901Z","end":"2026-04-22T16:26:27.154258Z","steps":["trace[1877373785] 'agreement among raft nodes before linearized reading' (duration: 463.92306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:27.154324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.690088Z","time spent":"464.226346ms","remote":"127.0.0.1:45372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1043,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha1.gateway.networking.x-k8s.io\" "} {"level":"info","ts":"2026-04-22T16:26:27.568726Z","caller":"traceutil/trace.go:171","msg":"trace[1179798687] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"856.555305ms","start":"2026-04-22T16:26:26.712131Z","end":"2026-04-22T16:26:27.568686Z","steps":["trace[1179798687] 'process raft request' (duration: 769.717338ms)","trace[1179798687] 'compare' (duration: 86.746305ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:27.568907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.712104Z","time spent":"856.700677ms","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:28.877397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394664469s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:26:28.895323Z","caller":"traceutil/trace.go:171","msg":"trace[2100507101] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"2.181839131s","start":"2026-04-22T16:26:26.713419Z","end":"2026-04-22T16:26:28.895258Z","steps":["trace[2100507101] 'process raft request' (duration: 2.164214515s)","trace[2100507101] 'compare' (duration: 17.309249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:28.895511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.713393Z","time spent":"2.182017675s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:28.895493Z","caller":"traceutil/trace.go:171","msg":"trace[465577337] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"2.17855111s","start":"2026-04-22T16:26:26.716906Z","end":"2026-04-22T16:26:28.895457Z","steps":["trace[465577337] 'process raft request' (duration: 2.178293505s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:28.895753Z","caller":"traceutil/trace.go:171","msg":"trace[83348578] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"2.165264702s","start":"2026-04-22T16:26:26.730467Z","end":"2026-04-22T16:26:28.895732Z","steps":["trace[83348578] 'process raft request' (duration: 2.164919614s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.895774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.716889Z","time spent":"2.178751125s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:28.895855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.730451Z","time spent":"2.165353213s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:28.895882Z","caller":"traceutil/trace.go:171","msg":"trace[1856992534] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:575; }","duration":"1.742019844s","start":"2026-04-22T16:26:27.153848Z","end":"2026-04-22T16:26:28.895867Z","steps":["trace[1856992534] 'read index received' (duration: 328.077212ms)","trace[1856992534] 'applied index is now lower than readState.Index' (duration: 1.413942002s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:28.895943Z","caller":"traceutil/trace.go:171","msg":"trace[414340773] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"2.158068645s","start":"2026-04-22T16:26:26.737868Z","end":"2026-04-22T16:26:28.895937Z","steps":["trace[414340773] 'process raft request' (duration: 2.157675047s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.896001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.737855Z","time spent":"2.158101135s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:28.896108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.852459227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T16:26:28.896113Z","caller":"traceutil/trace.go:171","msg":"trace[1253005234] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"2.127951232s","start":"2026-04-22T16:26:26.768123Z","end":"2026-04-22T16:26:28.896075Z","steps":["trace[1253005234] 'process raft request' (duration: 2.127618045s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.896145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182924354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-04-22T16:26:28.896161Z","caller":"traceutil/trace.go:171","msg":"trace[725590925] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:1; response_revision:555; }","duration":"1.852563939s","start":"2026-04-22T16:26:27.043582Z","end":"2026-04-22T16:26:28.896146Z","steps":["trace[725590925] 'agreement among raft nodes before linearized reading' (duration: 1.852385996s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:28.896294Z","caller":"traceutil/trace.go:171","msg":"trace[1108753926] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:555; }","duration":"2.183046856s","start":"2026-04-22T16:26:26.713184Z","end":"2026-04-22T16:26:28.89623Z","steps":["trace[1108753926] 'agreement among raft nodes before linearized reading' (duration: 2.182860762s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.896291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.768105Z","time spent":"2.128089865s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:28.896318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:27.043566Z","time spent":"1.852730913s","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"warn","ts":"2026-04-22T16:26:28.897109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:26.713175Z","time spent":"2.183900105s","remote":"127.0.0.1:45372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1037,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-04-22T16:26:28.911379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.0185536s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:28.911464Z","caller":"traceutil/trace.go:171","msg":"trace[1424150281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:557; }","duration":"1.018689383s","start":"2026-04-22T16:26:27.892756Z","end":"2026-04-22T16:26:28.911446Z","steps":["trace[1424150281] 'agreement among raft nodes before linearized reading' (duration: 1.01850731s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.911504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:27.89274Z","time spent":"1.018755834s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:28.911929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.506258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-22T16:26:28.912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.5971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T16:26:28.911989Z","caller":"traceutil/trace.go:171","msg":"trace[230493088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"241.602329ms","start":"2026-04-22T16:26:28.670373Z","end":"2026-04-22T16:26:28.911975Z","steps":["trace[230493088] 'agreement among raft nodes before linearized reading' (duration: 241.408885ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:28.912038Z","caller":"traceutil/trace.go:171","msg":"trace[557424055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:557; }","duration":"241.655411ms","start":"2026-04-22T16:26:28.670373Z","end":"2026-04-22T16:26:28.912028Z","steps":["trace[557424055] 'agreement among raft nodes before linearized reading' (duration: 241.534328ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:28.912076Z","caller":"traceutil/trace.go:171","msg":"trace[1175243974] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.332725373s","start":"2026-04-22T16:26:27.579336Z","end":"2026-04-22T16:26:28.912062Z","steps":["trace[1175243974] 'process raft request' (duration: 1.331832445s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.912142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:27.579278Z","time spent":"1.332821825s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:28.912185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.255354ms","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-22T16:26:28.912205Z","caller":"traceutil/trace.go:171","msg":"trace[1538516654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:557; }","duration":"227.289574ms","start":"2026-04-22T16:26:28.68491Z","end":"2026-04-22T16:26:28.9122Z","steps":["trace[1538516654] 'agreement among raft nodes before linearized reading' (duration: 227.220143ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:28.91224Z","caller":"traceutil/trace.go:171","msg":"trace[2065141576] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.333934989s","start":"2026-04-22T16:26:27.578297Z","end":"2026-04-22T16:26:28.912232Z","steps":["trace[2065141576] 'process raft request' (duration: 1.332656721s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:28.91228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:27.578265Z","time spent":"1.33399566s","remote":"127.0.0.1:44890","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:28.912236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.760798ms","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-22T16:26:28.912399Z","caller":"traceutil/trace.go:171","msg":"trace[1986017224] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"260.962182ms","start":"2026-04-22T16:26:28.651429Z","end":"2026-04-22T16:26:28.912391Z","steps":["trace[1986017224] 'agreement among raft nodes before linearized reading' (duration: 260.774138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:31.621679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.864276ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:31.621766Z","caller":"traceutil/trace.go:171","msg":"trace[1280988136] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"626.300981ms","start":"2026-04-22T16:26:30.995451Z","end":"2026-04-22T16:26:31.621752Z","steps":["trace[1280988136] 'process raft request' (duration: 322.301892ms)","trace[1280988136] 'compare' (duration: 303.571721ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:31.621804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:30.995433Z","time spent":"626.353492ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:31.627056Z","caller":"traceutil/trace.go:171","msg":"trace[1695081013] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"631.48277ms","start":"2026-04-22T16:26:30.995556Z","end":"2026-04-22T16:26:31.627039Z","steps":["trace[1695081013] 'process raft request' (duration: 631.337807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:31.627143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:30.995527Z","time spent":"631.575531ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:31.627581Z","caller":"traceutil/trace.go:171","msg":"trace[596431642] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"630.911097ms","start":"2026-04-22T16:26:30.996344Z","end":"2026-04-22T16:26:31.627255Z","steps":["trace[596431642] 'process raft request' (duration: 630.634791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:31.627716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:30.996328Z","time spent":"631.317586ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:33.80918Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:34.28579Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053571582s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:26:34.286597Z","caller":"traceutil/trace.go:171","msg":"trace[1240892819] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"977.496195ms","start":"2026-04-22T16:26:33.309076Z","end":"2026-04-22T16:26:34.286573Z","steps":["trace[1240892819] 'read index received' (duration: 976.924754ms)","trace[1240892819] 'applied index is now lower than readState.Index' (duration: 570.861µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:34.286739Z","caller":"traceutil/trace.go:171","msg":"trace[1071055569] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.056994631s","start":"2026-04-22T16:26:33.229675Z","end":"2026-04-22T16:26:34.286669Z","steps":["trace[1071055569] 'process raft request' (duration: 1.056471361s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.286837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.73873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jcljz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T16:26:34.286864Z","caller":"traceutil/trace.go:171","msg":"trace[974454015] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jcljz; range_end:; response_count:1; response_revision:622; }","duration":"977.817802ms","start":"2026-04-22T16:26:33.309038Z","end":"2026-04-22T16:26:34.286856Z","steps":["trace[974454015] 'agreement among raft nodes before linearized reading' (duration: 977.70316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.286896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.309021Z","time spent":"977.870633ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-jcljz\" "} {"level":"warn","ts":"2026-04-22T16:26:34.287009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.229637Z","time spent":"1.057205856s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:34.474856Z","caller":"traceutil/trace.go:171","msg":"trace[903582969] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"188.099417ms","start":"2026-04-22T16:26:34.28672Z","end":"2026-04-22T16:26:34.47482Z","steps":["trace[903582969] 'read index received' (duration: 137.49558ms)","trace[903582969] 'applied index is now lower than readState.Index' (duration: 50.602927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:34.474894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.723131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:34.474936Z","caller":"traceutil/trace.go:171","msg":"trace[1234422710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"583.777782ms","start":"2026-04-22T16:26:33.891149Z","end":"2026-04-22T16:26:34.474927Z","steps":["trace[1234422710] 'agreement among raft nodes before linearized reading' (duration: 583.686161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.474959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.891105Z","time spent":"583.848344ms","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:34.475085Z","caller":"traceutil/trace.go:171","msg":"trace[19850547] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"457.977621ms","start":"2026-04-22T16:26:34.017099Z","end":"2026-04-22T16:26:34.475076Z","steps":["trace[19850547] 'process raft request' (duration: 407.098909ms)","trace[19850547] 'compare' (duration: 50.506035ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:34.475187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.017073Z","time spent":"458.035753ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:34.47537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.068726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-22T16:26:34.47537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.904893ms","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-22T16:26:34.475393Z","caller":"traceutil/trace.go:171","msg":"trace[394741566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"845.132168ms","start":"2026-04-22T16:26:33.630255Z","end":"2026-04-22T16:26:34.475387Z","steps":["trace[394741566] 'agreement among raft nodes before linearized reading' (duration: 845.073246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.47541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.630235Z","time spent":"845.170578ms","remote":"127.0.0.1:45120","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-22T16:26:34.475411Z","caller":"traceutil/trace.go:171","msg":"trace[1314133953] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"844.958184ms","start":"2026-04-22T16:26:33.630441Z","end":"2026-04-22T16:26:34.4754Z","steps":["trace[1314133953] 'agreement among raft nodes before linearized reading' (duration: 844.813281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.475442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.630436Z","time spent":"844.998925ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:26:34.475042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.428364ms","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-22T16:26:34.475708Z","caller":"traceutil/trace.go:171","msg":"trace[653829711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"849.081217ms","start":"2026-04-22T16:26:33.626597Z","end":"2026-04-22T16:26:34.475679Z","steps":["trace[653829711] 'agreement among raft nodes before linearized reading' (duration: 848.325982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.475798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:33.626539Z","time spent":"849.22951ms","remote":"127.0.0.1:45120","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-22T16:26:34.945487Z","caller":"traceutil/trace.go:171","msg":"trace[308417577] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"600.527334ms","start":"2026-04-22T16:26:34.344931Z","end":"2026-04-22T16:26:34.945459Z","steps":["trace[308417577] 'process raft request' (duration: 600.435772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:34.945599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.344903Z","time spent":"600.643167ms","remote":"127.0.0.1:45168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:34.945739Z","caller":"traceutil/trace.go:171","msg":"trace[391307796] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"640.963324ms","start":"2026-04-22T16:26:34.304725Z","end":"2026-04-22T16:26:34.945688Z","steps":["trace[391307796] 'process raft request' (duration: 604.057035ms)","trace[391307796] 'compare' (duration: 36.297436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:34.94592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.304707Z","time spent":"641.119727ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:35.670467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.117325ms","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-22T16:26:35.670655Z","caller":"traceutil/trace.go:171","msg":"trace[606492255] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"497.327529ms","start":"2026-04-22T16:26:35.1733Z","end":"2026-04-22T16:26:35.670628Z","steps":["trace[606492255] 'range keys from in-memory index tree' (duration: 497.088755ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:35.768134Z","caller":"traceutil/trace.go:171","msg":"trace[649349198] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.289379425s","start":"2026-04-22T16:26:34.478707Z","end":"2026-04-22T16:26:35.768086Z","steps":["trace[649349198] 'process raft request' (duration: 1.289163641s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:35.768316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.478685Z","time spent":"1.289541558s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:35.768418Z","caller":"traceutil/trace.go:171","msg":"trace[1428291496] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.289350404s","start":"2026-04-22T16:26:34.479047Z","end":"2026-04-22T16:26:35.768398Z","steps":["trace[1428291496] 'process raft request' (duration: 1.288989017s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:35.768414Z","caller":"traceutil/trace.go:171","msg":"trace[1300672804] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.288808383s","start":"2026-04-22T16:26:34.479578Z","end":"2026-04-22T16:26:35.768387Z","steps":["trace[1300672804] 'process raft request' (duration: 1.288696101s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:35.768445Z","caller":"traceutil/trace.go:171","msg":"trace[819533808] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:651; }","duration":"863.30697ms","start":"2026-04-22T16:26:34.905113Z","end":"2026-04-22T16:26:35.76842Z","steps":["trace[819533808] 'read index received' (duration: 3.680744ms)","trace[819533808] 'applied index is now lower than readState.Index' (duration: 859.625446ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:35.768516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.479025Z","time spent":"1.289443135s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:35.768553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.479568Z","time spent":"1.288913786s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:35.768648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.533546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:35.768711Z","caller":"traceutil/trace.go:171","msg":"trace[2067307585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"863.634807ms","start":"2026-04-22T16:26:34.90506Z","end":"2026-04-22T16:26:35.768695Z","steps":["trace[2067307585] 'agreement among raft nodes before linearized reading' (duration: 863.516375ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:35.768666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.103832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:35.768722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.796469ms","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-22T16:26:35.768755Z","caller":"traceutil/trace.go:171","msg":"trace[651319062] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:629; }","duration":"733.86848ms","start":"2026-04-22T16:26:35.034878Z","end":"2026-04-22T16:26:35.768746Z","steps":["trace[651319062] 'agreement among raft nodes before linearized reading' (duration: 733.772588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:35.768754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.905044Z","time spent":"863.699579ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:35.76877Z","caller":"traceutil/trace.go:171","msg":"trace[144340835] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:629; }","duration":"813.199604ms","start":"2026-04-22T16:26:34.955541Z","end":"2026-04-22T16:26:35.768741Z","steps":["trace[144340835] 'agreement among raft nodes before linearized reading' (duration: 813.053501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:35.768788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:35.034857Z","time spent":"733.919861ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:26:35.768813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.955489Z","time spent":"813.301316ms","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-22T16:26:35.768656Z","caller":"traceutil/trace.go:171","msg":"trace[1661996222] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.072779541s","start":"2026-04-22T16:26:34.695862Z","end":"2026-04-22T16:26:35.768641Z","steps":["trace[1661996222] 'process raft request' (duration: 1.072496955s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:35.768896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:34.695834Z","time spent":"1.073023966s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:36.932169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.068918ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:36.932282Z","caller":"traceutil/trace.go:171","msg":"trace[708946000] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.155719644s","start":"2026-04-22T16:26:35.776543Z","end":"2026-04-22T16:26:36.932262Z","steps":["trace[708946000] 'process raft request' (duration: 430.482062ms)","trace[708946000] 'compare' (duration: 724.973146ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:36.932342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:35.77652Z","time spent":"1.155795345s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:37.392615Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:37.892959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:38.394083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:38.892805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000648465s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T16:26:38.892883Z","caller":"traceutil/trace.go:171","msg":"trace[313304990] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000769886s","start":"2026-04-22T16:26:36.892091Z","end":"2026-04-22T16:26:38.89286Z","steps":["trace[313304990] 'agreement among raft nodes before linearized reading' (duration: 2.000674814s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:38.892916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:36.892067Z","time spent":"2.000841928s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:38.895233Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:39.396289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:39.897414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:40.39784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457564,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:40.417703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.209405186s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T16:26:40.89364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000577764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-22T16:26:40.89373Z","caller":"traceutil/trace.go:171","msg":"trace[1906220290] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000718346s","start":"2026-04-22T16:26:38.892986Z","end":"2026-04-22T16:26:40.893704Z","steps":["trace[1906220290] 'agreement among raft nodes before linearized reading' (duration: 2.000608224s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:40.893779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:38.892967Z","time spent":"2.000801838s","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:41.86748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449291032s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:26:41.867616Z","caller":"traceutil/trace.go:171","msg":"trace[1198228127] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"4.975467536s","start":"2026-04-22T16:26:36.89213Z","end":"2026-04-22T16:26:41.867597Z","steps":["trace[1198228127] 'read index received' (duration: 3.525676923s)","trace[1198228127] 'applied index is now lower than readState.Index' (duration: 1.449764802s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:41.86773Z","caller":"traceutil/trace.go:171","msg":"trace[61128805] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"5.684005963s","start":"2026-04-22T16:26:36.183708Z","end":"2026-04-22T16:26:41.867714Z","steps":["trace[61128805] 'process raft request' (duration: 4.234376713s)","trace[61128805] 'compare' (duration: 1.448261813s)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:41.867802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:36.183674Z","time spent":"5.684091235s","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:41.868144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.82366374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-22T16:26:41.868177Z","caller":"traceutil/trace.go:171","msg":"trace[1147122000] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:1; response_revision:631; }","duration":"4.823723751s","start":"2026-04-22T16:26:37.044443Z","end":"2026-04-22T16:26:41.868166Z","steps":["trace[1147122000] 'agreement among raft nodes before linearized reading' (duration: 4.823568369s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333992561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:41.868208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:37.044425Z","time spent":"4.823774982s","remote":"127.0.0.1:44876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"warn","ts":"2026-04-22T16:26:41.868244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.094825962s","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-22T16:26:41.868287Z","caller":"traceutil/trace.go:171","msg":"trace[1799335724] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"1.334097573s","start":"2026-04-22T16:26:40.534133Z","end":"2026-04-22T16:26:41.86823Z","steps":["trace[1799335724] 'agreement among raft nodes before linearized reading' (duration: 1.33395159s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.094877463s","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-22T16:26:41.86832Z","caller":"traceutil/trace.go:171","msg":"trace[1542585243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"4.094953565s","start":"2026-04-22T16:26:37.773345Z","end":"2026-04-22T16:26:41.868299Z","steps":["trace[1542585243] 'agreement among raft nodes before linearized reading' (duration: 4.094740541s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.095800361s","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-22T16:26:41.86837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:37.773323Z","time spent":"4.095037256s","remote":"127.0.0.1:45120","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-22T16:26:41.868358Z","caller":"traceutil/trace.go:171","msg":"trace[1328032734] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"4.094986036s","start":"2026-04-22T16:26:37.77335Z","end":"2026-04-22T16:26:41.868336Z","steps":["trace[1328032734] 'agreement among raft nodes before linearized reading' (duration: 4.094774352s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.954165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-22T16:26:41.86841Z","caller":"traceutil/trace.go:171","msg":"trace[417536978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"4.095943864s","start":"2026-04-22T16:26:37.772443Z","end":"2026-04-22T16:26:41.868387Z","steps":["trace[417536978] 'agreement among raft nodes before linearized reading' (duration: 4.095694349s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:37.773332Z","time spent":"4.095072657s","remote":"127.0.0.1:45120","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-22T16:26:41.868439Z","caller":"traceutil/trace.go:171","msg":"trace[433034334] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5; range_end:; response_count:1; response_revision:631; }","duration":"970.016066ms","start":"2026-04-22T16:26:40.898412Z","end":"2026-04-22T16:26:41.868428Z","steps":["trace[433034334] 'agreement among raft nodes before linearized reading' (duration: 969.952155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.876454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:41.868456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:37.772419Z","time spent":"4.096027566s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:26:41.868466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:40.898393Z","time spent":"970.067467ms","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a8ba8ab48a9fb5\" "} {"level":"warn","ts":"2026-04-22T16:26:41.868325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:40.534116Z","time spent":"1.334202205s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:41.868482Z","caller":"traceutil/trace.go:171","msg":"trace[1926215598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"969.966035ms","start":"2026-04-22T16:26:40.898501Z","end":"2026-04-22T16:26:41.868467Z","steps":["trace[1926215598] 'agreement among raft nodes before linearized reading' (duration: 969.858263ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:40.898496Z","time spent":"970.014826ms","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:41.868523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.932433879s","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-22T16:26:41.868556Z","caller":"traceutil/trace.go:171","msg":"trace[1429204484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"2.93251575s","start":"2026-04-22T16:26:38.936031Z","end":"2026-04-22T16:26:41.868547Z","steps":["trace[1429204484] 'agreement among raft nodes before linearized reading' (duration: 2.932425078s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:41.868583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:38.936012Z","time spent":"2.932564991s","remote":"127.0.0.1:45120","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-22T16:26:42.395848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:42.896921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457568,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:26:43.029924Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.159422216s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-22T16:26:43.031652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.870387Z","time spent":"1.161258811s","remote":"127.0.0.1:44876","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-22T16:26:43.043735Z","caller":"traceutil/trace.go:171","msg":"trace[610812135] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.172437882s","start":"2026-04-22T16:26:41.871256Z","end":"2026-04-22T16:26:43.043694Z","steps":["trace[610812135] 'process raft request' (duration: 1.172213808s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:43.043784Z","caller":"traceutil/trace.go:171","msg":"trace[231587890] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.170540025s","start":"2026-04-22T16:26:41.87322Z","end":"2026-04-22T16:26:43.04376Z","steps":["trace[231587890] 'process raft request' (duration: 1.170427543s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:43.04383Z","caller":"traceutil/trace.go:171","msg":"trace[1511802945] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.170215039s","start":"2026-04-22T16:26:41.873608Z","end":"2026-04-22T16:26:43.043823Z","steps":["trace[1511802945] 'process raft request' (duration: 1.170059996s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.043932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.873599Z","time spent":"1.17026242s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:43.043951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.873207Z","time spent":"1.170639448s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:43.043964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.871227Z","time spent":"1.172609845s","remote":"127.0.0.1:44924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:43.043767Z","caller":"traceutil/trace.go:171","msg":"trace[702253571] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.171669277s","start":"2026-04-22T16:26:41.872054Z","end":"2026-04-22T16:26:43.043724Z","steps":["trace[702253571] 'process raft request' (duration: 1.171555275s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.04406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.872046Z","time spent":"1.171994504s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:43.044123Z","caller":"traceutil/trace.go:171","msg":"trace[708348895] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.170381924s","start":"2026-04-22T16:26:41.873734Z","end":"2026-04-22T16:26:43.044116Z","steps":["trace[708348895] 'process raft request' (duration: 1.169953805s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.04416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.873701Z","time spent":"1.170438774s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:43.044229Z","caller":"traceutil/trace.go:171","msg":"trace[330736815] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.118518058s","start":"2026-04-22T16:26:41.925705Z","end":"2026-04-22T16:26:43.044223Z","steps":["trace[330736815] 'process raft request' (duration: 1.118007017s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.044261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.925679Z","time spent":"1.118567958s","remote":"127.0.0.1:45174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:43.044084Z","caller":"traceutil/trace.go:171","msg":"trace[1578734089] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:659; }","duration":"1.149150526s","start":"2026-04-22T16:26:41.894925Z","end":"2026-04-22T16:26:43.044076Z","steps":["trace[1578734089] 'read index received' (duration: 1.135854824s)","trace[1578734089] 'applied index is now lower than readState.Index' (duration: 13.294902ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:43.044495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149565463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:43.044545Z","caller":"traceutil/trace.go:171","msg":"trace[1028955112] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"1.149644635s","start":"2026-04-22T16:26:41.894891Z","end":"2026-04-22T16:26:43.044535Z","steps":["trace[1028955112] 'agreement among raft nodes before linearized reading' (duration: 1.149550744s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.044587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:41.894875Z","time spent":"1.149705296s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:43.077094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.270218ms","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-22T16:26:43.077166Z","caller":"traceutil/trace.go:171","msg":"trace[165799757] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:638; }","duration":"327.404601ms","start":"2026-04-22T16:26:42.749746Z","end":"2026-04-22T16:26:43.077151Z","steps":["trace[165799757] 'agreement among raft nodes before linearized reading' (duration: 327.210167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:43.077194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:42.749716Z","time spent":"327.471701ms","remote":"127.0.0.1:45246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"info","ts":"2026-04-22T16:26:43.664514Z","caller":"traceutil/trace.go:171","msg":"trace[1666045914] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"165.379898ms","start":"2026-04-22T16:26:43.499102Z","end":"2026-04-22T16:26:43.664482Z","steps":["trace[1666045914] 'process raft request' (duration: 164.883269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.384185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.735316ms","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-22T16:26:45.38432Z","caller":"traceutil/trace.go:171","msg":"trace[1937270285] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:650; }","duration":"226.862928ms","start":"2026-04-22T16:26:45.15742Z","end":"2026-04-22T16:26:45.384283Z","steps":["trace[1937270285] 'range keys from in-memory index tree' (duration: 226.712526ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:45.388951Z","caller":"traceutil/trace.go:171","msg":"trace[1568715415] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"751.105342ms","start":"2026-04-22T16:26:44.637834Z","end":"2026-04-22T16:26:45.388939Z","steps":["trace[1568715415] 'process raft request' (duration: 751.015481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.389152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:44.637804Z","time spent":"751.178393ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:45.404844Z","caller":"traceutil/trace.go:171","msg":"trace[658856333] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"510.739035ms","start":"2026-04-22T16:26:44.894069Z","end":"2026-04-22T16:26:45.404808Z","steps":["trace[658856333] 'read index received' (duration: 494.91937ms)","trace[658856333] 'applied index is now lower than readState.Index' (duration: 15.818365ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:45.405303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.219274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:45.405386Z","caller":"traceutil/trace.go:171","msg":"trace[1767154115] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:652; }","duration":"511.347236ms","start":"2026-04-22T16:26:44.894014Z","end":"2026-04-22T16:26:45.405361Z","steps":["trace[1767154115] 'agreement among raft nodes before linearized reading' (duration: 511.222443ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:45.405356Z","caller":"traceutil/trace.go:171","msg":"trace[655645712] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"732.736594ms","start":"2026-04-22T16:26:44.672568Z","end":"2026-04-22T16:26:45.405304Z","steps":["trace[655645712] 'process raft request' (duration: 731.816157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.405428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:44.893993Z","time spent":"511.425888ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:26:45.405492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.437465ms","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-22T16:26:45.405539Z","caller":"traceutil/trace.go:171","msg":"trace[1319756637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:652; }","duration":"355.499506ms","start":"2026-04-22T16:26:45.050028Z","end":"2026-04-22T16:26:45.405527Z","steps":["trace[1319756637] 'agreement among raft nodes before linearized reading' (duration: 355.358923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.405504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.936675ms","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-22T16:26:45.405607Z","caller":"traceutil/trace.go:171","msg":"trace[679508922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:652; }","duration":"356.084337ms","start":"2026-04-22T16:26:45.049504Z","end":"2026-04-22T16:26:45.405589Z","steps":["trace[679508922] 'agreement among raft nodes before linearized reading' (duration: 355.833482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.405657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:45.049482Z","time spent":"356.16682ms","remote":"127.0.0.1:45120","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-22T16:26:45.405654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:44.672537Z","time spent":"732.916356ms","remote":"127.0.0.1:44942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:45.405567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:45.050023Z","time spent":"355.536656ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:26:45.405868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.862843ms","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-22T16:26:45.405912Z","caller":"traceutil/trace.go:171","msg":"trace[1819718478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:652; }","duration":"355.923685ms","start":"2026-04-22T16:26:45.049981Z","end":"2026-04-22T16:26:45.405905Z","steps":["trace[1819718478] 'agreement among raft nodes before linearized reading' (duration: 355.835413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.40595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:45.049974Z","time spent":"355.969855ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:26:45.406034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.310713ms","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-22T16:26:45.406089Z","caller":"traceutil/trace.go:171","msg":"trace[545470709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:652; }","duration":"356.402314ms","start":"2026-04-22T16:26:45.04967Z","end":"2026-04-22T16:26:45.406073Z","steps":["trace[545470709] 'agreement among raft nodes before linearized reading' (duration: 356.254601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:45.406131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:45.04965Z","time spent":"356.471396ms","remote":"127.0.0.1:45120","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-22T16:26:49.849294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.576608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-22T16:26:49.849401Z","caller":"traceutil/trace.go:171","msg":"trace[728053880] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:734; }","duration":"208.72453ms","start":"2026-04-22T16:26:49.640658Z","end":"2026-04-22T16:26:49.849382Z","steps":["trace[728053880] 'range keys from in-memory index tree' (duration: 208.430695ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:58.998098Z","caller":"traceutil/trace.go:171","msg":"trace[2077728834] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"102.490806ms","start":"2026-04-22T16:26:58.895589Z","end":"2026-04-22T16:26:58.998079Z","steps":["trace[2077728834] 'read index received' (duration: 102.287603ms)","trace[2077728834] 'applied index is now lower than readState.Index' (duration: 202.533µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:58.998144Z","caller":"traceutil/trace.go:171","msg":"trace[1364374799] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"546.68956ms","start":"2026-04-22T16:26:58.451439Z","end":"2026-04-22T16:26:58.998128Z","steps":["trace[1364374799] 'process raft request' (duration: 546.500297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:58.998235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.629598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:58.998271Z","caller":"traceutil/trace.go:171","msg":"trace[1063549263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"102.706699ms","start":"2026-04-22T16:26:58.895553Z","end":"2026-04-22T16:26:58.99826Z","steps":["trace[1063549263] 'agreement among raft nodes before linearized reading' (duration: 102.621398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:58.998292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:58.451418Z","time spent":"546.808752ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4857,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:03.959534Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223457998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-22T16:27:04.039603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.607701486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:27:04.039682Z","caller":"traceutil/trace.go:171","msg":"trace[1835400254] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:812; }","duration":"2.607823807s","start":"2026-04-22T16:27:01.431837Z","end":"2026-04-22T16:27:04.039661Z","steps":["trace[1835400254] 'count revisions from in-memory index tree' (duration: 2.607613033s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.039721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.431822Z","time spent":"2.607888448s","remote":"127.0.0.1:45310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:27:04.039977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.585099246s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zc585\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T16:27:04.040018Z","caller":"traceutil/trace.go:171","msg":"trace[1961778700] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zc585; range_end:; response_count:1; response_revision:812; }","duration":"2.585174768s","start":"2026-04-22T16:27:01.454831Z","end":"2026-04-22T16:27:04.040006Z","steps":["trace[1961778700] 'range keys from in-memory index tree' (duration: 2.584958674s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.040044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.45481Z","time spent":"2.585226518s","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-zc585\" "} {"level":"warn","ts":"2026-04-22T16:27:04.04022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.509020766s","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-22T16:27:04.040246Z","caller":"traceutil/trace.go:171","msg":"trace[1653569624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"2.509075778s","start":"2026-04-22T16:27:01.531162Z","end":"2026-04-22T16:27:04.040238Z","steps":["trace[1653569624] 'range keys from in-memory index tree' (duration: 2.508930424s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.04027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.531147Z","time spent":"2.509116789s","remote":"127.0.0.1:45120","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-22T16:27:04.040632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.504210627s","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-22T16:27:04.040656Z","caller":"traceutil/trace.go:171","msg":"trace[1762987862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"2.504275147s","start":"2026-04-22T16:27:01.536374Z","end":"2026-04-22T16:27:04.040649Z","steps":["trace[1762987862] 'range keys from in-memory index tree' (duration: 2.504091324s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.04068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.536349Z","time spent":"2.504322078s","remote":"127.0.0.1:45120","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-22T16:27:04.041565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982662992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:27:04.041607Z","caller":"traceutil/trace.go:171","msg":"trace[1486323617] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:812; }","duration":"1.982727672s","start":"2026-04-22T16:27:02.05887Z","end":"2026-04-22T16:27:04.041598Z","steps":["trace[1486323617] 'count revisions from in-memory index tree' (duration: 1.982558969s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.04167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:02.058855Z","time spent":"1.982802343s","remote":"127.0.0.1:45284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:27:04.041826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.483317717s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:27:04.041884Z","caller":"traceutil/trace.go:171","msg":"trace[332045952] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:812; }","duration":"2.483418348s","start":"2026-04-22T16:27:01.558451Z","end":"2026-04-22T16:27:04.041869Z","steps":["trace[332045952] 'range keys from in-memory index tree' (duration: 2.483141994s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.041905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147701622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:27:04.041926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.558422Z","time spent":"2.483493969s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T16:27:04.041968Z","caller":"traceutil/trace.go:171","msg":"trace[1739822043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"2.147826694s","start":"2026-04-22T16:27:01.89411Z","end":"2026-04-22T16:27:04.041937Z","steps":["trace[1739822043] 'range keys from in-memory index tree' (duration: 2.147624511s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.042005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.894092Z","time spent":"2.147903766s","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:27:04.042259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.504587272s","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-22T16:27:04.042297Z","caller":"traceutil/trace.go:171","msg":"trace[1149819144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"2.504645083s","start":"2026-04-22T16:27:01.537643Z","end":"2026-04-22T16:27:04.042288Z","steps":["trace[1149819144] 'range keys from in-memory index tree' (duration: 2.503072848s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.042366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.504266877s","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-22T16:27:04.042616Z","caller":"traceutil/trace.go:171","msg":"trace[1785407417] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"2.504633053s","start":"2026-04-22T16:27:01.537953Z","end":"2026-04-22T16:27:04.042586Z","steps":["trace[1785407417] 'range keys from in-memory index tree' (duration: 2.503467184s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.042806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.537931Z","time spent":"2.504861947s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-22T16:27:04.042622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:01.537625Z","time spent":"2.504787605s","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-22T16:27:04.047222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.932500577s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:27:04.077973Z","caller":"traceutil/trace.go:171","msg":"trace[875266185] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"618.566363ms","start":"2026-04-22T16:27:03.459373Z","end":"2026-04-22T16:27:04.077939Z","steps":["trace[875266185] 'read index received' (duration: 588.53132ms)","trace[875266185] 'applied index is now lower than readState.Index' (duration: 30.031433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:04.078212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.812197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-22T16:27:04.078253Z","caller":"traceutil/trace.go:171","msg":"trace[100931642] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:813; }","duration":"618.897699ms","start":"2026-04-22T16:27:03.459345Z","end":"2026-04-22T16:27:04.078243Z","steps":["trace[100931642] 'agreement among raft nodes before linearized reading' (duration: 618.762847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.078294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:03.459331Z","time spent":"618.951679ms","remote":"127.0.0.1:45232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:27:04.084321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.748252ms","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":"info","ts":"2026-04-22T16:27:04.084399Z","caller":"traceutil/trace.go:171","msg":"trace[637740772] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:818; }","duration":"445.867934ms","start":"2026-04-22T16:27:03.638514Z","end":"2026-04-22T16:27:04.084382Z","steps":["trace[637740772] 'agreement among raft nodes before linearized reading' (duration: 445.732842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:04.084479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:03.638493Z","time spent":"445.935455ms","remote":"127.0.0.1:45072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:27:04.08487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.715603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:27:04.084909Z","caller":"traceutil/trace.go:171","msg":"trace[1592485217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:818; }","duration":"188.778955ms","start":"2026-04-22T16:27:03.896121Z","end":"2026-04-22T16:27:04.0849Z","steps":["trace[1592485217] 'agreement among raft nodes before linearized reading' (duration: 188.724934ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:08.836565Z","caller":"traceutil/trace.go:171","msg":"trace[1196536302] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"110.582299ms","start":"2026-04-22T16:27:08.725959Z","end":"2026-04-22T16:27:08.836541Z","steps":["trace[1196536302] 'process raft request' (duration: 105.677751ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:08.850991Z","caller":"traceutil/trace.go:171","msg":"trace[53758974] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"124.705515ms","start":"2026-04-22T16:27:08.726272Z","end":"2026-04-22T16:27:08.850977Z","steps":["trace[53758974] 'process raft request' (duration: 124.553413ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:08.850937Z","caller":"traceutil/trace.go:171","msg":"trace[361568540] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:911; }","duration":"117.316776ms","start":"2026-04-22T16:27:08.7336Z","end":"2026-04-22T16:27:08.850917Z","steps":["trace[361568540] 'read index received' (duration: 97.994057ms)","trace[361568540] 'applied index is now lower than readState.Index' (duration: 19.321729ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:08.851387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.761473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/\" range_end:\"/registry/serviceaccounts/envoy-gateway-system0\" limit:10000 ","response":"range_response_count:3 size:1862"} {"level":"info","ts":"2026-04-22T16:27:08.85149Z","caller":"traceutil/trace.go:171","msg":"trace[497788778] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/; range_end:/registry/serviceaccounts/envoy-gateway-system0; response_count:3; response_revision:876; }","duration":"117.905576ms","start":"2026-04-22T16:27:08.73356Z","end":"2026-04-22T16:27:08.851466Z","steps":["trace[497788778] 'agreement among raft nodes before linearized reading' (duration: 117.587641ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.300885Z","caller":"traceutil/trace.go:171","msg":"trace[1023386870] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"114.030972ms","start":"2026-04-22T16:27:16.186835Z","end":"2026-04-22T16:27:16.300866Z","steps":["trace[1023386870] 'process raft request' (duration: 113.853799ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.300848Z","caller":"traceutil/trace.go:171","msg":"trace[73779165] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"112.937155ms","start":"2026-04-22T16:27:16.187891Z","end":"2026-04-22T16:27:16.300828Z","steps":["trace[73779165] 'read index received' (duration: 112.845774ms)","trace[73779165] 'applied index is now lower than readState.Index' (duration: 90.541µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:16.301218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.298381ms","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-22T16:27:16.301304Z","caller":"traceutil/trace.go:171","msg":"trace[1158314180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"113.420202ms","start":"2026-04-22T16:27:16.187865Z","end":"2026-04-22T16:27:16.301285Z","steps":["trace[1158314180] 'agreement among raft nodes before linearized reading' (duration: 113.158748ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.311699Z","caller":"traceutil/trace.go:171","msg":"trace[1778983312] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"123.70725ms","start":"2026-04-22T16:27:16.187961Z","end":"2026-04-22T16:27:16.311669Z","steps":["trace[1778983312] 'process raft request' (duration: 123.580168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:16.311739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.156991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T16:27:16.311792Z","caller":"traceutil/trace.go:171","msg":"trace[348950633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:964; }","duration":"123.223523ms","start":"2026-04-22T16:27:16.188556Z","end":"2026-04-22T16:27:16.311779Z","steps":["trace[348950633] 'agreement among raft nodes before linearized reading' (duration: 123.088031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:16.311811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.005189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-22T16:27:16.311875Z","caller":"traceutil/trace.go:171","msg":"trace[1405684804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:964; }","duration":"123.109621ms","start":"2026-04-22T16:27:16.188751Z","end":"2026-04-22T16:27:16.311861Z","steps":["trace[1405684804] 'agreement among raft nodes before linearized reading' (duration: 122.922768ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.431368Z","caller":"traceutil/trace.go:171","msg":"trace[647738128] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"126.797018ms","start":"2026-04-22T16:27:16.304546Z","end":"2026-04-22T16:27:16.431343Z","steps":["trace[647738128] 'process raft request' (duration: 121.635309ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.434662Z","caller":"traceutil/trace.go:171","msg":"trace[1631761416] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"119.196962ms","start":"2026-04-22T16:27:16.315444Z","end":"2026-04-22T16:27:16.434641Z","steps":["trace[1631761416] 'process raft request' (duration: 119.04346ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:16.434911Z","caller":"traceutil/trace.go:171","msg":"trace[473172500] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"119.388005ms","start":"2026-04-22T16:27:16.315497Z","end":"2026-04-22T16:27:16.434885Z","steps":["trace[473172500] 'process raft request' (duration: 119.10566ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:17.267378Z","caller":"traceutil/trace.go:171","msg":"trace[1434265914] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"173.386694ms","start":"2026-04-22T16:27:17.09396Z","end":"2026-04-22T16:27:17.267347Z","steps":["trace[1434265914] 'process raft request' (duration: 167.86022ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:18.376454Z","caller":"traceutil/trace.go:171","msg":"trace[689092057] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"338.331282ms","start":"2026-04-22T16:27:18.038014Z","end":"2026-04-22T16:27:18.376346Z","steps":["trace[689092057] 'process raft request' (duration: 338.102029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:18.376647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:18.037972Z","time spent":"338.560716ms","remote":"127.0.0.1:45018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5469,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:18.518996Z","caller":"traceutil/trace.go:171","msg":"trace[453487054] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"136.757137ms","start":"2026-04-22T16:27:18.382213Z","end":"2026-04-22T16:27:18.51897Z","steps":["trace[453487054] 'process raft request' (duration: 96.530349ms)","trace[453487054] 'compare' (duration: 40.093136ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:18.706088Z","caller":"traceutil/trace.go:171","msg":"trace[828559965] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"323.693452ms","start":"2026-04-22T16:27:18.382371Z","end":"2026-04-22T16:27:18.706064Z","steps":["trace[828559965] 'process raft request' (duration: 323.53216ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:18.706117Z","caller":"traceutil/trace.go:171","msg":"trace[198375022] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1011; }","duration":"262.509717ms","start":"2026-04-22T16:27:18.443585Z","end":"2026-04-22T16:27:18.706095Z","steps":["trace[198375022] 'read index received' (duration: 35.130262ms)","trace[198375022] 'applied index is now lower than readState.Index' (duration: 227.378485ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:18.706158Z","caller":"traceutil/trace.go:171","msg":"trace[1264037028] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"322.34226ms","start":"2026-04-22T16:27:18.383807Z","end":"2026-04-22T16:27:18.706149Z","steps":["trace[1264037028] 'process raft request' (duration: 322.238009ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:18.706165Z","caller":"traceutil/trace.go:171","msg":"trace[1386045191] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"322.792297ms","start":"2026-04-22T16:27:18.383349Z","end":"2026-04-22T16:27:18.706142Z","steps":["trace[1386045191] 'process raft request' (duration: 322.650255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:18.706207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:18.382357Z","time spent":"323.790853ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:18.706266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:18.383796Z","time spent":"322.402002ms","remote":"127.0.0.1:45124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:18.70607Z","caller":"traceutil/trace.go:171","msg":"trace[226985952] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"323.703252ms","start":"2026-04-22T16:27:18.382339Z","end":"2026-04-22T16:27:18.706043Z","steps":["trace[226985952] 'process raft request' (duration: 256.343224ms)","trace[226985952] 'compare' (duration: 66.991632ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:18.706278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:18.383334Z","time spent":"322.881578ms","remote":"127.0.0.1:45324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:18.706318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:18.382312Z","time spent":"323.986306ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:18.706337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.728341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-22T16:27:18.706347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.293534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-22T16:27:18.706371Z","caller":"traceutil/trace.go:171","msg":"trace[2069691377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:977; }","duration":"262.809031ms","start":"2026-04-22T16:27:18.443552Z","end":"2026-04-22T16:27:18.706361Z","steps":["trace[2069691377] 'agreement among raft nodes before linearized reading' (duration: 262.671559ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:18.70638Z","caller":"traceutil/trace.go:171","msg":"trace[1686846380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:977; }","duration":"262.372176ms","start":"2026-04-22T16:27:18.443998Z","end":"2026-04-22T16:27:18.70637Z","steps":["trace[1686846380] 'agreement among raft nodes before linearized reading' (duration: 262.263164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:18.706426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.161042ms","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-22T16:27:18.70645Z","caller":"traceutil/trace.go:171","msg":"trace[825723698] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:977; }","duration":"262.200013ms","start":"2026-04-22T16:27:18.444243Z","end":"2026-04-22T16:27:18.706443Z","steps":["trace[825723698] 'agreement among raft nodes before linearized reading' (duration: 262.110321ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:19.101793Z","caller":"traceutil/trace.go:171","msg":"trace[949487184] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"139.376309ms","start":"2026-04-22T16:27:18.962396Z","end":"2026-04-22T16:27:19.101772Z","steps":["trace[949487184] 'process raft request' (duration: 139.263367ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:35.247347Z","caller":"traceutil/trace.go:171","msg":"trace[436877538] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"427.909927ms","start":"2026-04-22T16:27:34.819385Z","end":"2026-04-22T16:27:35.247295Z","steps":["trace[436877538] 'process raft request' (duration: 369.507296ms)","trace[436877538] 'compare' (duration: 57.809512ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:35.247558Z","caller":"traceutil/trace.go:171","msg":"trace[597000726] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"424.162225ms","start":"2026-04-22T16:27:34.823372Z","end":"2026-04-22T16:27:35.247534Z","steps":["trace[597000726] 'process raft request' (duration: 423.673588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:35.247617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:34.81909Z","time spent":"428.473915ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:35.24768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:34.823349Z","time spent":"424.264456ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:35.247797Z","caller":"traceutil/trace.go:171","msg":"trace[118719993] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"424.248137ms","start":"2026-04-22T16:27:34.823521Z","end":"2026-04-22T16:27:35.247769Z","steps":["trace[118719993] 'process raft request' (duration: 423.931952ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:35.247919Z","caller":"traceutil/trace.go:171","msg":"trace[1621972606] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"421.327486ms","start":"2026-04-22T16:27:34.826565Z","end":"2026-04-22T16:27:35.247893Z","steps":["trace[1621972606] 'process raft request' (duration: 421.089153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:35.24805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:34.823618Z","time spent":"424.359158ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:35.248063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:34.8235Z","time spent":"424.430429ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:41.470411Z","caller":"traceutil/trace.go:171","msg":"trace[1147224179] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"162.279097ms","start":"2026-04-22T16:27:41.308082Z","end":"2026-04-22T16:27:41.470361Z","steps":["trace[1147224179] 'process raft request' (duration: 155.754909ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:41.884228Z","caller":"traceutil/trace.go:171","msg":"trace[1982488081] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"575.762955ms","start":"2026-04-22T16:27:41.30844Z","end":"2026-04-22T16:27:41.884203Z","steps":["trace[1982488081] 'process raft request' (duration: 501.951345ms)","trace[1982488081] 'compare' (duration: 73.597267ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:41.884245Z","caller":"traceutil/trace.go:171","msg":"trace[1987124623] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"575.334679ms","start":"2026-04-22T16:27:41.308887Z","end":"2026-04-22T16:27:41.884221Z","steps":["trace[1987124623] 'process raft request' (duration: 575.292208ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:41.88431Z","caller":"traceutil/trace.go:171","msg":"trace[913673881] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"575.529932ms","start":"2026-04-22T16:27:41.308766Z","end":"2026-04-22T16:27:41.884296Z","steps":["trace[913673881] 'process raft request' (duration: 575.344509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:41.884329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:41.308419Z","time spent":"575.858057ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:41.884366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:41.308758Z","time spent":"575.579612ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:27:41.884431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:41.308859Z","time spent":"575.464501ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:45.119077Z","caller":"traceutil/trace.go:171","msg":"trace[1565057046] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"227.528845ms","start":"2026-04-22T16:27:44.89153Z","end":"2026-04-22T16:27:45.119059Z","steps":["trace[1565057046] 'process raft request' (duration: 227.396834ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:46.010528Z","caller":"traceutil/trace.go:171","msg":"trace[1860724316] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"101.29283ms","start":"2026-04-22T16:27:45.909187Z","end":"2026-04-22T16:27:46.01048Z","steps":["trace[1860724316] 'process raft request' (duration: 101.060967ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.404514Z","caller":"traceutil/trace.go:171","msg":"trace[1762028886] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"239.329141ms","start":"2026-04-22T16:28:06.165155Z","end":"2026-04-22T16:28:06.404484Z","steps":["trace[1762028886] 'process raft request' (duration: 237.70781ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:06.405556Z","caller":"traceutil/trace.go:171","msg":"trace[414696216] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"239.886767ms","start":"2026-04-22T16:28:06.16565Z","end":"2026-04-22T16:28:06.405537Z","steps":["trace[414696216] 'process raft request' (duration: 239.797186ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:16.368968Z","caller":"traceutil/trace.go:171","msg":"trace[714993966] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"103.985078ms","start":"2026-04-22T16:28:16.264963Z","end":"2026-04-22T16:28:16.368948Z","steps":["trace[714993966] 'process raft request' (duration: 103.846377ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:23.7061Z","caller":"traceutil/trace.go:171","msg":"trace[1095120912] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"127.428893ms","start":"2026-04-22T16:28:23.578654Z","end":"2026-04-22T16:28:23.706082Z","steps":["trace[1095120912] 'process raft request' (duration: 127.328952ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:25.829576Z","caller":"traceutil/trace.go:171","msg":"trace[1902008928] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"117.823793ms","start":"2026-04-22T16:28:25.711731Z","end":"2026-04-22T16:28:25.829555Z","steps":["trace[1902008928] 'process raft request' (duration: 117.701862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:48.778758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.023499ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:48.778873Z","caller":"traceutil/trace.go:171","msg":"trace[941757757] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"139.306658ms","start":"2026-04-22T16:28:48.639551Z","end":"2026-04-22T16:28:48.778857Z","steps":["trace[941757757] 'read index received' (duration: 22.090217ms)","trace[941757757] 'applied index is now lower than readState.Index' (duration: 117.215391ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:48.778925Z","caller":"traceutil/trace.go:171","msg":"trace[1460605228] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"201.655606ms","start":"2026-04-22T16:28:48.577236Z","end":"2026-04-22T16:28:48.778892Z","steps":["trace[1460605228] 'process raft request' (duration: 84.416344ms)","trace[1460605228] 'compare' (duration: 116.881818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:48.779152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.597511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:28:48.779223Z","caller":"traceutil/trace.go:171","msg":"trace[41205431] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"139.709933ms","start":"2026-04-22T16:28:48.639498Z","end":"2026-04-22T16:28:48.779208Z","steps":["trace[41205431] 'agreement among raft nodes before linearized reading' (duration: 139.486781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:48.779238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.134672ms","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-22T16:28:48.77928Z","caller":"traceutil/trace.go:171","msg":"trace[286836185] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"128.203634ms","start":"2026-04-22T16:28:48.651065Z","end":"2026-04-22T16:28:48.779269Z","steps":["trace[286836185] 'agreement among raft nodes before linearized reading' (duration: 128.046422ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.943243Z","caller":"traceutil/trace.go:171","msg":"trace[766921377] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"159.475144ms","start":"2026-04-22T16:28:48.783736Z","end":"2026-04-22T16:28:48.943211Z","steps":["trace[766921377] 'process raft request' (duration: 115.207759ms)","trace[766921377] 'compare' (duration: 44.022653ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:48.94329Z","caller":"traceutil/trace.go:171","msg":"trace[70528659] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"120.131764ms","start":"2026-04-22T16:28:48.82314Z","end":"2026-04-22T16:28:48.943272Z","steps":["trace[70528659] 'process raft request' (duration: 120.017723ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:49.398492Z","caller":"traceutil/trace.go:171","msg":"trace[1197880604] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"213.07827ms","start":"2026-04-22T16:28:49.185392Z","end":"2026-04-22T16:28:49.39847Z","steps":["trace[1197880604] 'process raft request' (duration: 212.765367ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:49.919181Z","caller":"traceutil/trace.go:171","msg":"trace[152605276] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"293.462117ms","start":"2026-04-22T16:28:49.625699Z","end":"2026-04-22T16:28:49.919161Z","steps":["trace[152605276] 'process raft request' (duration: 226.340317ms)","trace[152605276] 'compare' (duration: 66.773866ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:50.066532Z","caller":"traceutil/trace.go:171","msg":"trace[732045542] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"134.902445ms","start":"2026-04-22T16:28:49.931591Z","end":"2026-04-22T16:28:50.066494Z","steps":["trace[732045542] 'process raft request' (duration: 105.132693ms)","trace[732045542] 'compare' (duration: 29.53643ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:50.14567Z","caller":"traceutil/trace.go:171","msg":"trace[1640450413] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"144.320688ms","start":"2026-04-22T16:28:50.001318Z","end":"2026-04-22T16:28:50.145639Z","steps":["trace[1640450413] 'process raft request' (duration: 144.177767ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:50.291577Z","caller":"traceutil/trace.go:171","msg":"trace[1269266522] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"141.72755ms","start":"2026-04-22T16:28:50.149819Z","end":"2026-04-22T16:28:50.291546Z","steps":["trace[1269266522] 'process raft request' (duration: 111.759565ms)","trace[1269266522] 'compare' (duration: 29.649571ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:50.291616Z","caller":"traceutil/trace.go:171","msg":"trace[1382201090] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"140.82659ms","start":"2026-04-22T16:28:50.150732Z","end":"2026-04-22T16:28:50.291558Z","steps":["trace[1382201090] 'process raft request' (duration: 140.718679ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:51.154376Z","caller":"traceutil/trace.go:171","msg":"trace[906329515] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"152.596728ms","start":"2026-04-22T16:28:51.001747Z","end":"2026-04-22T16:28:51.154343Z","steps":["trace[906329515] 'process raft request' (duration: 136.832273ms)","trace[906329515] 'compare' (duration: 15.630554ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:52.025882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.253713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:52.025948Z","caller":"traceutil/trace.go:171","msg":"trace[1468659506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"134.378825ms","start":"2026-04-22T16:28:51.891556Z","end":"2026-04-22T16:28:52.025935Z","steps":["trace[1468659506] 'range keys from in-memory index tree' (duration: 134.109072ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:52.264792Z","caller":"traceutil/trace.go:171","msg":"trace[1866493595] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"193.730794ms","start":"2026-04-22T16:28:52.071033Z","end":"2026-04-22T16:28:52.264764Z","steps":["trace[1866493595] 'process raft request' (duration: 193.552591ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:52.265021Z","caller":"traceutil/trace.go:171","msg":"trace[39203065] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1387; }","duration":"107.605427ms","start":"2026-04-22T16:28:52.157398Z","end":"2026-04-22T16:28:52.265004Z","steps":["trace[39203065] 'read index received' (duration: 107.600697ms)","trace[39203065] 'applied index is now lower than readState.Index' (duration: 3.48µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:52.265155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.742538ms","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-22T16:28:52.265185Z","caller":"traceutil/trace.go:171","msg":"trace[826647039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1329; }","duration":"107.817629ms","start":"2026-04-22T16:28:52.157358Z","end":"2026-04-22T16:28:52.265176Z","steps":["trace[826647039] 'agreement among raft nodes before linearized reading' (duration: 107.704887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:52.598947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.105056ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-22T16:28:52.599674Z","caller":"traceutil/trace.go:171","msg":"trace[1972024513] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"329.343048ms","start":"2026-04-22T16:28:52.270301Z","end":"2026-04-22T16:28:52.599644Z","steps":["trace[1972024513] 'process raft request' (duration: 329.164826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:52.599948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.270282Z","time spent":"329.563411ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:54.315366Z","caller":"traceutil/trace.go:171","msg":"trace[1734144894] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"423.28603ms","start":"2026-04-22T16:28:53.892054Z","end":"2026-04-22T16:28:54.315341Z","steps":["trace[1734144894] 'read index received' (duration: 423.027097ms)","trace[1734144894] 'applied index is now lower than readState.Index' (duration: 257.553µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:54.315389Z","caller":"traceutil/trace.go:171","msg":"trace[718845850] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"640.5761ms","start":"2026-04-22T16:28:53.674777Z","end":"2026-04-22T16:28:54.315353Z","steps":["trace[718845850] 'process raft request' (duration: 640.339016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:54.315482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.423232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:54.315514Z","caller":"traceutil/trace.go:171","msg":"trace[1982565998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"423.513882ms","start":"2026-04-22T16:28:53.891992Z","end":"2026-04-22T16:28:54.315506Z","steps":["trace[1982565998] 'agreement among raft nodes before linearized reading' (duration: 423.445711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:54.315538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:53.891964Z","time spent":"423.568753ms","remote":"127.0.0.1:44814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:28:54.315635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:53.674756Z","time spent":"640.710751ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:02.844026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368950223459315,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:29:03.185659Z","caller":"traceutil/trace.go:171","msg":"trace[1363361810] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1427; }","duration":"841.709854ms","start":"2026-04-22T16:29:02.343927Z","end":"2026-04-22T16:29:03.185637Z","steps":["trace[1363361810] 'read index received' (duration: 767.018248ms)","trace[1363361810] 'applied index is now lower than readState.Index' (duration: 74.690776ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:29:03.185758Z","caller":"traceutil/trace.go:171","msg":"trace[87788449] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"845.060071ms","start":"2026-04-22T16:29:02.340671Z","end":"2026-04-22T16:29:03.185731Z","steps":["trace[87788449] 'process raft request' (duration: 844.843559ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:29:03.185894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.340644Z","time spent":"845.179933ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:29:03.210065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.522424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:29:03.210129Z","caller":"traceutil/trace.go:171","msg":"trace[1009412601] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1368; }","duration":"246.639555ms","start":"2026-04-22T16:29:02.963474Z","end":"2026-04-22T16:29:03.210113Z","steps":["trace[1009412601] 'agreement among raft nodes before linearized reading' (duration: 222.35809ms)","trace[1009412601] 'count revisions from in-memory index tree' (duration: 24.168394ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.210227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.133537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:29:03.210252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.924224ms","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-22T16:29:03.210302Z","caller":"traceutil/trace.go:171","msg":"trace[237992332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"318.235529ms","start":"2026-04-22T16:29:02.89204Z","end":"2026-04-22T16:29:03.210276Z","steps":["trace[237992332] 'agreement among raft nodes before linearized reading' (duration: 293.803092ms)","trace[237992332] 'range keys from in-memory index tree' (duration: 24.303746ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.21037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.892023Z","time spent":"318.33308ms","remote":"127.0.0.1:44826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-22T16:29:03.210456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.516005ms","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-22T16:29:03.210119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.609661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:29:03.210552Z","caller":"traceutil/trace.go:171","msg":"trace[1544655684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"866.661886ms","start":"2026-04-22T16:29:02.343866Z","end":"2026-04-22T16:29:03.210528Z","steps":["trace[1544655684] 'agreement among raft nodes before linearized reading' (duration: 841.900425ms)","trace[1544655684] 'range keys from in-memory index tree' (duration: 24.548188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.210602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.343845Z","time spent":"866.748068ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-22T16:29:03.21071Z","caller":"traceutil/trace.go:171","msg":"trace[376178575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"497.016554ms","start":"2026-04-22T16:29:02.713277Z","end":"2026-04-22T16:29:03.210294Z","steps":["trace[376178575] 'agreement among raft nodes before linearized reading' (duration: 472.544507ms)","trace[376178575] 'range keys from in-memory index tree' (duration: 24.277495ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.210502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.710351ms","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-22T16:29:03.210763Z","caller":"traceutil/trace.go:171","msg":"trace[758806318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"167.004704ms","start":"2026-04-22T16:29:03.04375Z","end":"2026-04-22T16:29:03.210755Z","steps":["trace[758806318] 'agreement among raft nodes before linearized reading' (duration: 142.055852ms)","trace[758806318] 'range keys from in-memory index tree' (duration: 24.631419ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.21077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.713232Z","time spent":"497.5235ms","remote":"127.0.0.1:45120","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-22T16:29:03.210776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.918704ms","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-22T16:29:03.210968Z","caller":"traceutil/trace.go:171","msg":"trace[1563288321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"574.331119ms","start":"2026-04-22T16:29:02.636585Z","end":"2026-04-22T16:29:03.210916Z","steps":["trace[1563288321] 'agreement among raft nodes before linearized reading' (duration: 549.205955ms)","trace[1563288321] 'range keys from in-memory index tree' (duration: 24.477327ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.211029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.636565Z","time spent":"574.44922ms","remote":"127.0.0.1:45120","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-22T16:29:03.210761Z","caller":"traceutil/trace.go:171","msg":"trace[2006377415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1368; }","duration":"338.123786ms","start":"2026-04-22T16:29:02.872422Z","end":"2026-04-22T16:29:03.210546Z","steps":["trace[2006377415] 'agreement among raft nodes before linearized reading' (duration: 313.391806ms)","trace[2006377415] 'range keys from in-memory index tree' (duration: 24.141984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:29:03.2114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:29:02.872392Z","time spent":"338.993336ms","remote":"127.0.0.1:45120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}