{"level":"warn","ts":"2026-02-25T17:04:11.766545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:04:11.766635Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.234: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.234:2380","--initial-cluster=instance=https://199.19.213.234:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.234:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.234:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-25T17:04:11.766742Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T17:04:11.766756Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.234:2380"]} {"level":"info","ts":"2026-02-25T17:04:11.766779Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T17:04:11.767458Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.234:2379"]} {"level":"info","ts":"2026-02-25T17:04:11.767531Z","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.234:2380"],"listen-peer-urls":["https://199.19.213.234:2380"],"advertise-client-urls":["https://199.19.213.234:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.234:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.234:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-25T17:04:11.779443Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.675077ms"} {"level":"info","ts":"2026-02-25T17:04:11.792351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"985a0f2e99e4506a","cluster-id":"b70d9df6d0cb8c15"} {"level":"info","ts":"2026-02-25T17:04:11.792455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T17:04:11.792488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a became follower at term 0"} {"level":"info","ts":"2026-02-25T17:04:11.792505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 985a0f2e99e4506a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T17:04:11.792518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a became follower at term 1"} {"level":"info","ts":"2026-02-25T17:04:11.792565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a switched to configuration voters=(10978103734493794410)"} {"level":"warn","ts":"2026-02-25T17:04:11.807587Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T17:04:11.809987Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T17:04:11.812414Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-25T17:04:11.815746Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"985a0f2e99e4506a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T17:04:11.816214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"985a0f2e99e4506a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T17:04:11.816285Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T17:04:11.816476Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T17:04:11.816504Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-25T17:04:11.818142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a switched to configuration voters=(10978103734493794410)"} {"level":"info","ts":"2026-02-25T17:04:11.818309Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b70d9df6d0cb8c15","local-member-id":"985a0f2e99e4506a","added-peer-id":"985a0f2e99e4506a","added-peer-peer-urls":["https://199.19.213.234:2380"]} {"level":"info","ts":"2026-02-25T17:04:11.820956Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-25T17:04:11.821206Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.234:2380"} {"level":"info","ts":"2026-02-25T17:04:11.82126Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.234:2380"} {"level":"info","ts":"2026-02-25T17:04:11.821254Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"985a0f2e99e4506a","initial-advertise-peer-urls":["https://199.19.213.234:2380"],"listen-peer-urls":["https://199.19.213.234:2380"],"advertise-client-urls":["https://199.19.213.234:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.234:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T17:04:11.821304Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T17:04:12.693826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T17:04:12.693892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T17:04:12.693933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a received MsgPreVoteResp from 985a0f2e99e4506a at term 1"} {"level":"info","ts":"2026-02-25T17:04:12.693958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a became candidate at term 2"} {"level":"info","ts":"2026-02-25T17:04:12.693969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a received MsgVoteResp from 985a0f2e99e4506a at term 2"} {"level":"info","ts":"2026-02-25T17:04:12.693985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"985a0f2e99e4506a became leader at term 2"} {"level":"info","ts":"2026-02-25T17:04:12.693999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 985a0f2e99e4506a elected leader 985a0f2e99e4506a at term 2"} {"level":"info","ts":"2026-02-25T17:04:12.69561Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:12.695966Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"985a0f2e99e4506a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.234:2379]}","request-path":"/0/members/985a0f2e99e4506a/attributes","cluster-id":"b70d9df6d0cb8c15","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T17:04:12.695972Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:12.696054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T17:04:12.696733Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b70d9df6d0cb8c15","local-member-id":"985a0f2e99e4506a","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:12.696722Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T17:04:12.696812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T17:04:12.696862Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:12.696901Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T17:04:12.699508Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.234:2379"} {"level":"info","ts":"2026-02-25T17:04:12.700986Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T17:04:14.090369Z","caller":"traceutil/trace.go:171","msg":"trace[1805042192] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"112.844741ms","start":"2026-02-25T17:04:13.977502Z","end":"2026-02-25T17:04:14.090347Z","steps":["trace[1805042192] 'read index received' (duration: 112.411681ms)","trace[1805042192] 'applied index is now lower than readState.Index' (duration: 432.1µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:14.090414Z","caller":"traceutil/trace.go:171","msg":"trace[1294068033] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"116.007826ms","start":"2026-02-25T17:04:13.974377Z","end":"2026-02-25T17:04:14.090384Z","steps":["trace[1294068033] 'process raft request' (duration: 115.822872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.09057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.043177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.090788Z","caller":"traceutil/trace.go:171","msg":"trace[1764104691] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"113.293812ms","start":"2026-02-25T17:04:13.97748Z","end":"2026-02-25T17:04:14.090774Z","steps":["trace[1764104691] 'agreement among raft nodes before linearized reading' (duration: 112.983495ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.185301Z","caller":"traceutil/trace.go:171","msg":"trace[1970216423] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"141.89132ms","start":"2026-02-25T17:04:14.043382Z","end":"2026-02-25T17:04:14.185273Z","steps":["trace[1970216423] 'process raft request' (duration: 141.586633ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.185347Z","caller":"traceutil/trace.go:171","msg":"trace[2023182827] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"141.793748ms","start":"2026-02-25T17:04:14.043522Z","end":"2026-02-25T17:04:14.185316Z","steps":["trace[2023182827] 'process raft request' (duration: 141.665005ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.185661Z","caller":"traceutil/trace.go:171","msg":"trace[499296174] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"141.655904ms","start":"2026-02-25T17:04:14.043746Z","end":"2026-02-25T17:04:14.185402Z","steps":["trace[499296174] 'process raft request' (duration: 141.4857ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.185685Z","caller":"traceutil/trace.go:171","msg":"trace[1654579621] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"141.534721ms","start":"2026-02-25T17:04:14.044115Z","end":"2026-02-25T17:04:14.185649Z","steps":["trace[1654579621] 'process raft request' (duration: 141.148362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.186115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.397886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.234\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.186253Z","caller":"traceutil/trace.go:171","msg":"trace[945454706] range","detail":"{range_begin:/registry/masterleases/199.19.213.234; range_end:; response_count:0; response_revision:10; }","duration":"146.581911ms","start":"2026-02-25T17:04:14.039653Z","end":"2026-02-25T17:04:14.186235Z","steps":["trace[945454706] 'agreement among raft nodes before linearized reading' (duration: 146.343015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.186396Z","caller":"traceutil/trace.go:171","msg":"trace[698660451] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"142.022182ms","start":"2026-02-25T17:04:14.044354Z","end":"2026-02-25T17:04:14.186376Z","steps":["trace[698660451] 'process raft request' (duration: 141.443608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.186408Z","caller":"traceutil/trace.go:171","msg":"trace[251922358] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"128.362458ms","start":"2026-02-25T17:04:14.057948Z","end":"2026-02-25T17:04:14.18631Z","steps":["trace[251922358] 'process raft request' (duration: 127.960369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.18644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.352391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-25T17:04:14.186192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.270929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.186493Z","caller":"traceutil/trace.go:171","msg":"trace[1368054107] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"145.433342ms","start":"2026-02-25T17:04:14.041044Z","end":"2026-02-25T17:04:14.186477Z","steps":["trace[1368054107] 'agreement among raft nodes before linearized reading' (duration: 145.3324ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.186535Z","caller":"traceutil/trace.go:171","msg":"trace[647159932] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"156.634658ms","start":"2026-02-25T17:04:14.029871Z","end":"2026-02-25T17:04:14.186506Z","steps":["trace[647159932] 'agreement among raft nodes before linearized reading' (duration: 156.127856ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.186669Z","caller":"traceutil/trace.go:171","msg":"trace[653823037] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"129.126227ms","start":"2026-02-25T17:04:14.057527Z","end":"2026-02-25T17:04:14.186654Z","steps":["trace[653823037] 'process raft request' (duration: 128.307088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.186271Z","caller":"traceutil/trace.go:171","msg":"trace[596956421] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"142.091504ms","start":"2026-02-25T17:04:14.044164Z","end":"2026-02-25T17:04:14.186256Z","steps":["trace[596956421] 'process raft request' (duration: 141.321476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.186322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.506085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.186846Z","caller":"traceutil/trace.go:171","msg":"trace[644961216] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"157.06342ms","start":"2026-02-25T17:04:14.029767Z","end":"2026-02-25T17:04:14.186831Z","steps":["trace[644961216] 'agreement among raft nodes before linearized reading' (duration: 156.489695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.186314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.024833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.187061Z","caller":"traceutil/trace.go:171","msg":"trace[2051609012] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"145.758342ms","start":"2026-02-25T17:04:14.041253Z","end":"2026-02-25T17:04:14.187012Z","steps":["trace[2051609012] 'agreement among raft nodes before linearized reading' (duration: 145.017834ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.186155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.507306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.187264Z","caller":"traceutil/trace.go:171","msg":"trace[1971615086] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"157.654173ms","start":"2026-02-25T17:04:14.029594Z","end":"2026-02-25T17:04:14.187248Z","steps":["trace[1971615086] 'agreement among raft nodes before linearized reading' (duration: 156.447984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.187263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.123922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.191164Z","caller":"traceutil/trace.go:171","msg":"trace[352356685] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"204.993864ms","start":"2026-02-25T17:04:13.9861Z","end":"2026-02-25T17:04:14.191094Z","steps":["trace[352356685] 'agreement among raft nodes before linearized reading' (duration: 200.128429ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:14.207064Z","caller":"traceutil/trace.go:171","msg":"trace[687651956] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"114.974083ms","start":"2026-02-25T17:04:14.09207Z","end":"2026-02-25T17:04:14.207045Z","steps":["trace[687651956] 'process raft request' (duration: 114.877381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:14.207303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.634926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-25T17:04:14.207419Z","caller":"traceutil/trace.go:171","msg":"trace[1293614007] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"101.79034ms","start":"2026-02-25T17:04:14.10561Z","end":"2026-02-25T17:04:14.207401Z","steps":["trace[1293614007] 'agreement among raft nodes before linearized reading' (duration: 101.490943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:16.455745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.199044ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:16.456057Z","caller":"traceutil/trace.go:171","msg":"trace[756798074] transaction","detail":"{read_only:false; response_revision:192; number_of_response:1; }","duration":"649.376315ms","start":"2026-02-25T17:04:15.806639Z","end":"2026-02-25T17:04:16.456015Z","steps":["trace[756798074] 'process raft request' (duration: 286.544294ms)","trace[756798074] 'compare' (duration: 359.068001ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:16.456017Z","caller":"traceutil/trace.go:171","msg":"trace[77298366] linearizableReadLoop","detail":"{readStateIndex:196; appliedIndex:195; }","duration":"492.960071ms","start":"2026-02-25T17:04:15.962957Z","end":"2026-02-25T17:04:16.455917Z","steps":["trace[77298366] 'read index received' (duration: 130.300764ms)","trace[77298366] 'applied index is now lower than readState.Index' (duration: 362.654487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:16.456269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.806611Z","time spent":"649.57316ms","remote":"127.0.0.1:57160","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:16.45653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.545695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:16.457114Z","caller":"traceutil/trace.go:171","msg":"trace[809001300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:192; }","duration":"494.17196ms","start":"2026-02-25T17:04:15.962905Z","end":"2026-02-25T17:04:16.457077Z","steps":["trace[809001300] 'agreement among raft nodes before linearized reading' (duration: 493.182597ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:16.457207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:15.962877Z","time spent":"494.312713ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:17.565985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.241288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:17.566103Z","caller":"traceutil/trace.go:171","msg":"trace[719847837] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"1.104033513s","start":"2026-02-25T17:04:16.462051Z","end":"2026-02-25T17:04:17.566085Z","steps":["trace[719847837] 'process raft request' (duration: 396.474047ms)","trace[719847837] 'compare' (duration: 707.138106ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:17.566186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:16.462021Z","time spent":"1.104133516s","remote":"127.0.0.1:56850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:17.991215Z","caller":"traceutil/trace.go:171","msg":"trace[576246437] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:196; }","duration":"1.182305018s","start":"2026-02-25T17:04:16.808887Z","end":"2026-02-25T17:04:17.991192Z","steps":["trace[576246437] 'read index received' (duration: 49.629435ms)","trace[576246437] 'applied index is now lower than readState.Index' (duration: 1.132674643s)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:17.991323Z","caller":"traceutil/trace.go:171","msg":"trace[1859040317] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"1.422764301s","start":"2026-02-25T17:04:16.568549Z","end":"2026-02-25T17:04:17.991313Z","steps":["trace[1859040317] 'process raft request' (duration: 1.422466254s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:17.991394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:16.568522Z","time spent":"1.422821602s","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":985,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:04:17.991457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182549953s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:17.991545Z","caller":"traceutil/trace.go:171","msg":"trace[463597148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:194; }","duration":"1.182671267s","start":"2026-02-25T17:04:16.808852Z","end":"2026-02-25T17:04:17.991523Z","steps":["trace[463597148] 'agreement among raft nodes before linearized reading' (duration: 1.182481712s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:17.991594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:16.808836Z","time spent":"1.182747578s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T17:04:17.993498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.060789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:17.993523Z","caller":"traceutil/trace.go:171","msg":"trace[1501162223] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"425.503325ms","start":"2026-02-25T17:04:17.567999Z","end":"2026-02-25T17:04:17.993502Z","steps":["trace[1501162223] 'process raft request' (duration: 425.376102ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:17.993549Z","caller":"traceutil/trace.go:171","msg":"trace[497854121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:195; }","duration":"945.143542ms","start":"2026-02-25T17:04:17.048391Z","end":"2026-02-25T17:04:17.993535Z","steps":["trace[497854121] 'agreement among raft nodes before linearized reading' (duration: 945.05554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:17.993757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:17.048374Z","time spent":"945.371398ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T17:04:17.993759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:17.567966Z","time spent":"425.7157ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:04:19.615208Z","caller":"traceutil/trace.go:171","msg":"trace[1309154705] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"107.324461ms","start":"2026-02-25T17:04:19.507845Z","end":"2026-02-25T17:04:19.615169Z","steps":["trace[1309154705] 'process raft request' (duration: 29.114449ms)","trace[1309154705] 'compare' (duration: 77.912565ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:19.754553Z","caller":"traceutil/trace.go:171","msg":"trace[101409906] linearizableReadLoop","detail":"{readStateIndex:295; appliedIndex:294; }","duration":"114.04608ms","start":"2026-02-25T17:04:19.640482Z","end":"2026-02-25T17:04:19.754528Z","steps":["trace[101409906] 'read index received' (duration: 94.072078ms)","trace[101409906] 'applied index is now lower than readState.Index' (duration: 19.973082ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:19.754605Z","caller":"traceutil/trace.go:171","msg":"trace[824847436] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"121.128737ms","start":"2026-02-25T17:04:19.633438Z","end":"2026-02-25T17:04:19.754567Z","steps":["trace[824847436] 'process raft request' (duration: 101.103473ms)","trace[824847436] 'compare' (duration: 19.863971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:19.754843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.027628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T17:04:19.754923Z","caller":"traceutil/trace.go:171","msg":"trace[1014408610] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:289; }","duration":"130.136111ms","start":"2026-02-25T17:04:19.62477Z","end":"2026-02-25T17:04:19.754906Z","steps":["trace[1014408610] 'agreement among raft nodes before linearized reading' (duration: 129.878895ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:19.757682Z","caller":"traceutil/trace.go:171","msg":"trace[1979508514] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"113.754433ms","start":"2026-02-25T17:04:19.643906Z","end":"2026-02-25T17:04:19.75766Z","steps":["trace[1979508514] 'process raft request' (duration: 113.605519ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:19.757869Z","caller":"traceutil/trace.go:171","msg":"trace[1266339158] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"113.544468ms","start":"2026-02-25T17:04:19.644308Z","end":"2026-02-25T17:04:19.757852Z","steps":["trace[1266339158] 'process raft request' (duration: 113.303693ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:19.75836Z","caller":"traceutil/trace.go:171","msg":"trace[304589667] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"101.384871ms","start":"2026-02-25T17:04:19.656965Z","end":"2026-02-25T17:04:19.75835Z","steps":["trace[304589667] 'process raft request' (duration: 101.173746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:20.112096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.817764ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:20.112212Z","caller":"traceutil/trace.go:171","msg":"trace[1571214724] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"104.846253ms","start":"2026-02-25T17:04:20.007356Z","end":"2026-02-25T17:04:20.112202Z","steps":["trace[1571214724] 'process raft request' (duration: 104.811862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.112255Z","caller":"traceutil/trace.go:171","msg":"trace[217089833] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"189.252511ms","start":"2026-02-25T17:04:19.92296Z","end":"2026-02-25T17:04:20.112213Z","steps":["trace[217089833] 'process raft request' (duration: 70.273814ms)","trace[217089833] 'compare' (duration: 118.715451ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:20.112375Z","caller":"traceutil/trace.go:171","msg":"trace[997245170] linearizableReadLoop","detail":"{readStateIndex:314; appliedIndex:313; }","duration":"155.563764ms","start":"2026-02-25T17:04:19.956734Z","end":"2026-02-25T17:04:20.112298Z","steps":["trace[997245170] 'read index received' (duration: 36.578727ms)","trace[997245170] 'applied index is now lower than readState.Index' (duration: 118.982107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:20.112477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.741498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-02-25T17:04:20.112521Z","caller":"traceutil/trace.go:171","msg":"trace[603481778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:307; }","duration":"155.816039ms","start":"2026-02-25T17:04:19.956694Z","end":"2026-02-25T17:04:20.11251Z","steps":["trace[603481778] 'agreement among raft nodes before linearized reading' (duration: 155.719827ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.233973Z","caller":"traceutil/trace.go:171","msg":"trace[27134210] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"114.406229ms","start":"2026-02-25T17:04:20.119533Z","end":"2026-02-25T17:04:20.233939Z","steps":["trace[27134210] 'process raft request' (duration: 113.960909ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.233997Z","caller":"traceutil/trace.go:171","msg":"trace[1953734389] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:315; }","duration":"112.199667ms","start":"2026-02-25T17:04:20.121771Z","end":"2026-02-25T17:04:20.233971Z","steps":["trace[1953734389] 'read index received' (duration: 28.857154ms)","trace[1953734389] 'applied index is now lower than readState.Index' (duration: 83.341433ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:20.234191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.397762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T17:04:20.234242Z","caller":"traceutil/trace.go:171","msg":"trace[750064055] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"111.795818ms","start":"2026-02-25T17:04:20.122412Z","end":"2026-02-25T17:04:20.234208Z","steps":["trace[750064055] 'process raft request' (duration: 111.493531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.234178Z","caller":"traceutil/trace.go:171","msg":"trace[1533382980] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"113.713612ms","start":"2026-02-25T17:04:20.12034Z","end":"2026-02-25T17:04:20.234054Z","steps":["trace[1533382980] 'process raft request' (duration: 113.490447ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.234283Z","caller":"traceutil/trace.go:171","msg":"trace[602402881] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:311; }","duration":"112.525804ms","start":"2026-02-25T17:04:20.121738Z","end":"2026-02-25T17:04:20.234264Z","steps":["trace[602402881] 'agreement among raft nodes before linearized reading' (duration: 112.391651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.793754Z","caller":"traceutil/trace.go:171","msg":"trace[2109962688] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"135.671332ms","start":"2026-02-25T17:04:20.658058Z","end":"2026-02-25T17:04:20.793729Z","steps":["trace[2109962688] 'process raft request' (duration: 135.642151ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.794067Z","caller":"traceutil/trace.go:171","msg":"trace[1901456833] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"136.241587ms","start":"2026-02-25T17:04:20.657817Z","end":"2026-02-25T17:04:20.794059Z","steps":["trace[1901456833] 'process raft request' (duration: 84.695606ms)","trace[1901456833] 'compare' (duration: 51.104281ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:20.794176Z","caller":"traceutil/trace.go:171","msg":"trace[2142206193] transaction","detail":"{read_only:false; number_of_response:0; response_revision:320; }","duration":"136.099172ms","start":"2026-02-25T17:04:20.65807Z","end":"2026-02-25T17:04:20.79417Z","steps":["trace[2142206193] 'process raft request' (duration: 135.605831ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:20.797874Z","caller":"traceutil/trace.go:171","msg":"trace[238680764] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"125.220174ms","start":"2026-02-25T17:04:20.672644Z","end":"2026-02-25T17:04:20.797865Z","steps":["trace[238680764] 'process raft request' (duration: 125.01142ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:21.024958Z","caller":"traceutil/trace.go:171","msg":"trace[707789341] linearizableReadLoop","detail":"{readStateIndex:334; appliedIndex:333; }","duration":"167.901445ms","start":"2026-02-25T17:04:20.857035Z","end":"2026-02-25T17:04:21.024936Z","steps":["trace[707789341] 'read index received' (duration: 85.994185ms)","trace[707789341] 'applied index is now lower than readState.Index' (duration: 81.90633ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:21.025106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.037419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:21.025145Z","caller":"traceutil/trace.go:171","msg":"trace[2130539532] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:323; }","duration":"168.150262ms","start":"2026-02-25T17:04:20.856985Z","end":"2026-02-25T17:04:21.025135Z","steps":["trace[2130539532] 'agreement among raft nodes before linearized reading' (duration: 168.037219ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:21.025447Z","caller":"traceutil/trace.go:171","msg":"trace[533835855] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"216.807144ms","start":"2026-02-25T17:04:20.808627Z","end":"2026-02-25T17:04:21.025435Z","steps":["trace[533835855] 'process raft request' (duration: 134.478784ms)","trace[533835855] 'compare' (duration: 81.735656ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:21.96172Z","caller":"traceutil/trace.go:171","msg":"trace[1454392739] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"104.182267ms","start":"2026-02-25T17:04:21.857516Z","end":"2026-02-25T17:04:21.961699Z","steps":["trace[1454392739] 'process raft request' (duration: 103.695365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.692202ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:23.261313Z","caller":"traceutil/trace.go:171","msg":"trace[511344706] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"417.66644ms","start":"2026-02-25T17:04:22.843601Z","end":"2026-02-25T17:04:23.261268Z","steps":["trace[511344706] 'read index received' (duration: 235.717081ms)","trace[511344706] 'applied index is now lower than readState.Index' (duration: 181.948129ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:23.261423Z","caller":"traceutil/trace.go:171","msg":"trace[794141928] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"424.85716ms","start":"2026-02-25T17:04:22.836526Z","end":"2026-02-25T17:04:23.261383Z","steps":["trace[794141928] 'process raft request' (duration: 242.893822ms)","trace[794141928] 'compare' (duration: 181.60315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:23.261512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.664128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-25T17:04:23.261571Z","caller":"traceutil/trace.go:171","msg":"trace[1254050877] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:347; }","duration":"403.75862ms","start":"2026-02-25T17:04:22.857796Z","end":"2026-02-25T17:04:23.261555Z","steps":["trace[1254050877] 'agreement among raft nodes before linearized reading' (duration: 403.661288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.857776Z","time spent":"403.826533ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-25T17:04:23.261599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.899273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T17:04:23.261681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.363281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-02-25T17:04:23.261702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.55998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-25T17:04:23.261721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.013029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-25T17:04:23.261751Z","caller":"traceutil/trace.go:171","msg":"trace[325237249] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:347; }","duration":"104.452072ms","start":"2026-02-25T17:04:23.157276Z","end":"2026-02-25T17:04:23.261729Z","steps":["trace[325237249] 'agreement among raft nodes before linearized reading' (duration: 104.371001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.226338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-02-25T17:04:23.261819Z","caller":"traceutil/trace.go:171","msg":"trace[2142442253] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:347; }","duration":"354.296529ms","start":"2026-02-25T17:04:22.907515Z","end":"2026-02-25T17:04:23.261812Z","steps":["trace[2142442253] 'agreement among raft nodes before linearized reading' (duration: 354.252968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.836505Z","time spent":"425.001894ms","remote":"127.0.0.1:57070","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:23.261848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.907489Z","time spent":"354.35425ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"info","ts":"2026-02-25T17:04:23.261747Z","caller":"traceutil/trace.go:171","msg":"trace[1863932167] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:347; }","duration":"203.658453ms","start":"2026-02-25T17:04:23.058076Z","end":"2026-02-25T17:04:23.261735Z","steps":["trace[1863932167] 'agreement among raft nodes before linearized reading' (duration: 203.56306ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.261671Z","caller":"traceutil/trace.go:171","msg":"trace[1212385998] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:347; }","duration":"254.030686ms","start":"2026-02-25T17:04:23.007622Z","end":"2026-02-25T17:04:23.261653Z","steps":["trace[1212385998] 'agreement among raft nodes before linearized reading' (duration: 253.868352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.861034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-gojk9w\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-02-25T17:04:23.262193Z","caller":"traceutil/trace.go:171","msg":"trace[20069614] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-gojk9w; range_end:; response_count:1; response_revision:347; }","duration":"418.602132ms","start":"2026-02-25T17:04:22.843572Z","end":"2026-02-25T17:04:23.262174Z","steps":["trace[20069614] 'agreement among raft nodes before linearized reading' (duration: 417.839904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.261593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.619401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-02-25T17:04:23.26234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.843562Z","time spent":"418.765895ms","remote":"127.0.0.1:56882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":564,"request content":"key:\"/registry/secrets/kube-system/bootstrap-token-gojk9w\" "} {"level":"info","ts":"2026-02-25T17:04:23.26235Z","caller":"traceutil/trace.go:171","msg":"trace[1405218206] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:347; }","duration":"155.42492ms","start":"2026-02-25T17:04:23.106913Z","end":"2026-02-25T17:04:23.262338Z","steps":["trace[1405218206] 'agreement among raft nodes before linearized reading' (duration: 154.5658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.26175Z","caller":"traceutil/trace.go:171","msg":"trace[19519048] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:347; }","duration":"304.070671ms","start":"2026-02-25T17:04:22.957671Z","end":"2026-02-25T17:04:23.261741Z","steps":["trace[19519048] 'agreement among raft nodes before linearized reading' (duration: 304.02204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.262521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:22.957655Z","time spent":"304.852639ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":238,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"info","ts":"2026-02-25T17:04:23.466457Z","caller":"traceutil/trace.go:171","msg":"trace[2045853649] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"196.435661ms","start":"2026-02-25T17:04:23.269973Z","end":"2026-02-25T17:04:23.466408Z","steps":["trace[2045853649] 'process raft request' (duration: 112.555855ms)","trace[2045853649] 'compare' (duration: 83.675261ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:23.466539Z","caller":"traceutil/trace.go:171","msg":"trace[1050643292] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"196.504543ms","start":"2026-02-25T17:04:23.270012Z","end":"2026-02-25T17:04:23.466517Z","steps":["trace[1050643292] 'process raft request' (duration: 196.339159ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.466784Z","caller":"traceutil/trace.go:171","msg":"trace[2013090623] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"196.669006ms","start":"2026-02-25T17:04:23.270099Z","end":"2026-02-25T17:04:23.466768Z","steps":["trace[2013090623] 'process raft request' (duration: 196.38299ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.466785Z","caller":"traceutil/trace.go:171","msg":"trace[694546057] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"196.594064ms","start":"2026-02-25T17:04:23.270087Z","end":"2026-02-25T17:04:23.466681Z","steps":["trace[694546057] 'process raft request' (duration: 196.327928ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.467105Z","caller":"traceutil/trace.go:171","msg":"trace[1312030237] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"196.901722ms","start":"2026-02-25T17:04:23.270192Z","end":"2026-02-25T17:04:23.467094Z","steps":["trace[1312030237] 'process raft request' (duration: 196.36696ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.467512Z","caller":"traceutil/trace.go:171","msg":"trace[53807204] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"197.175328ms","start":"2026-02-25T17:04:23.27031Z","end":"2026-02-25T17:04:23.467485Z","steps":["trace[53807204] 'process raft request' (duration: 196.672126ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.467571Z","caller":"traceutil/trace.go:171","msg":"trace[1925787915] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"195.014507ms","start":"2026-02-25T17:04:23.272548Z","end":"2026-02-25T17:04:23.467563Z","steps":["trace[1925787915] 'process raft request' (duration: 194.982036ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.46784Z","caller":"traceutil/trace.go:171","msg":"trace[1264883270] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"195.596821ms","start":"2026-02-25T17:04:23.272235Z","end":"2026-02-25T17:04:23.467832Z","steps":["trace[1264883270] 'process raft request' (duration: 195.248862ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.467935Z","caller":"traceutil/trace.go:171","msg":"trace[2109612358] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"196.690008ms","start":"2026-02-25T17:04:23.271233Z","end":"2026-02-25T17:04:23.467923Z","steps":["trace[2109612358] 'process raft request' (duration: 196.127054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.467973Z","caller":"traceutil/trace.go:171","msg":"trace[1832879719] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"195.55846ms","start":"2026-02-25T17:04:23.272405Z","end":"2026-02-25T17:04:23.467963Z","steps":["trace[1832879719] 'process raft request' (duration: 195.102569ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.468241Z","caller":"traceutil/trace.go:171","msg":"trace[2000281141] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"196.649936ms","start":"2026-02-25T17:04:23.271584Z","end":"2026-02-25T17:04:23.468234Z","steps":["trace[2000281141] 'process raft request' (duration: 195.820445ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610139Z","caller":"traceutil/trace.go:171","msg":"trace[335618410] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"129.702351ms","start":"2026-02-25T17:04:23.480397Z","end":"2026-02-25T17:04:23.6101Z","steps":["trace[335618410] 'process raft request' (duration: 129.417975ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610179Z","caller":"traceutil/trace.go:171","msg":"trace[463886959] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"128.396329ms","start":"2026-02-25T17:04:23.481752Z","end":"2026-02-25T17:04:23.610149Z","steps":["trace[463886959] 'process raft request' (duration: 128.175554ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610189Z","caller":"traceutil/trace.go:171","msg":"trace[497605639] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:370; }","duration":"132.018316ms","start":"2026-02-25T17:04:23.478142Z","end":"2026-02-25T17:04:23.61016Z","steps":["trace[497605639] 'read index received' (duration: 33.879562ms)","trace[497605639] 'applied index is now lower than readState.Index' (duration: 98.137604ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:23.610411Z","caller":"traceutil/trace.go:171","msg":"trace[1743005468] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"129.297301ms","start":"2026-02-25T17:04:23.481103Z","end":"2026-02-25T17:04:23.6104Z","steps":["trace[1743005468] 'process raft request' (duration: 128.787399ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610526Z","caller":"traceutil/trace.go:171","msg":"trace[1791058971] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"133.394648ms","start":"2026-02-25T17:04:23.477019Z","end":"2026-02-25T17:04:23.610414Z","steps":["trace[1791058971] 'process raft request' (duration: 98.883121ms)","trace[1791058971] 'compare' (duration: 33.660157ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:23.610681Z","caller":"traceutil/trace.go:171","msg":"trace[1036841845] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"133.46504ms","start":"2026-02-25T17:04:23.477205Z","end":"2026-02-25T17:04:23.61067Z","steps":["trace[1036841845] 'process raft request' (duration: 132.516417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:23.610695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.539439ms","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-02-25T17:04:23.610813Z","caller":"traceutil/trace.go:171","msg":"trace[207913552] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"132.673162ms","start":"2026-02-25T17:04:23.478119Z","end":"2026-02-25T17:04:23.610792Z","steps":["trace[207913552] 'agreement among raft nodes before linearized reading' (duration: 132.530179ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.610703Z","caller":"traceutil/trace.go:171","msg":"trace[984081392] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"133.294497ms","start":"2026-02-25T17:04:23.477388Z","end":"2026-02-25T17:04:23.610682Z","steps":["trace[984081392] 'process raft request' (duration: 132.370724ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.831631Z","caller":"traceutil/trace.go:171","msg":"trace[147600798] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"128.927553ms","start":"2026-02-25T17:04:23.702686Z","end":"2026-02-25T17:04:23.831614Z","steps":["trace[147600798] 'process raft request' (duration: 128.893542ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.831852Z","caller":"traceutil/trace.go:171","msg":"trace[2103905412] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"157.230084ms","start":"2026-02-25T17:04:23.674608Z","end":"2026-02-25T17:04:23.831838Z","steps":["trace[2103905412] 'process raft request' (duration: 156.895925ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.832769Z","caller":"traceutil/trace.go:171","msg":"trace[2031585192] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"153.544376ms","start":"2026-02-25T17:04:23.678255Z","end":"2026-02-25T17:04:23.8318Z","steps":["trace[2031585192] 'process raft request' (duration: 153.281039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:23.832142Z","caller":"traceutil/trace.go:171","msg":"trace[384060197] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"157.621932ms","start":"2026-02-25T17:04:23.673943Z","end":"2026-02-25T17:04:23.831565Z","steps":["trace[384060197] 'process raft request' (duration: 108.275925ms)","trace[384060197] 'compare' (duration: 49.166593ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:28.741511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.846538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:28.741635Z","caller":"traceutil/trace.go:171","msg":"trace[1632708872] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"296.220005ms","start":"2026-02-25T17:04:28.445396Z","end":"2026-02-25T17:04:28.741616Z","steps":["trace[1632708872] 'read index received' (duration: 195.115631ms)","trace[1632708872] 'applied index is now lower than readState.Index' (duration: 101.102974ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:28.74166Z","caller":"traceutil/trace.go:171","msg":"trace[606001154] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"368.405813ms","start":"2026-02-25T17:04:28.373223Z","end":"2026-02-25T17:04:28.741629Z","steps":["trace[606001154] 'process raft request' (duration: 267.35264ms)","trace[606001154] 'compare' (duration: 100.477809ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:28.741772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.379828ms","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-02-25T17:04:28.741772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:28.373193Z","time spent":"368.519946ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:28.74179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.174913ms","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-02-25T17:04:28.74181Z","caller":"traceutil/trace.go:171","msg":"trace[266175153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"296.45195ms","start":"2026-02-25T17:04:28.445344Z","end":"2026-02-25T17:04:28.741796Z","steps":["trace[266175153] 'agreement among raft nodes before linearized reading' (duration: 296.377658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:28.741845Z","caller":"traceutil/trace.go:171","msg":"trace[495879198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"231.323857ms","start":"2026-02-25T17:04:28.510505Z","end":"2026-02-25T17:04:28.741828Z","steps":["trace[495879198] 'agreement among raft nodes before linearized reading' (duration: 231.221904ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:28.987334Z","caller":"traceutil/trace.go:171","msg":"trace[2080531183] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"241.242622ms","start":"2026-02-25T17:04:28.74607Z","end":"2026-02-25T17:04:28.987313Z","steps":["trace[2080531183] 'process raft request' (duration: 240.816052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:29.325085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.217636ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:29.325183Z","caller":"traceutil/trace.go:171","msg":"trace[1791327988] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"579.04832ms","start":"2026-02-25T17:04:28.746117Z","end":"2026-02-25T17:04:29.325165Z","steps":["trace[1791327988] 'process raft request' (duration: 439.676201ms)","trace[1791327988] 'compare' (duration: 139.119173ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:29.325244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:28.746106Z","time spent":"579.108862ms","remote":"127.0.0.1:57078","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-02-25T17:04:33.531576Z","caller":"traceutil/trace.go:171","msg":"trace[1794094891] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"173.254443ms","start":"2026-02-25T17:04:33.358298Z","end":"2026-02-25T17:04:33.531552Z","steps":["trace[1794094891] 'read index received' (duration: 121.055477ms)","trace[1794094891] 'applied index is now lower than readState.Index' (duration: 52.197586ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:33.531759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.447477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:04:33.531872Z","caller":"traceutil/trace.go:171","msg":"trace[1357727542] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:451; }","duration":"173.58364ms","start":"2026-02-25T17:04:33.358244Z","end":"2026-02-25T17:04:33.531827Z","steps":["trace[1357727542] 'agreement among raft nodes before linearized reading' (duration: 173.398676ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:37.405009Z","caller":"traceutil/trace.go:171","msg":"trace[641773078] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"233.223613ms","start":"2026-02-25T17:04:37.171759Z","end":"2026-02-25T17:04:37.404983Z","steps":["trace[641773078] 'process raft request' (duration: 233.063859ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:40.51748Z","caller":"traceutil/trace.go:171","msg":"trace[897490104] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"110.762733ms","start":"2026-02-25T17:04:40.406699Z","end":"2026-02-25T17:04:40.517462Z","steps":["trace[897490104] 'process raft request' (duration: 110.658801ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:43.854701Z","caller":"traceutil/trace.go:171","msg":"trace[882080037] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"326.671006ms","start":"2026-02-25T17:04:43.528008Z","end":"2026-02-25T17:04:43.854679Z","steps":["trace[882080037] 'read index received' (duration: 326.454211ms)","trace[882080037] 'applied index is now lower than readState.Index' (duration: 215.585µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:43.854854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.831629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:43.854912Z","caller":"traceutil/trace.go:171","msg":"trace[1259237870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"326.939762ms","start":"2026-02-25T17:04:43.527959Z","end":"2026-02-25T17:04:43.854898Z","steps":["trace[1259237870] 'agreement among raft nodes before linearized reading' (duration: 326.84564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.854955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.527938Z","time spent":"327.006904ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:43.854982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.815946ms","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-02-25T17:04:43.85507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.282026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:43.855109Z","caller":"traceutil/trace.go:171","msg":"trace[497982371] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:474; }","duration":"260.98131ms","start":"2026-02-25T17:04:43.5941Z","end":"2026-02-25T17:04:43.855082Z","steps":["trace[497982371] 'agreement among raft nodes before linearized reading' (duration: 260.792926ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:43.855189Z","caller":"traceutil/trace.go:171","msg":"trace[1142126974] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:474; }","duration":"250.43996ms","start":"2026-02-25T17:04:43.60473Z","end":"2026-02-25T17:04:43.85517Z","steps":["trace[1142126974] 'agreement among raft nodes before linearized reading' (duration: 250.281077ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:43.855403Z","caller":"traceutil/trace.go:171","msg":"trace[1849982477] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"416.935453ms","start":"2026-02-25T17:04:43.437809Z","end":"2026-02-25T17:04:43.854744Z","steps":["trace[1849982477] 'process raft request' (duration: 416.741619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:43.855857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.437791Z","time spent":"417.942916ms","remote":"127.0.0.1:57078","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-02-25T17:04:44.521895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.45097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:44.521966Z","caller":"traceutil/trace.go:171","msg":"trace[73952450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:0; response_revision:474; }","duration":"663.557452ms","start":"2026-02-25T17:04:43.858393Z","end":"2026-02-25T17:04:44.521951Z","steps":["trace[73952450] 'range keys from in-memory index tree' (duration: 663.357108ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.521998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.858376Z","time spent":"663.613164ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-02-25T17:04:44.522138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.854882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:04:44.522223Z","caller":"traceutil/trace.go:171","msg":"trace[1212471593] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"328.373745ms","start":"2026-02-25T17:04:44.193837Z","end":"2026-02-25T17:04:44.52221Z","steps":["trace[1212471593] 'read index received' (duration: 40.821µs)","trace[1212471593] 'applied index is now lower than readState.Index' (duration: 328.332004ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:44.522272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.446447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:44.522269Z","caller":"traceutil/trace.go:171","msg":"trace[1345596935] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"662.600749ms","start":"2026-02-25T17:04:43.859657Z","end":"2026-02-25T17:04:44.522258Z","steps":["trace[1345596935] 'process raft request' (duration: 253.566864ms)","trace[1345596935] 'compare' (duration: 408.603455ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:44.522293Z","caller":"traceutil/trace.go:171","msg":"trace[2057935303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:475; }","duration":"328.498808ms","start":"2026-02-25T17:04:44.193787Z","end":"2026-02-25T17:04:44.522285Z","steps":["trace[2057935303] 'agreement among raft nodes before linearized reading' (duration: 328.459227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:44.522316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:44.193767Z","time spent":"328.543649ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:44.522343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:43.859634Z","time spent":"662.66128ms","remote":"127.0.0.1:57078","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-02-25T17:04:46.931806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.369723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-25T17:04:46.931896Z","caller":"traceutil/trace.go:171","msg":"trace[1159405276] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:517; }","duration":"107.508335ms","start":"2026-02-25T17:04:46.824368Z","end":"2026-02-25T17:04:46.931876Z","steps":["trace[1159405276] 'range keys from in-memory index tree' (duration: 107.25633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.030718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586113631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:48.5315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586113631,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:04:48.974896Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.034197027s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:04:48.975476Z","caller":"traceutil/trace.go:171","msg":"trace[1196782647] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.034836613s","start":"2026-02-25T17:04:46.940616Z","end":"2026-02-25T17:04:48.975452Z","steps":["trace[1196782647] 'process raft request' (duration: 2.034638298s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:48.975633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.940585Z","time spent":"2.034968886s","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:04:49.12221Z","caller":"traceutil/trace.go:171","msg":"trace[748639721] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:536; }","duration":"1.592094159s","start":"2026-02-25T17:04:47.530091Z","end":"2026-02-25T17:04:49.122185Z","steps":["trace[748639721] 'read index received' (duration: 1.44514956s)","trace[748639721] 'applied index is now lower than readState.Index' (duration: 146.943639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:49.122364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592252722s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:49.122341Z","caller":"traceutil/trace.go:171","msg":"trace[241009162] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"2.179080328s","start":"2026-02-25T17:04:46.943228Z","end":"2026-02-25T17:04:49.122308Z","steps":["trace[241009162] 'process raft request' (duration: 2.129223268s)","trace[241009162] 'compare' (duration: 49.637364ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:04:49.122406Z","caller":"traceutil/trace.go:171","msg":"trace[510735803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.592343075s","start":"2026-02-25T17:04:47.530053Z","end":"2026-02-25T17:04:49.122396Z","steps":["trace[510735803] 'agreement among raft nodes before linearized reading' (duration: 1.592263713s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.122562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.530032Z","time spent":"1.592516499s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:04:49.122489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:46.943212Z","time spent":"2.179203701s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:04:49.122941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.679171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:04:49.12298Z","caller":"traceutil/trace.go:171","msg":"trace[445539205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"477.755963ms","start":"2026-02-25T17:04:48.645214Z","end":"2026-02-25T17:04:49.12297Z","steps":["trace[445539205] 'agreement among raft nodes before linearized reading' (duration: 477.688512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.123009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.645192Z","time spent":"477.810615ms","remote":"127.0.0.1:57078","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-02-25T17:04:49.123309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.652863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:04:49.123404Z","caller":"traceutil/trace.go:171","msg":"trace[991825063] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"586.784576ms","start":"2026-02-25T17:04:48.536591Z","end":"2026-02-25T17:04:49.123376Z","steps":["trace[991825063] 'agreement among raft nodes before linearized reading' (duration: 586.409957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.123553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:48.536531Z","time spent":"586.915538ms","remote":"127.0.0.1:57078","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-02-25T17:04:49.123323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253100463s","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-02-25T17:04:49.123673Z","caller":"traceutil/trace.go:171","msg":"trace[1761670578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"1.253502672s","start":"2026-02-25T17:04:47.870156Z","end":"2026-02-25T17:04:49.123659Z","steps":["trace[1761670578] 'agreement among raft nodes before linearized reading' (duration: 1.252443316s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:49.123765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:47.870136Z","time spent":"1.253612915s","remote":"127.0.0.1:57078","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-02-25T17:04:50.658115Z","caller":"traceutil/trace.go:171","msg":"trace[1797542765] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"127.468339ms","start":"2026-02-25T17:04:50.530627Z","end":"2026-02-25T17:04:50.658096Z","steps":["trace[1797542765] 'read index received' (duration: 127.287714ms)","trace[1797542765] 'applied index is now lower than readState.Index' (duration: 179.675µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:04:50.658252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.602382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:04:50.658309Z","caller":"traceutil/trace.go:171","msg":"trace[889566408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"127.707504ms","start":"2026-02-25T17:04:50.530591Z","end":"2026-02-25T17:04:50.658298Z","steps":["trace[889566408] 'agreement among raft nodes before linearized reading' (duration: 127.592682ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:50.658547Z","caller":"traceutil/trace.go:171","msg":"trace[1624195871] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"158.523494ms","start":"2026-02-25T17:04:50.499981Z","end":"2026-02-25T17:04:50.658504Z","steps":["trace[1624195871] 'process raft request' (duration: 157.986722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.313347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.679351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:04:51.313398Z","caller":"traceutil/trace.go:171","msg":"trace[1956204428] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"186.768503ms","start":"2026-02-25T17:04:51.126616Z","end":"2026-02-25T17:04:51.313384Z","steps":["trace[1956204428] 'range keys from in-memory index tree' (duration: 186.580989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:51.313404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.907084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:04:51.313471Z","caller":"traceutil/trace.go:171","msg":"trace[1980396939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:523; }","duration":"154.015667ms","start":"2026-02-25T17:04:51.159435Z","end":"2026-02-25T17:04:51.313451Z","steps":["trace[1980396939] 'range keys from in-memory index tree' (duration: 153.806553ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:04:59.102423Z","caller":"traceutil/trace.go:171","msg":"trace[2030790673] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"329.315719ms","start":"2026-02-25T17:04:58.773081Z","end":"2026-02-25T17:04:59.102397Z","steps":["trace[2030790673] 'process raft request' (duration: 329.152125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:04:59.102567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:58.773058Z","time spent":"329.435721ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:00.688418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266515862s","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-02-25T17:05:00.688546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.266540483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:05:00.688611Z","caller":"traceutil/trace.go:171","msg":"trace[2038776852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.266625375s","start":"2026-02-25T17:04:59.421969Z","end":"2026-02-25T17:05:00.688595Z","steps":["trace[2038776852] 'range keys from in-memory index tree' (duration: 1.266487042s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.688645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.421961Z","time spent":"1.266676256s","remote":"127.0.0.1:57078","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-02-25T17:05:00.688615Z","caller":"traceutil/trace.go:171","msg":"trace[1251196890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.266773099s","start":"2026-02-25T17:04:59.42181Z","end":"2026-02-25T17:05:00.688583Z","steps":["trace[1251196890] 'range keys from in-memory index tree' (duration: 1.266272246s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.688629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161492415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:00.688709Z","caller":"traceutil/trace.go:171","msg":"trace[469995099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.161601468s","start":"2026-02-25T17:04:59.527085Z","end":"2026-02-25T17:05:00.688687Z","steps":["trace[469995099] 'range keys from in-memory index tree' (duration: 1.161331172s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.688708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.421789Z","time spent":"1.266899891s","remote":"127.0.0.1:57078","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-02-25T17:05:00.688745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.527068Z","time spent":"1.161667839s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:00.688772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307920703s","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-02-25T17:05:00.688822Z","caller":"traceutil/trace.go:171","msg":"trace[1230646061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"1.308005555s","start":"2026-02-25T17:04:59.380802Z","end":"2026-02-25T17:05:00.688807Z","steps":["trace[1230646061] 'range keys from in-memory index tree' (duration: 1.307755989s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.688823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301254355s","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-02-25T17:05:00.688865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.380777Z","time spent":"1.308078827s","remote":"127.0.0.1:57078","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-02-25T17:05:00.688877Z","caller":"traceutil/trace.go:171","msg":"trace[1719276987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"1.301345816s","start":"2026-02-25T17:04:59.387516Z","end":"2026-02-25T17:05:00.688862Z","steps":["trace[1719276987] 'range keys from in-memory index tree' (duration: 1.30108062s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.688919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:04:59.387497Z","time spent":"1.301411018s","remote":"127.0.0.1:57078","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":"info","ts":"2026-02-25T17:05:00.911879Z","caller":"traceutil/trace.go:171","msg":"trace[1674600015] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"217.490364ms","start":"2026-02-25T17:05:00.694272Z","end":"2026-02-25T17:05:00.911763Z","steps":["trace[1674600015] 'process raft request' (duration: 217.351161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:00.912161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.040586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:00.912307Z","caller":"traceutil/trace.go:171","msg":"trace[922689783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"213.292182ms","start":"2026-02-25T17:05:00.698996Z","end":"2026-02-25T17:05:00.912289Z","steps":["trace[922689783] 'agreement among raft nodes before linearized reading' (duration: 213.056866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:00.911914Z","caller":"traceutil/trace.go:171","msg":"trace[183391958] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"217.687209ms","start":"2026-02-25T17:05:00.694198Z","end":"2026-02-25T17:05:00.911885Z","steps":["trace[183391958] 'process raft request' (duration: 202.206094ms)","trace[183391958] 'compare' (duration: 15.011614ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:00.912003Z","caller":"traceutil/trace.go:171","msg":"trace[1822635422] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:566; }","duration":"212.902673ms","start":"2026-02-25T17:05:00.699059Z","end":"2026-02-25T17:05:00.911962Z","steps":["trace[1822635422] 'read index received' (duration: 61.004844ms)","trace[1822635422] 'applied index is now lower than readState.Index' (duration: 151.895309ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:00.912073Z","caller":"traceutil/trace.go:171","msg":"trace[1630301030] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"216.375926ms","start":"2026-02-25T17:05:00.695686Z","end":"2026-02-25T17:05:00.912062Z","steps":["trace[1630301030] 'process raft request' (duration: 216.011828ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:02.920007Z","caller":"traceutil/trace.go:171","msg":"trace[9779980] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"143.661171ms","start":"2026-02-25T17:05:02.776307Z","end":"2026-02-25T17:05:02.919968Z","steps":["trace[9779980] 'process raft request' (duration: 100.489927ms)","trace[9779980] 'compare' (duration: 42.99244ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:02.932318Z","caller":"traceutil/trace.go:171","msg":"trace[591465469] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"134.508699ms","start":"2026-02-25T17:05:02.79778Z","end":"2026-02-25T17:05:02.932289Z","steps":["trace[591465469] 'process raft request' (duration: 134.313484ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.717453Z","caller":"traceutil/trace.go:171","msg":"trace[720376637] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"115.963531ms","start":"2026-02-25T17:05:04.601448Z","end":"2026-02-25T17:05:04.717412Z","steps":["trace[720376637] 'process raft request' (duration: 115.714775ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:04.935058Z","caller":"traceutil/trace.go:171","msg":"trace[1755939961] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"151.343196ms","start":"2026-02-25T17:05:04.783694Z","end":"2026-02-25T17:05:04.935037Z","steps":["trace[1755939961] 'read index received' (duration: 150.67853ms)","trace[1755939961] 'applied index is now lower than readState.Index' (duration: 663.746µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:04.935158Z","caller":"traceutil/trace.go:171","msg":"trace[816969809] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"158.93416ms","start":"2026-02-25T17:05:04.776147Z","end":"2026-02-25T17:05:04.935081Z","steps":["trace[816969809] 'process raft request' (duration: 158.258324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:04.935248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.54229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:05:04.935288Z","caller":"traceutil/trace.go:171","msg":"trace[1804187997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"151.638403ms","start":"2026-02-25T17:05:04.783639Z","end":"2026-02-25T17:05:04.935278Z","steps":["trace[1804187997] 'agreement among raft nodes before linearized reading' (duration: 151.50897ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.17949Z","caller":"traceutil/trace.go:171","msg":"trace[2115453105] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"244.26104ms","start":"2026-02-25T17:05:04.935201Z","end":"2026-02-25T17:05:05.179462Z","steps":["trace[2115453105] 'read index received' (duration: 211.220991ms)","trace[2115453105] 'applied index is now lower than readState.Index' (duration: 33.038909ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:05.179512Z","caller":"traceutil/trace.go:171","msg":"trace[2144259509] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"386.143558ms","start":"2026-02-25T17:05:04.793323Z","end":"2026-02-25T17:05:05.179466Z","steps":["trace[2144259509] 'process raft request' (duration: 353.090379ms)","trace[2144259509] 'compare' (duration: 32.855824ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:05.179687Z","caller":"traceutil/trace.go:171","msg":"trace[1177219466] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"378.703648ms","start":"2026-02-25T17:05:04.800836Z","end":"2026-02-25T17:05:05.17954Z","steps":["trace[1177219466] 'process raft request' (duration: 378.576224ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.179874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.793296Z","time spent":"386.291791ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":743,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:05:05.179998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.800817Z","time spent":"379.007904ms","remote":"127.0.0.1:56848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:05:05.179732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.625786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lh6jn\" ","response":"range_response_count:1 size:17333"} {"level":"info","ts":"2026-02-25T17:05:05.180481Z","caller":"traceutil/trace.go:171","msg":"trace[2040917430] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lh6jn; range_end:; response_count:1; response_revision:584; }","duration":"389.450097ms","start":"2026-02-25T17:05:04.791011Z","end":"2026-02-25T17:05:05.180461Z","steps":["trace[2040917430] 'agreement among raft nodes before linearized reading' (duration: 388.574386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.180575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.790984Z","time spent":"389.57175ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17357,"request content":"key:\"/registry/pods/kube-system/cilium-lh6jn\" "} {"level":"info","ts":"2026-02-25T17:05:05.355014Z","caller":"traceutil/trace.go:171","msg":"trace[1634917198] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"416.46994ms","start":"2026-02-25T17:05:04.938511Z","end":"2026-02-25T17:05:05.354981Z","steps":["trace[1634917198] 'process raft request' (duration: 405.340011ms)","trace[1634917198] 'compare' (duration: 10.817662ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.355179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.938486Z","time spent":"416.594983ms","remote":"127.0.0.1:57078","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-02-25T17:05:05.355249Z","caller":"traceutil/trace.go:171","msg":"trace[1369657482] linearizableReadLoop","detail":"{readStateIndex:608; appliedIndex:606; }","duration":"175.667664ms","start":"2026-02-25T17:05:05.17956Z","end":"2026-02-25T17:05:05.355228Z","steps":["trace[1369657482] 'read index received' (duration: 164.353721ms)","trace[1369657482] 'applied index is now lower than readState.Index' (duration: 11.313163ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:05.355495Z","caller":"traceutil/trace.go:171","msg":"trace[1626593361] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"337.514513ms","start":"2026-02-25T17:05:05.017952Z","end":"2026-02-25T17:05:05.355467Z","steps":["trace[1626593361] 'process raft request' (duration: 336.878358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.35559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.809966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:05:05.355637Z","caller":"traceutil/trace.go:171","msg":"trace[2100385999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"406.895958ms","start":"2026-02-25T17:05:04.948725Z","end":"2026-02-25T17:05:05.355621Z","steps":["trace[2100385999] 'agreement among raft nodes before linearized reading' (duration: 406.748075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.35543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.098678ms","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-02-25T17:05:05.355678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.948695Z","time spent":"406.97497ms","remote":"127.0.0.1:57078","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-02-25T17:05:05.355699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.017919Z","time spent":"337.676697ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.355714Z","caller":"traceutil/trace.go:171","msg":"trace[1630529220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"403.418295ms","start":"2026-02-25T17:05:04.95228Z","end":"2026-02-25T17:05:05.355698Z","steps":["trace[1630529220] 'agreement among raft nodes before linearized reading' (duration: 403.095737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.355763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.952254Z","time spent":"403.492747ms","remote":"127.0.0.1:57078","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-02-25T17:05:05.355494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.272707ms","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-02-25T17:05:05.35543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.121978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.355916Z","caller":"traceutil/trace.go:171","msg":"trace[894864221] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:586; }","duration":"355.589599ms","start":"2026-02-25T17:05:05.000281Z","end":"2026-02-25T17:05:05.35587Z","steps":["trace[894864221] 'agreement among raft nodes before linearized reading' (duration: 355.051166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.355889Z","caller":"traceutil/trace.go:171","msg":"trace[1255916418] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"402.670397ms","start":"2026-02-25T17:05:04.953169Z","end":"2026-02-25T17:05:05.355839Z","steps":["trace[1255916418] 'agreement among raft nodes before linearized reading' (duration: 402.183736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.355963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.000204Z","time spent":"355.747573ms","remote":"127.0.0.1:56848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-25T17:05:05.356159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:04.953154Z","time spent":"402.819981ms","remote":"127.0.0.1:57078","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-02-25T17:05:05.560359Z","caller":"traceutil/trace.go:171","msg":"trace[1988408589] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"365.57427ms","start":"2026-02-25T17:05:05.194751Z","end":"2026-02-25T17:05:05.560325Z","steps":["trace[1988408589] 'process raft request' (duration: 365.410446ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.560426Z","caller":"traceutil/trace.go:171","msg":"trace[2066357011] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"369.792043ms","start":"2026-02-25T17:05:05.190535Z","end":"2026-02-25T17:05:05.560327Z","steps":["trace[2066357011] 'process raft request' (duration: 369.405514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.560567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.194734Z","time spent":"365.719954ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17400,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:05.560858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.190498Z","time spent":"370.0986ms","remote":"127.0.0.1:56848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:05.5612Z","caller":"traceutil/trace.go:171","msg":"trace[821369844] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"361.498714ms","start":"2026-02-25T17:05:05.199692Z","end":"2026-02-25T17:05:05.561191Z","steps":["trace[821369844] 'process raft request' (duration: 360.586131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.561636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:05.199673Z","time spent":"361.683558ms","remote":"127.0.0.1:56848","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:05:05.607516Z","caller":"traceutil/trace.go:171","msg":"trace[1447074362] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"246.337151ms","start":"2026-02-25T17:05:05.361152Z","end":"2026-02-25T17:05:05.607489Z","steps":["trace[1447074362] 'process raft request' (duration: 246.139776ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.607655Z","caller":"traceutil/trace.go:171","msg":"trace[2101265456] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"246.399462ms","start":"2026-02-25T17:05:05.361245Z","end":"2026-02-25T17:05:05.607644Z","steps":["trace[2101265456] 'process raft request' (duration: 246.179747ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.607735Z","caller":"traceutil/trace.go:171","msg":"trace[1528303707] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:608; }","duration":"207.322528ms","start":"2026-02-25T17:05:05.400398Z","end":"2026-02-25T17:05:05.607721Z","steps":["trace[1528303707] 'read index received' (duration: 159.598415ms)","trace[1528303707] 'applied index is now lower than readState.Index' (duration: 47.722653ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:05.607879Z","caller":"traceutil/trace.go:171","msg":"trace[825086849] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"245.905221ms","start":"2026-02-25T17:05:05.361962Z","end":"2026-02-25T17:05:05.607867Z","steps":["trace[825086849] 'process raft request' (duration: 245.643374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.607912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.510692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.608011Z","caller":"traceutil/trace.go:171","msg":"trace[1086700608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"207.862651ms","start":"2026-02-25T17:05:05.400138Z","end":"2026-02-25T17:05:05.608Z","steps":["trace[1086700608] 'agreement among raft nodes before linearized reading' (duration: 207.731938ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.77275Z","caller":"traceutil/trace.go:171","msg":"trace[986378258] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"164.956975ms","start":"2026-02-25T17:05:05.607776Z","end":"2026-02-25T17:05:05.772733Z","steps":["trace[986378258] 'read index received' (duration: 164.604467ms)","trace[986378258] 'applied index is now lower than readState.Index' (duration: 351.778µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:05.77286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.925984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.77289Z","caller":"traceutil/trace.go:171","msg":"trace[243583489] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"242.015556ms","start":"2026-02-25T17:05:05.530867Z","end":"2026-02-25T17:05:05.772883Z","steps":["trace[243583489] 'agreement among raft nodes before linearized reading' (duration: 241.936324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.773061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.427072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-k56xb\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-25T17:05:05.773175Z","caller":"traceutil/trace.go:171","msg":"trace[2041774443] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-k56xb; range_end:; response_count:1; response_revision:594; }","duration":"205.546315ms","start":"2026-02-25T17:05:05.567568Z","end":"2026-02-25T17:05:05.773115Z","steps":["trace[2041774443] 'agreement among raft nodes before linearized reading' (duration: 205.389691ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.773055Z","caller":"traceutil/trace.go:171","msg":"trace[133321169] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"204.561321ms","start":"2026-02-25T17:05:05.568467Z","end":"2026-02-25T17:05:05.773028Z","steps":["trace[133321169] 'process raft request' (duration: 203.979997ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:05.773541Z","caller":"traceutil/trace.go:171","msg":"trace[1651098927] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"203.429384ms","start":"2026-02-25T17:05:05.570088Z","end":"2026-02-25T17:05:05.773517Z","steps":["trace[1651098927] 'process raft request' (duration: 202.601034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:05.773671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.697481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:05.77375Z","caller":"traceutil/trace.go:171","msg":"trace[839557144] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:594; }","duration":"173.160962ms","start":"2026-02-25T17:05:05.600572Z","end":"2026-02-25T17:05:05.773733Z","steps":["trace[839557144] 'agreement among raft nodes before linearized reading' (duration: 172.345703ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:14.067913Z","caller":"traceutil/trace.go:171","msg":"trace[1832356536] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"349.624295ms","start":"2026-02-25T17:05:13.718273Z","end":"2026-02-25T17:05:14.067897Z","steps":["trace[1832356536] 'process raft request' (duration: 349.591235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:14.068012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:13.718251Z","time spent":"349.718368ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:05:14.068339Z","caller":"traceutil/trace.go:171","msg":"trace[37435039] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"351.10211ms","start":"2026-02-25T17:05:13.717214Z","end":"2026-02-25T17:05:14.068316Z","steps":["trace[37435039] 'process raft request' (duration: 350.148438ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:14.068425Z","caller":"traceutil/trace.go:171","msg":"trace[667959964] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"350.866815ms","start":"2026-02-25T17:05:13.717549Z","end":"2026-02-25T17:05:14.068416Z","steps":["trace[667959964] 'process raft request' (duration: 350.25779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:14.068461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:13.717196Z","time spent":"351.205183ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:14.068491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:13.71754Z","time spent":"350.912197ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:14.139279Z","caller":"traceutil/trace.go:171","msg":"trace[555294485] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"370.834449ms","start":"2026-02-25T17:05:13.768425Z","end":"2026-02-25T17:05:14.139259Z","steps":["trace[555294485] 'process raft request' (duration: 370.801788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:14.139392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:13.768407Z","time spent":"370.936091ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:05:14.13968Z","caller":"traceutil/trace.go:171","msg":"trace[759241469] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"376.420524ms","start":"2026-02-25T17:05:13.763251Z","end":"2026-02-25T17:05:14.139672Z","steps":["trace[759241469] 'process raft request' (duration: 375.863581ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:14.139713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:13.763214Z","time spent":"376.482235ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:05:19.541993Z","caller":"traceutil/trace.go:171","msg":"trace[1276058987] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"104.108645ms","start":"2026-02-25T17:05:19.437865Z","end":"2026-02-25T17:05:19.541974Z","steps":["trace[1276058987] 'process raft request' (duration: 103.87818ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:20.285374Z","caller":"traceutil/trace.go:171","msg":"trace[392765836] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"191.179759ms","start":"2026-02-25T17:05:20.094161Z","end":"2026-02-25T17:05:20.285341Z","steps":["trace[392765836] 'read index received' (duration: 191.011115ms)","trace[392765836] 'applied index is now lower than readState.Index' (duration: 167.744µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:20.285468Z","caller":"traceutil/trace.go:171","msg":"trace[1372510339] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"515.915483ms","start":"2026-02-25T17:05:19.769533Z","end":"2026-02-25T17:05:20.285448Z","steps":["trace[1372510339] 'process raft request' (duration: 515.650137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:20.285637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:19.76951Z","time spent":"516.011546ms","remote":"127.0.0.1:57078","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-02-25T17:05:20.285673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.515487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T17:05:20.285717Z","caller":"traceutil/trace.go:171","msg":"trace[1208177846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"191.617119ms","start":"2026-02-25T17:05:20.094088Z","end":"2026-02-25T17:05:20.285705Z","steps":["trace[1208177846] 'agreement among raft nodes before linearized reading' (duration: 191.455085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:20.286853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.032592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-25T17:05:20.286905Z","caller":"traceutil/trace.go:171","msg":"trace[1268936647] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"188.119683ms","start":"2026-02-25T17:05:20.098771Z","end":"2026-02-25T17:05:20.286891Z","steps":["trace[1268936647] 'agreement among raft nodes before linearized reading' (duration: 187.9839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:20.796487Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114051,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:05:21.382635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.076029ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:21.382812Z","caller":"traceutil/trace.go:171","msg":"trace[364478929] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.091482365s","start":"2026-02-25T17:05:20.291312Z","end":"2026-02-25T17:05:21.382794Z","steps":["trace[364478929] 'process raft request' (duration: 1.091414843s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:21.382876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.291281Z","time spent":"1.091565227s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:21.382873Z","caller":"traceutil/trace.go:171","msg":"trace[237641286] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"1.091556987s","start":"2026-02-25T17:05:20.291281Z","end":"2026-02-25T17:05:21.382838Z","steps":["trace[237641286] 'process raft request' (duration: 940.197661ms)","trace[237641286] 'compare' (duration: 150.878925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:21.383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.291246Z","time spent":"1.091685s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:21.727077Z","caller":"traceutil/trace.go:171","msg":"trace[2021614130] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:755; }","duration":"1.431235473s","start":"2026-02-25T17:05:20.295819Z","end":"2026-02-25T17:05:21.727055Z","steps":["trace[2021614130] 'read index received' (duration: 935.940769ms)","trace[2021614130] 'applied index is now lower than readState.Index' (duration: 495.293834ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:21.727199Z","caller":"traceutil/trace.go:171","msg":"trace[532511117] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.058756055s","start":"2026-02-25T17:05:20.668385Z","end":"2026-02-25T17:05:21.727141Z","steps":["trace[532511117] 'process raft request' (duration: 1.05854236s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:21.727304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431463989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T17:05:21.72734Z","caller":"traceutil/trace.go:171","msg":"trace[803207831] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:732; }","duration":"1.431553491s","start":"2026-02-25T17:05:20.295775Z","end":"2026-02-25T17:05:21.727329Z","steps":["trace[803207831] 'agreement among raft nodes before linearized reading' (duration: 1.431415488s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:21.727378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.295753Z","time spent":"1.431617073s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:21.727369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:20.668362Z","time spent":"1.05891998s","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4728,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:21.738695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.100197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:21.738801Z","caller":"traceutil/trace.go:171","msg":"trace[1541268668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"208.226189ms","start":"2026-02-25T17:05:21.530554Z","end":"2026-02-25T17:05:21.73878Z","steps":["trace[1541268668] 'agreement among raft nodes before linearized reading' (duration: 208.080496ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:21.738722Z","caller":"traceutil/trace.go:171","msg":"trace[772023967] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"402.890422ms","start":"2026-02-25T17:05:21.335812Z","end":"2026-02-25T17:05:21.738703Z","steps":["trace[772023967] 'process raft request' (duration: 402.712037ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:21.738912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.044027ms","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-02-25T17:05:21.738957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:21.335608Z","time spent":"403.277362ms","remote":"127.0.0.1:57078","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-02-25T17:05:21.738978Z","caller":"traceutil/trace.go:171","msg":"trace[594792775] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:733; }","duration":"194.15697ms","start":"2026-02-25T17:05:21.544804Z","end":"2026-02-25T17:05:21.738961Z","steps":["trace[594792775] 'agreement among raft nodes before linearized reading' (duration: 193.962085ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:23.741416Z","caller":"traceutil/trace.go:171","msg":"trace[293360543] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"350.831565ms","start":"2026-02-25T17:05:23.390563Z","end":"2026-02-25T17:05:23.741394Z","steps":["trace[293360543] 'process raft request' (duration: 350.694482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:23.741589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.390551Z","time spent":"350.942197ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:24.399649Z","caller":"traceutil/trace.go:171","msg":"trace[1932344155] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:763; }","duration":"871.935482ms","start":"2026-02-25T17:05:23.527688Z","end":"2026-02-25T17:05:24.399623Z","steps":["trace[1932344155] 'read index received' (duration: 213.599549ms)","trace[1932344155] 'applied index is now lower than readState.Index' (duration: 658.334893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:24.399748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.522544Z","time spent":"877.18743ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:05:24.399473Z","caller":"traceutil/trace.go:171","msg":"trace[1125151190] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"1.008613264s","start":"2026-02-25T17:05:23.390808Z","end":"2026-02-25T17:05:24.399421Z","steps":["trace[1125151190] 'process raft request' (duration: 932.462745ms)","trace[1125151190] 'compare' (duration: 75.937854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:24.399895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.51394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T17:05:24.399916Z","caller":"traceutil/trace.go:171","msg":"trace[30297161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"654.53311ms","start":"2026-02-25T17:05:23.745376Z","end":"2026-02-25T17:05:24.399909Z","steps":["trace[30297161] 'agreement among raft nodes before linearized reading' (duration: 654.458488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:24.399936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.745316Z","time spent":"654.615253ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:24.399998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.390785Z","time spent":"1.009088365s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:24.400069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.682738ms","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-02-25T17:05:24.400057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.730359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:24.400093Z","caller":"traceutil/trace.go:171","msg":"trace[1830782688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"650.735349ms","start":"2026-02-25T17:05:23.74935Z","end":"2026-02-25T17:05:24.400086Z","steps":["trace[1830782688] 'agreement among raft nodes before linearized reading' (duration: 650.677968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:24.400109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.749335Z","time spent":"650.77078ms","remote":"127.0.0.1:57078","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":"info","ts":"2026-02-25T17:05:24.400114Z","caller":"traceutil/trace.go:171","msg":"trace[1478113202] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"205.823882ms","start":"2026-02-25T17:05:24.194276Z","end":"2026-02-25T17:05:24.4001Z","steps":["trace[1478113202] 'agreement among raft nodes before linearized reading' (duration: 205.721999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:24.399802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.103346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:24.400205Z","caller":"traceutil/trace.go:171","msg":"trace[1461928123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"872.527627ms","start":"2026-02-25T17:05:23.527661Z","end":"2026-02-25T17:05:24.400189Z","steps":["trace[1461928123] 'agreement among raft nodes before linearized reading' (duration: 872.097117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:24.400246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:23.527652Z","time spent":"872.581928ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:05:24.582095Z","caller":"traceutil/trace.go:171","msg":"trace[1164021790] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"178.672196ms","start":"2026-02-25T17:05:24.403364Z","end":"2026-02-25T17:05:24.582036Z","steps":["trace[1164021790] 'process raft request' (duration: 159.665837ms)","trace[1164021790] 'compare' (duration: 18.63318ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:24.582125Z","caller":"traceutil/trace.go:171","msg":"trace[1702824212] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"178.480361ms","start":"2026-02-25T17:05:24.403599Z","end":"2026-02-25T17:05:24.582079Z","steps":["trace[1702824212] 'process raft request' (duration: 178.333317ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:24.582341Z","caller":"traceutil/trace.go:171","msg":"trace[833054574] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"178.02783ms","start":"2026-02-25T17:05:24.404303Z","end":"2026-02-25T17:05:24.582331Z","steps":["trace[833054574] 'process raft request' (duration: 177.681261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:24.916696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.855428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:05:24.916775Z","caller":"traceutil/trace.go:171","msg":"trace[1067126851] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:742; }","duration":"194.984331ms","start":"2026-02-25T17:05:24.721774Z","end":"2026-02-25T17:05:24.916759Z","steps":["trace[1067126851] 'count revisions from in-memory index tree' (duration: 194.728464ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:25.990411Z","caller":"traceutil/trace.go:171","msg":"trace[439656462] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"241.198307ms","start":"2026-02-25T17:05:25.749197Z","end":"2026-02-25T17:05:25.990395Z","steps":["trace[439656462] 'process raft request' (duration: 241.100024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.383084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.306734ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:26.3832Z","caller":"traceutil/trace.go:171","msg":"trace[1431192525] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:770; }","duration":"423.003709ms","start":"2026-02-25T17:05:25.960182Z","end":"2026-02-25T17:05:26.383186Z","steps":["trace[1431192525] 'read index received' (duration: 30.138429ms)","trace[1431192525] 'applied index is now lower than readState.Index' (duration: 392.86432ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.383256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.116481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:26.383277Z","caller":"traceutil/trace.go:171","msg":"trace[61870053] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:744; }","duration":"423.164283ms","start":"2026-02-25T17:05:25.960105Z","end":"2026-02-25T17:05:26.38327Z","steps":["trace[61870053] 'agreement among raft nodes before linearized reading' (duration: 423.121982ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:26.383266Z","caller":"traceutil/trace.go:171","msg":"trace[1441167808] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"590.760311ms","start":"2026-02-25T17:05:25.792476Z","end":"2026-02-25T17:05:26.383236Z","steps":["trace[1441167808] 'process raft request' (duration: 271.239272ms)","trace[1441167808] 'compare' (duration: 319.173541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:26.383306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:25.960091Z","time spent":"423.207403ms","remote":"127.0.0.1:57240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:05:26.383428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:25.79244Z","time spent":"590.890764ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:26.756345Z","caller":"traceutil/trace.go:171","msg":"trace[836319313] linearizableReadLoop","detail":"{readStateIndex:778; appliedIndex:776; }","duration":"146.284475ms","start":"2026-02-25T17:05:26.610031Z","end":"2026-02-25T17:05:26.756316Z","steps":["trace[836319313] 'read index received' (duration: 23.250503ms)","trace[836319313] 'applied index is now lower than readState.Index' (duration: 123.032752ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:26.756441Z","caller":"traceutil/trace.go:171","msg":"trace[945886928] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"148.646461ms","start":"2026-02-25T17:05:26.607783Z","end":"2026-02-25T17:05:26.75643Z","steps":["trace[945886928] 'process raft request' (duration: 148.19811ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:26.756492Z","caller":"traceutil/trace.go:171","msg":"trace[142817689] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"148.127049ms","start":"2026-02-25T17:05:26.60835Z","end":"2026-02-25T17:05:26.756477Z","steps":["trace[142817689] 'process raft request' (duration: 147.894662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:26.756755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.719024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T17:05:26.756818Z","caller":"traceutil/trace.go:171","msg":"trace[1921503174] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:749; }","duration":"146.837417ms","start":"2026-02-25T17:05:26.609963Z","end":"2026-02-25T17:05:26.7568Z","steps":["trace[1921503174] 'agreement among raft nodes before linearized reading' (duration: 146.659243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:45.552568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.648735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:05:45.552649Z","caller":"traceutil/trace.go:171","msg":"trace[1437310524] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:803; }","duration":"226.775748ms","start":"2026-02-25T17:05:45.325852Z","end":"2026-02-25T17:05:45.552628Z","steps":["trace[1437310524] 'count revisions from in-memory index tree' (duration: 226.532973ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:46.525329Z","caller":"traceutil/trace.go:171","msg":"trace[215280572] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"333.633189ms","start":"2026-02-25T17:05:46.191628Z","end":"2026-02-25T17:05:46.525261Z","steps":["trace[215280572] 'process raft request' (duration: 333.399373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.525734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.191604Z","time spent":"333.980218ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:46.911075Z","caller":"traceutil/trace.go:171","msg":"trace[1423864883] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"381.140907ms","start":"2026-02-25T17:05:46.529908Z","end":"2026-02-25T17:05:46.911049Z","steps":["trace[1423864883] 'read index received' (duration: 355.796575ms)","trace[1423864883] 'applied index is now lower than readState.Index' (duration: 25.342852ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:46.911092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.528616Z","time spent":"382.466489ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T17:05:46.911227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.29668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:46.911341Z","caller":"traceutil/trace.go:171","msg":"trace[741163314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"381.435854ms","start":"2026-02-25T17:05:46.529875Z","end":"2026-02-25T17:05:46.91131Z","steps":["trace[741163314] 'agreement among raft nodes before linearized reading' (duration: 381.27852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.911412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.52986Z","time spent":"381.535956ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:05:46.975158Z","caller":"traceutil/trace.go:171","msg":"trace[1152193601] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"346.259116ms","start":"2026-02-25T17:05:46.628884Z","end":"2026-02-25T17:05:46.975143Z","steps":["trace[1152193601] 'process raft request' (duration: 346.111262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.975283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.628865Z","time spent":"346.352607ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:46.975328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.435716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:05:46.975356Z","caller":"traceutil/trace.go:171","msg":"trace[1597295840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"357.499686ms","start":"2026-02-25T17:05:46.617847Z","end":"2026-02-25T17:05:46.975347Z","steps":["trace[1597295840] 'agreement among raft nodes before linearized reading' (duration: 357.377784ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:46.975374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:46.617826Z","time spent":"357.543568ms","remote":"127.0.0.1:57078","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-02-25T17:05:48.891893Z","caller":"traceutil/trace.go:171","msg":"trace[1478778165] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"356.247985ms","start":"2026-02-25T17:05:48.535615Z","end":"2026-02-25T17:05:48.891863Z","steps":["trace[1478778165] 'process raft request' (duration: 356.02669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:48.892044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:48.535589Z","time spent":"356.381289ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:05:55.431451Z","caller":"traceutil/trace.go:171","msg":"trace[1789860111] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:862; }","duration":"248.689388ms","start":"2026-02-25T17:05:55.182721Z","end":"2026-02-25T17:05:55.43141Z","steps":["trace[1789860111] 'read index received' (duration: 52.783316ms)","trace[1789860111] 'applied index is now lower than readState.Index' (duration: 195.904622ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:55.431502Z","caller":"traceutil/trace.go:171","msg":"trace[375214696] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"252.999792ms","start":"2026-02-25T17:05:55.178448Z","end":"2026-02-25T17:05:55.431447Z","steps":["trace[375214696] 'process raft request' (duration: 252.583871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:55.431746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.022992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T17:05:55.431765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.018675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T17:05:55.43182Z","caller":"traceutil/trace.go:171","msg":"trace[356826940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:829; }","duration":"249.123658ms","start":"2026-02-25T17:05:55.182681Z","end":"2026-02-25T17:05:55.431805Z","steps":["trace[356826940] 'agreement among raft nodes before linearized reading' (duration: 248.918713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:55.431818Z","caller":"traceutil/trace.go:171","msg":"trace[862193902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:829; }","duration":"248.112384ms","start":"2026-02-25T17:05:55.183685Z","end":"2026-02-25T17:05:55.431798Z","steps":["trace[862193902] 'agreement among raft nodes before linearized reading' (duration: 247.917359ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:56.844007Z","caller":"traceutil/trace.go:171","msg":"trace[202596327] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:866; }","duration":"311.079094ms","start":"2026-02-25T17:05:56.532911Z","end":"2026-02-25T17:05:56.843991Z","steps":["trace[202596327] 'read index received' (duration: 310.947511ms)","trace[202596327] 'applied index is now lower than readState.Index' (duration: 130.793µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:56.844041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.466008Z","time spent":"378.026901ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-25T17:05:56.844098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.173516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:56.844132Z","caller":"traceutil/trace.go:171","msg":"trace[1099515152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:831; }","duration":"311.251049ms","start":"2026-02-25T17:05:56.532874Z","end":"2026-02-25T17:05:56.844125Z","steps":["trace[1099515152] 'agreement among raft nodes before linearized reading' (duration: 311.182917ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:56.84415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.532855Z","time spent":"311.2906ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:57.740282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.424788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:05:57.740515Z","caller":"traceutil/trace.go:171","msg":"trace[1322254129] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"665.103907ms","start":"2026-02-25T17:05:57.075393Z","end":"2026-02-25T17:05:57.740497Z","steps":["trace[1322254129] 'process raft request' (duration: 665.025866ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.74051Z","caller":"traceutil/trace.go:171","msg":"trace[1749347785] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"895.434861ms","start":"2026-02-25T17:05:56.84505Z","end":"2026-02-25T17:05:57.740485Z","steps":["trace[1749347785] 'process raft request' (duration: 115.732487ms)","trace[1749347785] 'compare' (duration: 779.299975ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:05:57.740518Z","caller":"traceutil/trace.go:171","msg":"trace[333119874] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"895.313598ms","start":"2026-02-25T17:05:56.845175Z","end":"2026-02-25T17:05:57.740488Z","steps":["trace[333119874] 'read index received' (duration: 115.632374ms)","trace[333119874] 'applied index is now lower than readState.Index' (duration: 779.679154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:05:57.740591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.07536Z","time spent":"665.19702ms","remote":"127.0.0.1:57078","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-02-25T17:05:57.74064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.845032Z","time spent":"895.532613ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:05:57.740712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.513983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:05:57.740862Z","caller":"traceutil/trace.go:171","msg":"trace[1788014766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"895.714367ms","start":"2026-02-25T17:05:56.845064Z","end":"2026-02-25T17:05:57.740778Z","steps":["trace[1788014766] 'agreement among raft nodes before linearized reading' (duration: 895.553023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.740918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.845029Z","time spent":"895.879401ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:05:57.740979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.031475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T17:05:57.740993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.357273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:05:57.741009Z","caller":"traceutil/trace.go:171","msg":"trace[1581759232] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"292.079945ms","start":"2026-02-25T17:05:57.448921Z","end":"2026-02-25T17:05:57.741001Z","steps":["trace[1581759232] 'agreement among raft nodes before linearized reading' (duration: 291.967473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.741032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.180622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T17:05:57.741037Z","caller":"traceutil/trace.go:171","msg":"trace[1476583613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:833; }","duration":"812.455806ms","start":"2026-02-25T17:05:56.928569Z","end":"2026-02-25T17:05:57.741025Z","steps":["trace[1476583613] 'agreement among raft nodes before linearized reading' (duration: 812.1995ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:05:57.741069Z","caller":"traceutil/trace.go:171","msg":"trace[265194197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"305.258974ms","start":"2026-02-25T17:05:57.435799Z","end":"2026-02-25T17:05:57.741058Z","steps":["trace[265194197] 'agreement among raft nodes before linearized reading' (duration: 305.1005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.741087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:56.928552Z","time spent":"812.517207ms","remote":"127.0.0.1:57078","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-02-25T17:05:57.741098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.435777Z","time spent":"305.315075ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:05:57.74114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.336262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T17:05:57.741224Z","caller":"traceutil/trace.go:171","msg":"trace[630350073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:833; }","duration":"280.413294ms","start":"2026-02-25T17:05:57.460782Z","end":"2026-02-25T17:05:57.741196Z","steps":["trace[630350073] 'agreement among raft nodes before linearized reading' (duration: 280.202149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.741192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.969945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T17:05:57.741354Z","caller":"traceutil/trace.go:171","msg":"trace[134531296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"502.149689ms","start":"2026-02-25T17:05:57.239147Z","end":"2026-02-25T17:05:57.741297Z","steps":["trace[134531296] 'agreement among raft nodes before linearized reading' (duration: 501.835052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:05:57.741444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:05:57.239093Z","time spent":"502.335333ms","remote":"127.0.0.1:57078","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-02-25T17:06:00.668665Z","caller":"traceutil/trace.go:171","msg":"trace[655001754] transaction","detail":"{read_only:false; response_revision:846; number_of_response:1; }","duration":"115.188513ms","start":"2026-02-25T17:06:00.553459Z","end":"2026-02-25T17:06:00.668648Z","steps":["trace[655001754] 'process raft request' (duration: 115.04577ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:00.8036Z","caller":"traceutil/trace.go:171","msg":"trace[1824044160] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"124.107028ms","start":"2026-02-25T17:06:00.679449Z","end":"2026-02-25T17:06:00.803556Z","steps":["trace[1824044160] 'process raft request' (duration: 112.314523ms)","trace[1824044160] 'compare' (duration: 11.487187ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:00.803517Z","caller":"traceutil/trace.go:171","msg":"trace[843641782] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"123.79877ms","start":"2026-02-25T17:06:00.679687Z","end":"2026-02-25T17:06:00.803486Z","steps":["trace[843641782] 'process raft request' (duration: 123.735708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.511359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.251839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:01.511529Z","caller":"traceutil/trace.go:171","msg":"trace[868248173] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"292.256999ms","start":"2026-02-25T17:06:01.219257Z","end":"2026-02-25T17:06:01.511513Z","steps":["trace[868248173] 'process raft request' (duration: 292.198028ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.511626Z","caller":"traceutil/trace.go:171","msg":"trace[832761355] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"294.024802ms","start":"2026-02-25T17:06:01.217566Z","end":"2026-02-25T17:06:01.511591Z","steps":["trace[832761355] 'process raft request' (duration: 119.479876ms)","trace[832761355] 'compare' (duration: 174.042764ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:01.948829Z","caller":"traceutil/trace.go:171","msg":"trace[2131287210] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"124.015595ms","start":"2026-02-25T17:06:01.824771Z","end":"2026-02-25T17:06:01.948787Z","steps":["trace[2131287210] 'process raft request' (duration: 105.748625ms)","trace[2131287210] 'compare' (duration: 18.072915ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:01.949334Z","caller":"traceutil/trace.go:171","msg":"trace[1197027819] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"121.196448ms","start":"2026-02-25T17:06:01.828114Z","end":"2026-02-25T17:06:01.94931Z","steps":["trace[1197027819] 'process raft request' (duration: 120.630334ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.94951Z","caller":"traceutil/trace.go:171","msg":"trace[785482123] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"120.4792ms","start":"2026-02-25T17:06:01.829017Z","end":"2026-02-25T17:06:01.949496Z","steps":["trace[785482123] 'process raft request' (duration: 119.800764ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.949651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.452073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T17:06:01.949698Z","caller":"traceutil/trace.go:171","msg":"trace[520567992] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:889; }","duration":"121.528686ms","start":"2026-02-25T17:06:01.828161Z","end":"2026-02-25T17:06:01.949689Z","steps":["trace[520567992] 'agreement among raft nodes before linearized reading' (duration: 121.393222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.949722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.952028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-25T17:06:01.949812Z","caller":"traceutil/trace.go:171","msg":"trace[1401706061] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:889; }","duration":"125.07038ms","start":"2026-02-25T17:06:01.824725Z","end":"2026-02-25T17:06:01.949795Z","steps":["trace[1401706061] 'agreement among raft nodes before linearized reading' (duration: 124.862005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.949865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.991349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:9307"} {"level":"warn","ts":"2026-02-25T17:06:01.949941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.148148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363634"} {"level":"warn","ts":"2026-02-25T17:06:01.949881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.04866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-25T17:06:01.949961Z","caller":"traceutil/trace.go:171","msg":"trace[1610115789] range","detail":"{range_begin:/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:889; }","duration":"125.103022ms","start":"2026-02-25T17:06:01.824835Z","end":"2026-02-25T17:06:01.949938Z","steps":["trace[1610115789] 'agreement among raft nodes before linearized reading' (duration: 124.846315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.950028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.615099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:06:01.950054Z","caller":"traceutil/trace.go:171","msg":"trace[1089500270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:889; }","duration":"121.665849ms","start":"2026-02-25T17:06:01.828381Z","end":"2026-02-25T17:06:01.950047Z","steps":["trace[1089500270] 'agreement among raft nodes before linearized reading' (duration: 121.602357ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.950042Z","caller":"traceutil/trace.go:171","msg":"trace[2028321817] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ceph-csi-rbd-nodeplugin; range_end:; response_count:1; response_revision:889; }","duration":"125.238605ms","start":"2026-02-25T17:06:01.824767Z","end":"2026-02-25T17:06:01.950005Z","steps":["trace[2028321817] 'agreement among raft nodes before linearized reading' (duration: 124.806914ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.949954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.464561ms","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-02-25T17:06:01.950119Z","caller":"traceutil/trace.go:171","msg":"trace[278787001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:889; }","duration":"135.686868ms","start":"2026-02-25T17:06:01.814427Z","end":"2026-02-25T17:06:01.950114Z","steps":["trace[278787001] 'agreement among raft nodes before linearized reading' (duration: 135.352849ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:01.949969Z","caller":"traceutil/trace.go:171","msg":"trace[861003018] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:889; }","duration":"100.20836ms","start":"2026-02-25T17:06:01.849751Z","end":"2026-02-25T17:06:01.949959Z","steps":["trace[861003018] 'agreement among raft nodes before linearized reading' (duration: 100.034146ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:01.950582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.230453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:01.950629Z","caller":"traceutil/trace.go:171","msg":"trace[1552472154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:889; }","duration":"122.291925ms","start":"2026-02-25T17:06:01.828326Z","end":"2026-02-25T17:06:01.950618Z","steps":["trace[1552472154] 'agreement among raft nodes before linearized reading' (duration: 121.738992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:02.000355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.198375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-vhjsf\" ","response":"range_response_count:1 size:10625"} {"level":"info","ts":"2026-02-25T17:06:02.000503Z","caller":"traceutil/trace.go:171","msg":"trace[137575258] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-vhjsf; range_end:; response_count:1; response_revision:889; }","duration":"135.403141ms","start":"2026-02-25T17:06:01.865064Z","end":"2026-02-25T17:06:02.000467Z","steps":["trace[137575258] 'agreement among raft nodes before linearized reading' (duration: 135.031721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:06.524628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:07.025759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:07.526453Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:08.027626Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114487,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:08.452853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.485774942s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T17:06:08.45322Z","caller":"traceutil/trace.go:171","msg":"trace[217490380] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"2.486235242s","start":"2026-02-25T17:06:05.966964Z","end":"2026-02-25T17:06:08.453199Z","steps":["trace[217490380] 'process raft request' (duration: 2.486102059s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.45337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:05.966946Z","time spent":"2.486349765s","remote":"127.0.0.1:57078","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-02-25T17:06:08.512365Z","caller":"traceutil/trace.go:171","msg":"trace[335458309] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"2.488047913s","start":"2026-02-25T17:06:06.024296Z","end":"2026-02-25T17:06:08.512344Z","steps":["trace[335458309] 'read index received' (duration: 2.428962805s)","trace[335458309] 'applied index is now lower than readState.Index' (duration: 59.084168ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:08.51246Z","caller":"traceutil/trace.go:171","msg":"trace[1024391390] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"688.01144ms","start":"2026-02-25T17:06:07.824436Z","end":"2026-02-25T17:06:08.512447Z","steps":["trace[1024391390] 'process raft request' (duration: 678.07603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.51255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.824412Z","time spent":"688.079221ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:08.512569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488274539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-25T17:06:08.512665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.483422506s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-25T17:06:08.512691Z","caller":"traceutil/trace.go:171","msg":"trace[1599893121] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"2.483529459s","start":"2026-02-25T17:06:06.029154Z","end":"2026-02-25T17:06:08.512684Z","steps":["trace[1599893121] 'agreement among raft nodes before linearized reading' (duration: 2.483390976s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.512693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.481152434s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T17:06:08.512712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.029135Z","time spent":"2.483571729s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:06:08.512713Z","caller":"traceutil/trace.go:171","msg":"trace[1079505461] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"2.481203305s","start":"2026-02-25T17:06:06.031503Z","end":"2026-02-25T17:06:08.512707Z","steps":["trace[1079505461] 'agreement among raft nodes before linearized reading' (duration: 2.481146154s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.512731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.031485Z","time spent":"2.481241066s","remote":"127.0.0.1:57078","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-02-25T17:06:08.512761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.047875232s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.234\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-25T17:06:08.512745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.982704575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:08.512786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.660346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:08.512794Z","caller":"traceutil/trace.go:171","msg":"trace[1587289464] range","detail":"{range_begin:/registry/masterleases/199.19.213.234; range_end:; response_count:1; response_revision:907; }","duration":"2.047947724s","start":"2026-02-25T17:06:06.464837Z","end":"2026-02-25T17:06:08.512785Z","steps":["trace[1587289464] 'agreement among raft nodes before linearized reading' (duration: 2.047841322s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.512799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.482029934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T17:06:08.512809Z","caller":"traceutil/trace.go:171","msg":"trace[1190936265] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:907; }","duration":"397.736139ms","start":"2026-02-25T17:06:08.115066Z","end":"2026-02-25T17:06:08.512802Z","steps":["trace[1190936265] 'agreement among raft nodes before linearized reading' (duration: 397.703458ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:08.51282Z","caller":"traceutil/trace.go:171","msg":"trace[841522074] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"2.482066275s","start":"2026-02-25T17:06:06.030749Z","end":"2026-02-25T17:06:08.512815Z","steps":["trace[841522074] 'agreement among raft nodes before linearized reading' (duration: 2.482006764s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:08.512816Z","caller":"traceutil/trace.go:171","msg":"trace[283473409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:907; }","duration":"1.982789556s","start":"2026-02-25T17:06:06.530008Z","end":"2026-02-25T17:06:08.512798Z","steps":["trace[283473409] 'agreement among raft nodes before linearized reading' (duration: 1.982653113s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.512827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:08.115038Z","time spent":"397.783409ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:08.512823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.464815Z","time spent":"2.048000696s","remote":"127.0.0.1:56834","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.234\" "} {"level":"warn","ts":"2026-02-25T17:06:08.51284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.030739Z","time spent":"2.482094365s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:08.512854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.529964Z","time spent":"1.982881869s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:08.512928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.135891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:08.513014Z","caller":"traceutil/trace.go:171","msg":"trace[2092551085] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:907; }","duration":"970.272254ms","start":"2026-02-25T17:06:07.542723Z","end":"2026-02-25T17:06:08.512996Z","steps":["trace[2092551085] 'agreement among raft nodes before linearized reading' (duration: 970.127351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:08.512601Z","caller":"traceutil/trace.go:171","msg":"trace[48071590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"2.48833684s","start":"2026-02-25T17:06:06.024255Z","end":"2026-02-25T17:06:08.512592Z","steps":["trace[48071590] 'agreement among raft nodes before linearized reading' (duration: 2.488216347s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:08.513062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:07.542703Z","time spent":"970.346765ms","remote":"127.0.0.1:56876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:08.513083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:06.024238Z","time spent":"2.488834332s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:25.261007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.054343ms","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-02-25T17:06:25.261069Z","caller":"traceutil/trace.go:171","msg":"trace[763456222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:953; }","duration":"365.150805ms","start":"2026-02-25T17:06:24.895904Z","end":"2026-02-25T17:06:25.261055Z","steps":["trace[763456222] 'range keys from in-memory index tree' (duration: 364.95388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.261104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.895898Z","time spent":"365.197166ms","remote":"127.0.0.1:57078","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-02-25T17:06:25.261133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.645582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T17:06:25.261194Z","caller":"traceutil/trace.go:171","msg":"trace[1307398207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:953; }","duration":"364.738265ms","start":"2026-02-25T17:06:24.896443Z","end":"2026-02-25T17:06:25.261181Z","steps":["trace[1307398207] 'range keys from in-memory index tree' (duration: 364.518609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.261228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.896433Z","time spent":"364.787396ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T17:06:25.261342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.751528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:06:25.261368Z","caller":"traceutil/trace.go:171","msg":"trace[1509058042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:953; }","duration":"365.81207ms","start":"2026-02-25T17:06:24.895549Z","end":"2026-02-25T17:06:25.261362Z","steps":["trace[1509058042] 'range keys from in-memory index tree' (duration: 365.675167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.261385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:24.89553Z","time spent":"365.8501ms","remote":"127.0.0.1:57078","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-02-25T17:06:25.863101Z","caller":"traceutil/trace.go:171","msg":"trace[1028312286] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"598.359673ms","start":"2026-02-25T17:06:25.264718Z","end":"2026-02-25T17:06:25.863077Z","steps":["trace[1028312286] 'process raft request' (duration: 598.19655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:25.863297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.2647Z","time spent":"598.515637ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:26.569535Z","caller":"traceutil/trace.go:171","msg":"trace[44483140] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:995; }","duration":"1.039762143s","start":"2026-02-25T17:06:25.529758Z","end":"2026-02-25T17:06:26.56952Z","steps":["trace[44483140] 'read index received' (duration: 333.127393ms)","trace[44483140] 'applied index is now lower than readState.Index' (duration: 706.6342ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:26.569638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039872375s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:26.569665Z","caller":"traceutil/trace.go:171","msg":"trace[2100268802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"1.039941907s","start":"2026-02-25T17:06:25.529716Z","end":"2026-02-25T17:06:26.569658Z","steps":["trace[2100268802] 'agreement among raft nodes before linearized reading' (duration: 1.039881316s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:26.569572Z","caller":"traceutil/trace.go:171","msg":"trace[594238575] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.304750528s","start":"2026-02-25T17:06:25.26481Z","end":"2026-02-25T17:06:26.56956Z","steps":["trace[594238575] 'process raft request' (duration: 1.304687446s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:26.569737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.312238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T17:06:26.569769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.237653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.234\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T17:06:26.569558Z","caller":"traceutil/trace.go:171","msg":"trace[667994752] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.304794989s","start":"2026-02-25T17:06:25.264739Z","end":"2026-02-25T17:06:26.569534Z","steps":["trace[667994752] 'process raft request' (duration: 1.304661376s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:26.56977Z","caller":"traceutil/trace.go:171","msg":"trace[1042442956] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:956; }","duration":"790.382429ms","start":"2026-02-25T17:06:25.779382Z","end":"2026-02-25T17:06:26.569765Z","steps":["trace[1042442956] 'agreement among raft nodes before linearized reading' (duration: 790.317357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:26.569821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.264803Z","time spent":"1.304926393s","remote":"127.0.0.1:57078","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-02-25T17:06:26.569872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.264719Z","time spent":"1.305090486s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:26.569867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.779359Z","time spent":"790.501682ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":31,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:26.569688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:25.529702Z","time spent":"1.039980548s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:26.569785Z","caller":"traceutil/trace.go:171","msg":"trace[1487443405] range","detail":"{range_begin:/registry/masterleases/199.19.213.234; range_end:; response_count:1; response_revision:956; }","duration":"104.294254ms","start":"2026-02-25T17:06:26.465487Z","end":"2026-02-25T17:06:26.569781Z","steps":["trace[1487443405] 'agreement among raft nodes before linearized reading' (duration: 104.222322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.071069Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114632,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:27.378567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.570691Z","time spent":"807.871005ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:06:27.378566Z","caller":"traceutil/trace.go:171","msg":"trace[547825534] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"807.772892ms","start":"2026-02-25T17:06:26.570775Z","end":"2026-02-25T17:06:27.378548Z","steps":["trace[547825534] 'read index received' (duration: 805.211193ms)","trace[547825534] 'applied index is now lower than readState.Index' (duration: 2.560489ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:27.378635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.851815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:27.378665Z","caller":"traceutil/trace.go:171","msg":"trace[1689448679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"807.962177ms","start":"2026-02-25T17:06:26.570695Z","end":"2026-02-25T17:06:27.378657Z","steps":["trace[1689448679] 'agreement among raft nodes before linearized reading' (duration: 807.901225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.378684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.570678Z","time spent":"808.001568ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:27.37893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.867995ms","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-02-25T17:06:27.378935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.804906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:27.378959Z","caller":"traceutil/trace.go:171","msg":"trace[12239763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:956; }","duration":"620.928916ms","start":"2026-02-25T17:06:26.758022Z","end":"2026-02-25T17:06:27.378951Z","steps":["trace[12239763] 'agreement among raft nodes before linearized reading' (duration: 620.843484ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:27.378965Z","caller":"traceutil/trace.go:171","msg":"trace[197685692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"748.869408ms","start":"2026-02-25T17:06:26.630087Z","end":"2026-02-25T17:06:27.378956Z","steps":["trace[197685692] 'agreement among raft nodes before linearized reading' (duration: 748.759306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:27.37898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.758003Z","time spent":"620.969657ms","remote":"127.0.0.1:57078","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-02-25T17:06:27.378984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:26.630066Z","time spent":"748.91254ms","remote":"127.0.0.1:57078","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-02-25T17:06:27.379002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.860819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:27.379056Z","caller":"traceutil/trace.go:171","msg":"trace[1398438428] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:956; }","duration":"155.965271ms","start":"2026-02-25T17:06:27.223076Z","end":"2026-02-25T17:06:27.379041Z","steps":["trace[1398438428] 'agreement among raft nodes before linearized reading' (duration: 155.774027ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:28.41646Z","caller":"traceutil/trace.go:171","msg":"trace[1400108546] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"153.388381ms","start":"2026-02-25T17:06:28.263047Z","end":"2026-02-25T17:06:28.416436Z","steps":["trace[1400108546] 'process raft request' (duration: 59.422227ms)","trace[1400108546] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-vhjsf.18978c40c82a97ce; req_size:820; } (duration: 93.846802ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:32.787015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348449049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:32.787101Z","caller":"traceutil/trace.go:171","msg":"trace[382379016] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"1.348592192s","start":"2026-02-25T17:06:31.438489Z","end":"2026-02-25T17:06:32.787081Z","steps":["trace[382379016] 'range keys from in-memory index tree' (duration: 1.348234335s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:32.787109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.348357797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:32.787169Z","caller":"traceutil/trace.go:171","msg":"trace[1816959206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"1.348461689s","start":"2026-02-25T17:06:31.438697Z","end":"2026-02-25T17:06:32.787159Z","steps":["trace[1816959206] 'range keys from in-memory index tree' (duration: 1.348196253s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:32.787166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.43846Z","time spent":"1.348695525s","remote":"127.0.0.1:57078","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-02-25T17:06:32.787191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.43867Z","time spent":"1.348515511s","remote":"127.0.0.1:57078","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-02-25T17:06:32.787576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.633704084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hh5vs\" ","response":"range_response_count:1 size:10928"} {"level":"info","ts":"2026-02-25T17:06:32.787688Z","caller":"traceutil/trace.go:171","msg":"trace[781553473] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hh5vs; range_end:; response_count:1; response_revision:984; }","duration":"1.63388936s","start":"2026-02-25T17:06:31.15377Z","end":"2026-02-25T17:06:32.787659Z","steps":["trace[781553473] 'range keys from in-memory index tree' (duration: 1.633199392s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:32.787813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.153752Z","time spent":"1.634039453s","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":10952,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hh5vs\" "} {"level":"warn","ts":"2026-02-25T17:06:33.457073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.5195192s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T17:06:33.457331Z","caller":"traceutil/trace.go:171","msg":"trace[1535138410] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"1.924911217s","start":"2026-02-25T17:06:31.532387Z","end":"2026-02-25T17:06:33.457298Z","steps":["trace[1535138410] 'read index received' (duration: 405.168611ms)","trace[1535138410] 'applied index is now lower than readState.Index' (duration: 1.519740486s)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:33.457443Z","caller":"traceutil/trace.go:171","msg":"trace[1255049575] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"666.228215ms","start":"2026-02-25T17:06:32.791188Z","end":"2026-02-25T17:06:33.457417Z","steps":["trace[1255049575] 'process raft request' (duration: 666.00046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.45759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.791155Z","time spent":"666.360238ms","remote":"127.0.0.1:57078","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-02-25T17:06:33.626405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.093994751s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:33.626492Z","caller":"traceutil/trace.go:171","msg":"trace[1002399316] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:985; }","duration":"2.094152665s","start":"2026-02-25T17:06:31.532316Z","end":"2026-02-25T17:06:33.626469Z","steps":["trace[1002399316] 'agreement among raft nodes before linearized reading' (duration: 1.92506827s)","trace[1002399316] 'range keys from in-memory index tree' (duration: 168.795678ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:33.626537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.532286Z","time spent":"2.094241097s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:33.626788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.360663ms","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-02-25T17:06:33.626781Z","caller":"traceutil/trace.go:171","msg":"trace[724987075] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1030; }","duration":"169.199397ms","start":"2026-02-25T17:06:33.45737Z","end":"2026-02-25T17:06:33.62657Z","steps":["trace[724987075] 'read index received' (duration: 160.406334ms)","trace[724987075] 'applied index is now lower than readState.Index' (duration: 8.792093ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:33.626828Z","caller":"traceutil/trace.go:171","msg":"trace[1900351463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:987; }","duration":"950.435855ms","start":"2026-02-25T17:06:32.676383Z","end":"2026-02-25T17:06:33.626818Z","steps":["trace[1900351463] 'agreement among raft nodes before linearized reading' (duration: 950.301892ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:33.626757Z","caller":"traceutil/trace.go:171","msg":"trace[1805120039] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"827.339225ms","start":"2026-02-25T17:06:32.799332Z","end":"2026-02-25T17:06:33.626671Z","steps":["trace[1805120039] 'process raft request' (duration: 827.178732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.626861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.676362Z","time spent":"950.491977ms","remote":"127.0.0.1:57078","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-02-25T17:06:33.626809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43048945s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T17:06:33.626918Z","caller":"traceutil/trace.go:171","msg":"trace[349853686] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"834.703306ms","start":"2026-02-25T17:06:32.7922Z","end":"2026-02-25T17:06:33.626903Z","steps":["trace[349853686] 'process raft request' (duration: 825.579935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.626978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.666975095s","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-02-25T17:06:33.627027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.792175Z","time spent":"834.796228ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:33.627048Z","caller":"traceutil/trace.go:171","msg":"trace[1841497217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:987; }","duration":"1.667091448s","start":"2026-02-25T17:06:31.959939Z","end":"2026-02-25T17:06:33.627031Z","steps":["trace[1841497217] 'agreement among raft nodes before linearized reading' (duration: 1.666926124s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.627026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.799311Z","time spent":"827.603011ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11176,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:33.626802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.45004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-25T17:06:33.627099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:31.959922Z","time spent":"1.667163689s","remote":"127.0.0.1:57078","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-02-25T17:06:33.627167Z","caller":"traceutil/trace.go:171","msg":"trace[1909076174] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:987; }","duration":"904.854439ms","start":"2026-02-25T17:06:32.722304Z","end":"2026-02-25T17:06:33.627158Z","steps":["trace[1909076174] 'agreement among raft nodes before linearized reading' (duration: 904.342048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.627196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.722284Z","time spent":"904.904551ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T17:06:33.626957Z","caller":"traceutil/trace.go:171","msg":"trace[1848812441] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:987; }","duration":"1.430687344s","start":"2026-02-25T17:06:32.19625Z","end":"2026-02-25T17:06:33.626937Z","steps":["trace[1848812441] 'agreement among raft nodes before linearized reading' (duration: 1.430450579s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.627253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:32.196227Z","time spent":"1.431014352s","remote":"127.0.0.1:57154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":14,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-02-25T17:06:33.650876Z","caller":"traceutil/trace.go:171","msg":"trace[2042377629] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"108.083583ms","start":"2026-02-25T17:06:33.542775Z","end":"2026-02-25T17:06:33.650859Z","steps":["trace[2042377629] 'process raft request' (duration: 107.743765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:33.65143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.707612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:33.652412Z","caller":"traceutil/trace.go:171","msg":"trace[1930096335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"120.722985ms","start":"2026-02-25T17:06:33.531675Z","end":"2026-02-25T17:06:33.652398Z","steps":["trace[1930096335] 'agreement among raft nodes before linearized reading' (duration: 119.714332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.151328Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114704,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:34.906578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.480546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:34.907022Z","caller":"traceutil/trace.go:171","msg":"trace[1473499669] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1033; }","duration":"1.256380007s","start":"2026-02-25T17:06:33.650628Z","end":"2026-02-25T17:06:34.907008Z","steps":["trace[1473499669] 'read index received' (duration: 600.416031ms)","trace[1473499669] 'applied index is now lower than readState.Index' (duration: 655.963006ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:34.907059Z","caller":"traceutil/trace.go:171","msg":"trace[629795501] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.272103062s","start":"2026-02-25T17:06:33.634916Z","end":"2026-02-25T17:06:34.907019Z","steps":["trace[629795501] 'process raft request' (duration: 1.271922688s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.907279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.634907Z","time spent":"1.272283047s","remote":"127.0.0.1:57078","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-02-25T17:06:34.907319Z","caller":"traceutil/trace.go:171","msg":"trace[181456296] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.270366361s","start":"2026-02-25T17:06:33.636941Z","end":"2026-02-25T17:06:34.907307Z","steps":["trace[181456296] 'process raft request' (duration: 1.269925951s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.90741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.636932Z","time spent":"1.270415603s","remote":"127.0.0.1:57080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:34.907494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280486576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:34.907548Z","caller":"traceutil/trace.go:171","msg":"trace[2056004212] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.27289831s","start":"2026-02-25T17:06:33.634637Z","end":"2026-02-25T17:06:34.907536Z","steps":["trace[2056004212] 'process raft request' (duration: 1.272026101s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:34.907583Z","caller":"traceutil/trace.go:171","msg":"trace[363053283] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.266202986s","start":"2026-02-25T17:06:33.641368Z","end":"2026-02-25T17:06:34.90757Z","steps":["trace[363053283] 'process raft request' (duration: 1.265568942s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.90761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.634621Z","time spent":"1.272955911s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:34.907619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242267221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:34.907634Z","caller":"traceutil/trace.go:171","msg":"trace[1849969232] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.247171945s","start":"2026-02-25T17:06:33.66045Z","end":"2026-02-25T17:06:34.907622Z","steps":["trace[1849969232] 'process raft request' (duration: 1.246524121s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:34.907653Z","caller":"traceutil/trace.go:171","msg":"trace[320214314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:995; }","duration":"1.242340103s","start":"2026-02-25T17:06:33.665303Z","end":"2026-02-25T17:06:34.907643Z","steps":["trace[320214314] 'agreement among raft nodes before linearized reading' (duration: 1.242270711s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.907706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.660423Z","time spent":"1.247245076s","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:34.907715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.665279Z","time spent":"1.242426155s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:34.907778Z","caller":"traceutil/trace.go:171","msg":"trace[1906615341] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"1.273056814s","start":"2026-02-25T17:06:33.634707Z","end":"2026-02-25T17:06:34.907764Z","steps":["trace[1906615341] 'process raft request' (duration: 1.272081851s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:34.907586Z","caller":"traceutil/trace.go:171","msg":"trace[645915041] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:995; }","duration":"1.280619449s","start":"2026-02-25T17:06:33.626946Z","end":"2026-02-25T17:06:34.907566Z","steps":["trace[645915041] 'agreement among raft nodes before linearized reading' (duration: 1.280453025s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:34.910036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.626932Z","time spent":"1.282925883s","remote":"127.0.0.1:52558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T17:06:34.907711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.64135Z","time spent":"1.266289178s","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:34.90751Z","caller":"traceutil/trace.go:171","msg":"trace[540340329] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.272912821s","start":"2026-02-25T17:06:33.634553Z","end":"2026-02-25T17:06:34.907466Z","steps":["trace[540340329] 'process raft request' (duration: 616.478453ms)","trace[540340329] 'compare' (duration: 655.21049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:34.910491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.634533Z","time spent":"1.275871119s","remote":"127.0.0.1:56980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:34.907912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:33.634693Z","time spent":"1.273129536s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:37.031895Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114715,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:06:37.209331Z","caller":"traceutil/trace.go:171","msg":"trace[508477964] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"678.353215ms","start":"2026-02-25T17:06:36.53095Z","end":"2026-02-25T17:06:37.209303Z","steps":["trace[508477964] 'read index received' (duration: 678.11303ms)","trace[508477964] 'applied index is now lower than readState.Index' (duration: 238.585µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:37.209508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.546369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:37.209339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:36.467468Z","time spent":"741.863206ms","remote":"127.0.0.1:56834","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T17:06:37.209546Z","caller":"traceutil/trace.go:171","msg":"trace[915976515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"678.639011ms","start":"2026-02-25T17:06:36.530898Z","end":"2026-02-25T17:06:37.209537Z","steps":["trace[915976515] 'agreement among raft nodes before linearized reading' (duration: 678.55505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.20958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:36.530861Z","time spent":"678.712003ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:37.209722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.998256ms","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-02-25T17:06:37.209781Z","caller":"traceutil/trace.go:171","msg":"trace[1954097118] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"297.079218ms","start":"2026-02-25T17:06:36.912686Z","end":"2026-02-25T17:06:37.209765Z","steps":["trace[1954097118] 'agreement among raft nodes before linearized reading' (duration: 296.891323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.209751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.55316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T17:06:37.209913Z","caller":"traceutil/trace.go:171","msg":"trace[746071996] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"294.738244ms","start":"2026-02-25T17:06:36.915157Z","end":"2026-02-25T17:06:37.209895Z","steps":["trace[746071996] 'agreement among raft nodes before linearized reading' (duration: 294.359845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.210519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.943377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T17:06:37.210661Z","caller":"traceutil/trace.go:171","msg":"trace[1268272736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:998; }","duration":"298.113522ms","start":"2026-02-25T17:06:36.912535Z","end":"2026-02-25T17:06:37.210649Z","steps":["trace[1268272736] 'agreement among raft nodes before linearized reading' (duration: 296.958975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.712524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114719,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T17:06:37.871676Z","caller":"traceutil/trace.go:171","msg":"trace[1184374034] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"661.069816ms","start":"2026-02-25T17:06:37.210581Z","end":"2026-02-25T17:06:37.871651Z","steps":["trace[1184374034] 'process raft request' (duration: 603.37176ms)","trace[1184374034] 'compare' (duration: 57.345788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:37.871786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.210562Z","time spent":"661.165758ms","remote":"127.0.0.1:56834","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-02-25T17:06:37.967504Z","caller":"traceutil/trace.go:171","msg":"trace[153961766] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"754.167852ms","start":"2026-02-25T17:06:37.213306Z","end":"2026-02-25T17:06:37.967473Z","steps":["trace[153961766] 'process raft request' (duration: 753.898245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.967632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.196308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:37.967608Z","caller":"traceutil/trace.go:171","msg":"trace[1024905587] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"752.799019ms","start":"2026-02-25T17:06:37.214777Z","end":"2026-02-25T17:06:37.967576Z","steps":["trace[1024905587] 'process raft request' (duration: 752.596535ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:37.967702Z","caller":"traceutil/trace.go:171","msg":"trace[1617852507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"756.307591ms","start":"2026-02-25T17:06:37.21138Z","end":"2026-02-25T17:06:37.967687Z","steps":["trace[1617852507] 'agreement among raft nodes before linearized reading' (duration: 756.170268ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:37.967721Z","caller":"traceutil/trace.go:171","msg":"trace[597353045] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"752.653016ms","start":"2026-02-25T17:06:37.215034Z","end":"2026-02-25T17:06:37.967687Z","steps":["trace[597353045] 'process raft request' (duration: 752.38151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.967749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.21136Z","time spent":"756.376302ms","remote":"127.0.0.1:56804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:37.967643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.213288Z","time spent":"754.281504ms","remote":"127.0.0.1:57078","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-02-25T17:06:37.967858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.215027Z","time spent":"752.747299ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:37.967894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.214765Z","time spent":"753.007585ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:37.967499Z","caller":"traceutil/trace.go:171","msg":"trace[905390286] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1044; }","duration":"756.064755ms","start":"2026-02-25T17:06:37.211412Z","end":"2026-02-25T17:06:37.967477Z","steps":["trace[905390286] 'read index received' (duration: 602.557841ms)","trace[905390286] 'applied index is now lower than readState.Index' (duration: 153.505924ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:37.968107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.523805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:37.968146Z","caller":"traceutil/trace.go:171","msg":"trace[987383202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1002; }","duration":"461.596037ms","start":"2026-02-25T17:06:37.506538Z","end":"2026-02-25T17:06:37.968134Z","steps":["trace[987383202] 'agreement among raft nodes before linearized reading' (duration: 461.481074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:37.968171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.506519Z","time spent":"461.645399ms","remote":"127.0.0.1:57078","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-02-25T17:06:37.968286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.821594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:37.968322Z","caller":"traceutil/trace.go:171","msg":"trace[726039024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"265.897356ms","start":"2026-02-25T17:06:37.702415Z","end":"2026-02-25T17:06:37.968312Z","steps":["trace[726039024] 'agreement among raft nodes before linearized reading' (duration: 265.818614ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:38.109691Z","caller":"traceutil/trace.go:171","msg":"trace[1447481953] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"136.624792ms","start":"2026-02-25T17:06:37.973042Z","end":"2026-02-25T17:06:38.109667Z","steps":["trace[1447481953] 'process raft request' (duration: 136.441148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:38.355785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.579021ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:38.355909Z","caller":"traceutil/trace.go:171","msg":"trace[1987110849] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"288.290765ms","start":"2026-02-25T17:06:38.067599Z","end":"2026-02-25T17:06:38.35589Z","steps":["trace[1987110849] 'read index received' (duration: 41.919191ms)","trace[1987110849] 'applied index is now lower than readState.Index' (duration: 246.369844ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:38.35594Z","caller":"traceutil/trace.go:171","msg":"trace[2139820589] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"291.754884ms","start":"2026-02-25T17:06:38.064156Z","end":"2026-02-25T17:06:38.355911Z","steps":["trace[2139820589] 'process raft request' (duration: 164.972909ms)","trace[2139820589] 'compare' (duration: 126.381576ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:38.35602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.854188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:38.356065Z","caller":"traceutil/trace.go:171","msg":"trace[1320192225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"380.95093ms","start":"2026-02-25T17:06:37.9751Z","end":"2026-02-25T17:06:38.356051Z","steps":["trace[1320192225] 'agreement among raft nodes before linearized reading' (duration: 380.848437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:38.35611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:37.975088Z","time spent":"381.011641ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T17:06:39.773276Z","caller":"traceutil/trace.go:171","msg":"trace[1308949361] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"568.43697ms","start":"2026-02-25T17:06:39.204811Z","end":"2026-02-25T17:06:39.773248Z","steps":["trace[1308949361] 'process raft request' (duration: 568.272477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:39.773636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:39.204782Z","time spent":"568.782009ms","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T17:06:39.774957Z","caller":"traceutil/trace.go:171","msg":"trace[1117846455] linearizableReadLoop","detail":"{readStateIndex:1053; appliedIndex:1051; }","duration":"247.127411ms","start":"2026-02-25T17:06:39.52781Z","end":"2026-02-25T17:06:39.774937Z","steps":["trace[1117846455] 'read index received' (duration: 245.308388ms)","trace[1117846455] 'applied index is now lower than readState.Index' (duration: 1.817703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:39.775086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.260875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:39.775179Z","caller":"traceutil/trace.go:171","msg":"trace[2018860179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"247.398658ms","start":"2026-02-25T17:06:39.527763Z","end":"2026-02-25T17:06:39.775162Z","steps":["trace[2018860179] 'agreement among raft nodes before linearized reading' (duration: 247.250945ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:39.775328Z","caller":"traceutil/trace.go:171","msg":"trace[1695728214] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"434.251834ms","start":"2026-02-25T17:06:39.341043Z","end":"2026-02-25T17:06:39.775294Z","steps":["trace[1695728214] 'process raft request' (duration: 433.787713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:39.775487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:39.341012Z","time spent":"434.395367ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:40.159363Z","caller":"traceutil/trace.go:171","msg":"trace[1249693447] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"178.102594ms","start":"2026-02-25T17:06:39.981191Z","end":"2026-02-25T17:06:40.159294Z","steps":["trace[1249693447] 'process raft request' (duration: 177.887089ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.11882Z","caller":"traceutil/trace.go:171","msg":"trace[7028809] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"1.067142097s","start":"2026-02-25T17:06:40.051658Z","end":"2026-02-25T17:06:41.1188Z","steps":["trace[7028809] 'read index received' (duration: 107.657732ms)","trace[7028809] 'applied index is now lower than readState.Index' (duration: 959.483615ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:41.118893Z","caller":"traceutil/trace.go:171","msg":"trace[752906742] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.136930074s","start":"2026-02-25T17:06:39.981957Z","end":"2026-02-25T17:06:41.118887Z","steps":["trace[752906742] 'process raft request' (duration: 1.13681425s)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:41.118916Z","caller":"traceutil/trace.go:171","msg":"trace[114342428] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.136978625s","start":"2026-02-25T17:06:39.981909Z","end":"2026-02-25T17:06:41.118888Z","steps":["trace[114342428] 'process raft request' (duration: 1.136735968s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:41.118972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:39.981925Z","time spent":"1.136985935s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:06:41.119043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067355292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T17:06:41.119073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:39.981899Z","time spent":"1.137089667s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T17:06:41.119155Z","caller":"traceutil/trace.go:171","msg":"trace[1411336117] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1010; }","duration":"1.067486606s","start":"2026-02-25T17:06:40.051624Z","end":"2026-02-25T17:06:41.11911Z","steps":["trace[1411336117] 'agreement among raft nodes before linearized reading' (duration: 1.067294491s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:41.119218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:40.051605Z","time spent":"1.067602358s","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T17:06:41.619025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794616037586114744,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T17:06:42.293838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.2839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:06:42.293925Z","caller":"traceutil/trace.go:171","msg":"trace[2009387236] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1056; }","duration":"1.175046305s","start":"2026-02-25T17:06:41.118867Z","end":"2026-02-25T17:06:42.293913Z","steps":["trace[2009387236] 'read index received' (duration: 710.574811ms)","trace[2009387236] 'applied index is now lower than readState.Index' (duration: 464.470644ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:42.294007Z","caller":"traceutil/trace.go:171","msg":"trace[1944775718] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.855555921s","start":"2026-02-25T17:06:40.438439Z","end":"2026-02-25T17:06:42.293995Z","steps":["trace[1944775718] 'process raft request' (duration: 1.391067736s)","trace[1944775718] 'compare' (duration: 464.108236ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:42.29406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:40.43842Z","time spent":"1.855616692s","remote":"127.0.0.1:56874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T17:06:42.294256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.76392842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:42.294283Z","caller":"traceutil/trace.go:171","msg":"trace[1267982355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"1.763991101s","start":"2026-02-25T17:06:40.530284Z","end":"2026-02-25T17:06:42.294275Z","steps":["trace[1267982355] 'agreement among raft nodes before linearized reading' (duration: 1.763923629s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:42.294303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:40.530264Z","time spent":"1.764034663s","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T17:06:42.294525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.179513942s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:42.294558Z","caller":"traceutil/trace.go:171","msg":"trace[869003791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"2.179597863s","start":"2026-02-25T17:06:40.114952Z","end":"2026-02-25T17:06:42.29455Z","steps":["trace[869003791] 'agreement among raft nodes before linearized reading' (duration: 2.17943349s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:42.29458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:40.114916Z","time spent":"2.179657095s","remote":"127.0.0.1:57078","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-02-25T17:06:42.294953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.223688ms","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-02-25T17:06:42.29494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170448168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:06:42.294983Z","caller":"traceutil/trace.go:171","msg":"trace[1402451467] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"131.28152ms","start":"2026-02-25T17:06:42.163694Z","end":"2026-02-25T17:06:42.294976Z","steps":["trace[1402451467] 'agreement among raft nodes before linearized reading' (duration: 131.208058ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:42.295008Z","caller":"traceutil/trace.go:171","msg":"trace[1779751419] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1011; }","duration":"1.170525629s","start":"2026-02-25T17:06:41.124465Z","end":"2026-02-25T17:06:42.294991Z","steps":["trace[1779751419] 'agreement among raft nodes before linearized reading' (duration: 1.170410967s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:42.295061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:41.124403Z","time spent":"1.170649014s","remote":"127.0.0.1:56876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-25T17:06:42.295575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.223645984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:42.295809Z","caller":"traceutil/trace.go:171","msg":"trace[1243168854] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1011; }","duration":"2.223734216s","start":"2026-02-25T17:06:40.071902Z","end":"2026-02-25T17:06:42.295637Z","steps":["trace[1243168854] 'agreement among raft nodes before linearized reading' (duration: 2.222763533s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:42.295876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:06:40.071814Z","time spent":"2.224049183s","remote":"127.0.0.1:57078","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":"info","ts":"2026-02-25T17:06:46.463434Z","caller":"traceutil/trace.go:171","msg":"trace[13654268] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"144.996626ms","start":"2026-02-25T17:06:46.318417Z","end":"2026-02-25T17:06:46.463413Z","steps":["trace[13654268] 'process raft request' (duration: 144.877904ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:46.463863Z","caller":"traceutil/trace.go:171","msg":"trace[1396291007] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1081; }","duration":"144.750452ms","start":"2026-02-25T17:06:46.319104Z","end":"2026-02-25T17:06:46.463854Z","steps":["trace[1396291007] 'read index received' (duration: 144.747232ms)","trace[1396291007] 'applied index is now lower than readState.Index' (duration: 2.59µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:06:46.463983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.037855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:46.464006Z","caller":"traceutil/trace.go:171","msg":"trace[416872109] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1034; }","duration":"128.103076ms","start":"2026-02-25T17:06:46.335896Z","end":"2026-02-25T17:06:46.463999Z","steps":["trace[416872109] 'agreement among raft nodes before linearized reading' (duration: 128.004994ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:46.464179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.079379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:46.464201Z","caller":"traceutil/trace.go:171","msg":"trace[966254321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"145.12809ms","start":"2026-02-25T17:06:46.319066Z","end":"2026-02-25T17:06:46.464194Z","steps":["trace[966254321] 'agreement among raft nodes before linearized reading' (duration: 145.066449ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:56.668357Z","caller":"traceutil/trace.go:171","msg":"trace[982679996] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"160.850904ms","start":"2026-02-25T17:06:56.507489Z","end":"2026-02-25T17:06:56.66834Z","steps":["trace[982679996] 'read index received' (duration: 160.67552ms)","trace[982679996] 'applied index is now lower than readState.Index' (duration: 174.804µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:06:56.668424Z","caller":"traceutil/trace.go:171","msg":"trace[1457873648] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"161.440288ms","start":"2026-02-25T17:06:56.506961Z","end":"2026-02-25T17:06:56.668401Z","steps":["trace[1457873648] 'process raft request' (duration: 161.249673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:56.668633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.150367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-25T17:06:56.668685Z","caller":"traceutil/trace.go:171","msg":"trace[326090441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1099; }","duration":"160.23016ms","start":"2026-02-25T17:06:56.50844Z","end":"2026-02-25T17:06:56.66867Z","steps":["trace[326090441] 'agreement among raft nodes before linearized reading' (duration: 160.077436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:06:56.668835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.317925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-25T17:06:56.668953Z","caller":"traceutil/trace.go:171","msg":"trace[985411736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1099; }","duration":"161.440407ms","start":"2026-02-25T17:06:56.507454Z","end":"2026-02-25T17:06:56.668895Z","steps":["trace[985411736] 'agreement among raft nodes before linearized reading' (duration: 160.986807ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:56.787215Z","caller":"traceutil/trace.go:171","msg":"trace[788212725] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"114.627454ms","start":"2026-02-25T17:06:56.672565Z","end":"2026-02-25T17:06:56.787192Z","steps":["trace[788212725] 'process raft request' (duration: 113.924417ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:56.787835Z","caller":"traceutil/trace.go:171","msg":"trace[813491603] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"114.610093ms","start":"2026-02-25T17:06:56.673171Z","end":"2026-02-25T17:06:56.787781Z","steps":["trace[813491603] 'process raft request' (duration: 114.527641ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:06:56.787937Z","caller":"traceutil/trace.go:171","msg":"trace[1925287937] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"114.90769ms","start":"2026-02-25T17:06:56.673005Z","end":"2026-02-25T17:06:56.787913Z","steps":["trace[1925287937] 'process raft request' (duration: 114.499041ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:42.308034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.405886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:09:42.30816Z","caller":"traceutil/trace.go:171","msg":"trace[1497068281] transaction","detail":"{read_only:false; response_revision:1798; number_of_response:1; }","duration":"201.161669ms","start":"2026-02-25T17:09:42.10698Z","end":"2026-02-25T17:09:42.308141Z","steps":["trace[1497068281] 'process raft request' (duration: 45.57203ms)","trace[1497068281] 'compare' (duration: 155.308983ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:09:54.607425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.85374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T17:09:54.607557Z","caller":"traceutil/trace.go:171","msg":"trace[369746122] linearizableReadLoop","detail":"{readStateIndex:1943; appliedIndex:1942; }","duration":"414.674792ms","start":"2026-02-25T17:09:54.192862Z","end":"2026-02-25T17:09:54.607537Z","steps":["trace[369746122] 'read index received' (duration: 53.431µs)","trace[369746122] 'applied index is now lower than readState.Index' (duration: 414.619841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:09:54.607807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.941339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T17:09:54.607838Z","caller":"traceutil/trace.go:171","msg":"trace[2140983868] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"478.275277ms","start":"2026-02-25T17:09:54.129542Z","end":"2026-02-25T17:09:54.607817Z","steps":["trace[2140983868] 'process raft request' (duration: 41.925415ms)","trace[2140983868] 'compare' (duration: 435.729558ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T17:09:54.607887Z","caller":"traceutil/trace.go:171","msg":"trace[791620437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1857; }","duration":"415.053132ms","start":"2026-02-25T17:09:54.192813Z","end":"2026-02-25T17:09:54.607866Z","steps":["trace[791620437] 'agreement among raft nodes before linearized reading' (duration: 414.933119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T17:09:54.607926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:54.129528Z","time spent":"478.351598ms","remote":"127.0.0.1:57078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T17:09:54.607942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T17:09:54.192793Z","time spent":"415.136393ms","remote":"127.0.0.1:56788","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}