{"level":"warn","ts":"2026-04-18T22:14:37.075268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:14:37.075375Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.204.45.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-18T22:14:37.075436Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:14:37.075445Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-18T22:14:37.075464Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:14:37.076185Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-04-18T22:14:37.076274Z","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.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-18T22:14:37.103086Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.475903ms"} {"level":"info","ts":"2026-04-18T22:14:37.125404Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-04-18T22:14:37.125527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:14:37.125566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-04-18T22:14:37.125592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:14:37.125611Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-04-18T22:14:37.125663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-04-18T22:14:37.170216Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:14:37.172507Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:14:37.176798Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-18T22:14:37.188258Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:14:37.188482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:14:37.188668Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:14:37.188722Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:14:37.188734Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-18T22:14:37.191595Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-04-18T22:14:37.1917Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-04-18T22:14:37.192475Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-18T22:14:37.192585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-18T22:14:37.192596Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-04-18T22:14:37.192701Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:14:37.192742Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:14:37.426393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:14:37.426478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:14:37.42651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-04-18T22:14:37.426536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:14:37.426547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-18T22:14:37.426563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-04-18T22:14:37.426576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-04-18T22:14:37.433622Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:14:37.433777Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:14:37.433864Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:14:37.4338Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:37.435955Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:14:37.436112Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:14:37.436915Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:37.437095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:37.437175Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:14:37.437201Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:14:37.437402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-04-18T22:14:48.209872Z","caller":"traceutil/trace.go:171","msg":"trace[1259210277] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"116.405156ms","start":"2026-04-18T22:14:48.093427Z","end":"2026-04-18T22:14:48.209833Z","steps":["trace[1259210277] 'process raft request' (duration: 80.30979ms)","trace[1259210277] 'compare' (duration: 35.876692ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:48.209895Z","caller":"traceutil/trace.go:171","msg":"trace[527660730] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"115.946516ms","start":"2026-04-18T22:14:48.093923Z","end":"2026-04-18T22:14:48.20987Z","steps":["trace[527660730] 'process raft request' (duration: 115.829634ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:48.927673Z","caller":"traceutil/trace.go:171","msg":"trace[1257358398] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"194.379862ms","start":"2026-04-18T22:14:48.733269Z","end":"2026-04-18T22:14:48.927649Z","steps":["trace[1257358398] 'read index received' (duration: 188.865916ms)","trace[1257358398] 'applied index is now lower than readState.Index' (duration: 5.512946ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:48.927839Z","caller":"traceutil/trace.go:171","msg":"trace[435049422] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"217.141914ms","start":"2026-04-18T22:14:48.710656Z","end":"2026-04-18T22:14:48.927798Z","steps":["trace[435049422] 'process raft request' (duration: 211.594237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:14:48.927866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.574596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-18T22:14:48.927986Z","caller":"traceutil/trace.go:171","msg":"trace[1124864544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:327; }","duration":"194.782931ms","start":"2026-04-18T22:14:48.733191Z","end":"2026-04-18T22:14:48.927973Z","steps":["trace[1124864544] 'agreement among raft nodes before linearized reading' (duration: 194.547636ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:49.080875Z","caller":"traceutil/trace.go:171","msg":"trace[291430567] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"148.698446ms","start":"2026-04-18T22:14:48.932149Z","end":"2026-04-18T22:14:49.080848Z","steps":["trace[291430567] 'process raft request' (duration: 110.252976ms)","trace[291430567] 'compare' (duration: 38.342758ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:49.105915Z","caller":"traceutil/trace.go:171","msg":"trace[753218352] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"173.060005ms","start":"2026-04-18T22:14:48.932821Z","end":"2026-04-18T22:14:49.105881Z","steps":["trace[753218352] 'process raft request' (duration: 172.86364ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:49.106002Z","caller":"traceutil/trace.go:171","msg":"trace[1761240216] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"169.590705ms","start":"2026-04-18T22:14:48.93637Z","end":"2026-04-18T22:14:49.10596Z","steps":["trace[1761240216] 'process raft request' (duration: 169.477362ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:49.106028Z","caller":"traceutil/trace.go:171","msg":"trace[140574377] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"172.182285ms","start":"2026-04-18T22:14:48.933826Z","end":"2026-04-18T22:14:49.106009Z","steps":["trace[140574377] 'process raft request' (duration: 171.96912ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:49.617954Z","caller":"traceutil/trace.go:171","msg":"trace[1977114923] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"117.327097ms","start":"2026-04-18T22:14:49.500603Z","end":"2026-04-18T22:14:49.61793Z","steps":["trace[1977114923] 'process raft request' (duration: 70.136337ms)","trace[1977114923] 'compare' (duration: 47.094868ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:50.328774Z","caller":"traceutil/trace.go:171","msg":"trace[1676548611] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"134.634794ms","start":"2026-04-18T22:14:50.194104Z","end":"2026-04-18T22:14:50.328738Z","steps":["trace[1676548611] 'process raft request' (duration: 71.678652ms)","trace[1676548611] 'compare' (duration: 62.824549ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:14:51.579551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.847947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:14:51.579619Z","caller":"traceutil/trace.go:171","msg":"trace[895396121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:359; }","duration":"130.96503ms","start":"2026-04-18T22:14:51.44864Z","end":"2026-04-18T22:14:51.579605Z","steps":["trace[895396121] 'range keys from in-memory index tree' (duration: 130.722234ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:53.767752Z","caller":"traceutil/trace.go:171","msg":"trace[944950342] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"106.249313ms","start":"2026-04-18T22:14:53.661477Z","end":"2026-04-18T22:14:53.767726Z","steps":["trace[944950342] 'process raft request' (duration: 86.139443ms)","trace[944950342] 'compare' (duration: 20.007518ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:14:55.367764Z","caller":"traceutil/trace.go:171","msg":"trace[211259535] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"111.446213ms","start":"2026-04-18T22:14:55.256278Z","end":"2026-04-18T22:14:55.367724Z","steps":["trace[211259535] 'process raft request' (duration: 51.737025ms)","trace[211259535] 'compare' (duration: 59.550265ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:02.51661Z","caller":"traceutil/trace.go:171","msg":"trace[52593226] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"134.174723ms","start":"2026-04-18T22:15:02.382415Z","end":"2026-04-18T22:15:02.51659Z","steps":["trace[52593226] 'read index received' (duration: 134.03445ms)","trace[52593226] 'applied index is now lower than readState.Index' (duration: 139.373µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:02.516715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.290016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:02.516685Z","caller":"traceutil/trace.go:171","msg":"trace[236490436] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"704.667811ms","start":"2026-04-18T22:15:01.811978Z","end":"2026-04-18T22:15:02.516646Z","steps":["trace[236490436] 'process raft request' (duration: 704.494397ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:02.516739Z","caller":"traceutil/trace.go:171","msg":"trace[1824603578] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"134.368927ms","start":"2026-04-18T22:15:02.382362Z","end":"2026-04-18T22:15:02.516731Z","steps":["trace[1824603578] 'agreement among raft nodes before linearized reading' (duration: 134.311216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:02.517415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:01.811958Z","time spent":"704.822764ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:03.279221Z","caller":"traceutil/trace.go:171","msg":"trace[193672096] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"209.460038ms","start":"2026-04-18T22:15:03.069743Z","end":"2026-04-18T22:15:03.279203Z","steps":["trace[193672096] 'process raft request' (duration: 209.351535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:03.663995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.422309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:15:03.664108Z","caller":"traceutil/trace.go:171","msg":"trace[1474010405] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:421; }","duration":"196.580892ms","start":"2026-04-18T22:15:03.467506Z","end":"2026-04-18T22:15:03.664087Z","steps":["trace[1474010405] 'range keys from in-memory index tree' (duration: 196.318846ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:03.664321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.17878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:15:03.664394Z","caller":"traceutil/trace.go:171","msg":"trace[63274937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"257.294023ms","start":"2026-04-18T22:15:03.407081Z","end":"2026-04-18T22:15:03.664375Z","steps":["trace[63274937] 'range keys from in-memory index tree' (duration: 257.084437ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:12.217644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329245071,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T22:15:12.386425Z","caller":"traceutil/trace.go:171","msg":"trace[2011565980] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"669.550052ms","start":"2026-04-18T22:15:11.716853Z","end":"2026-04-18T22:15:12.386403Z","steps":["trace[2011565980] 'read index received' (duration: 669.396759ms)","trace[2011565980] 'applied index is now lower than readState.Index' (duration: 152.583µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:12.386665Z","caller":"traceutil/trace.go:171","msg":"trace[1137186823] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"670.940754ms","start":"2026-04-18T22:15:11.715712Z","end":"2026-04-18T22:15:12.386653Z","steps":["trace[1137186823] 'process raft request' (duration: 670.584866ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:12.386769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:11.715701Z","time spent":"670.996475ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:12.386981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.120065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:15:12.38702Z","caller":"traceutil/trace.go:171","msg":"trace[491560784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"670.169186ms","start":"2026-04-18T22:15:11.71684Z","end":"2026-04-18T22:15:12.387009Z","steps":["trace[491560784] 'agreement among raft nodes before linearized reading' (duration: 670.062914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:12.387047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:11.716834Z","time spent":"670.207027ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:12.387213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.501448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:15:12.387259Z","caller":"traceutil/trace.go:171","msg":"trace[2025182631] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"665.558349ms","start":"2026-04-18T22:15:11.721692Z","end":"2026-04-18T22:15:12.387251Z","steps":["trace[2025182631] 'agreement among raft nodes before linearized reading' (duration: 665.462167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:12.387287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:11.721658Z","time spent":"665.62363ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:15:13.355967Z","caller":"traceutil/trace.go:171","msg":"trace[1180892752] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"358.668915ms","start":"2026-04-18T22:15:12.997275Z","end":"2026-04-18T22:15:13.355944Z","steps":["trace[1180892752] 'process raft request' (duration: 358.364768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:13.356073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:12.997256Z","time spent":"358.773348ms","remote":"127.0.0.1:33824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:15:14.299619Z","caller":"traceutil/trace.go:171","msg":"trace[1311049740] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"493.29714ms","start":"2026-04-18T22:15:13.806304Z","end":"2026-04-18T22:15:14.299601Z","steps":["trace[1311049740] 'process raft request' (duration: 492.197575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:14.299727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:13.806275Z","time spent":"493.397233ms","remote":"127.0.0.1:33824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:20.307187Z","caller":"traceutil/trace.go:171","msg":"trace[809273688] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:615; }","duration":"333.650345ms","start":"2026-04-18T22:15:19.973514Z","end":"2026-04-18T22:15:20.307164Z","steps":["trace[809273688] 'read index received' (duration: 333.386019ms)","trace[809273688] 'applied index is now lower than readState.Index' (duration: 263.226µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:20.307381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.83639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rqqkq\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-18T22:15:20.307457Z","caller":"traceutil/trace.go:171","msg":"trace[1417977869] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rqqkq; range_end:; response_count:1; response_revision:593; }","duration":"333.939573ms","start":"2026-04-18T22:15:19.97349Z","end":"2026-04-18T22:15:20.30743Z","steps":["trace[1417977869] 'agreement among raft nodes before linearized reading' (duration: 333.792459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:20.30751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:19.973482Z","time spent":"334.016275ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-rqqkq\" "} {"level":"info","ts":"2026-04-18T22:15:20.307878Z","caller":"traceutil/trace.go:171","msg":"trace[1117794946] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"343.344931ms","start":"2026-04-18T22:15:19.964522Z","end":"2026-04-18T22:15:20.307866Z","steps":["trace[1117794946] 'process raft request' (duration: 342.506201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:20.308064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:19.964499Z","time spent":"343.419422ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:20.88023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329245228,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T22:15:21.08242Z","caller":"traceutil/trace.go:171","msg":"trace[276641274] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"702.476277ms","start":"2026-04-18T22:15:20.379919Z","end":"2026-04-18T22:15:21.082395Z","steps":["trace[276641274] 'read index received' (duration: 702.317473ms)","trace[276641274] 'applied index is now lower than readState.Index' (duration: 157.934µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:21.082565Z","caller":"traceutil/trace.go:171","msg":"trace[1268629453] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"759.650524ms","start":"2026-04-18T22:15:20.322901Z","end":"2026-04-18T22:15:21.082551Z","steps":["trace[1268629453] 'process raft request' (duration: 759.323816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.082686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.32287Z","time spent":"759.737816ms","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:21.082864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.477857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:15:21.082949Z","caller":"traceutil/trace.go:171","msg":"trace[250776595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"665.59974ms","start":"2026-04-18T22:15:20.41733Z","end":"2026-04-18T22:15:21.082929Z","steps":["trace[250776595] 'agreement among raft nodes before linearized reading' (duration: 665.423026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.082992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.41731Z","time spent":"665.671942ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:21.083011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.15528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:15:21.083123Z","caller":"traceutil/trace.go:171","msg":"trace[508705991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"469.313144ms","start":"2026-04-18T22:15:20.613778Z","end":"2026-04-18T22:15:21.083091Z","steps":["trace[508705991] 'agreement among raft nodes before linearized reading' (duration: 469.031167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.083178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.613759Z","time spent":"469.407866ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:15:21.083245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.553093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:15:21.083285Z","caller":"traceutil/trace.go:171","msg":"trace[1797976878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:594; }","duration":"573.629475ms","start":"2026-04-18T22:15:20.509646Z","end":"2026-04-18T22:15:21.083276Z","steps":["trace[1797976878] 'agreement among raft nodes before linearized reading' (duration: 573.536553ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.083312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.509627Z","time spent":"573.679176ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:21.08288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.965559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:21.083417Z","caller":"traceutil/trace.go:171","msg":"trace[1058989593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"703.511312ms","start":"2026-04-18T22:15:20.379881Z","end":"2026-04-18T22:15:21.083392Z","steps":["trace[1058989593] 'agreement among raft nodes before linearized reading' (duration: 702.967388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.08344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.475856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:15:21.083465Z","caller":"traceutil/trace.go:171","msg":"trace[1317842724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:594; }","duration":"655.524967ms","start":"2026-04-18T22:15:20.427932Z","end":"2026-04-18T22:15:21.083457Z","steps":["trace[1317842724] 'agreement among raft nodes before linearized reading' (duration: 655.456326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.083466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.379862Z","time spent":"703.592144ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:15:21.083509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:20.427915Z","time spent":"655.586468ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:15:21.610599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:21.089161Z","time spent":"521.430443ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-18T22:15:21.652432Z","caller":"traceutil/trace.go:171","msg":"trace[1019172804] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"562.682851ms","start":"2026-04-18T22:15:21.089731Z","end":"2026-04-18T22:15:21.652414Z","steps":["trace[1019172804] 'process raft request' (duration: 562.61777ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.652582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:21.089708Z","time spent":"562.778533ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:21.65284Z","caller":"traceutil/trace.go:171","msg":"trace[903367721] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"563.117181ms","start":"2026-04-18T22:15:21.08971Z","end":"2026-04-18T22:15:21.652827Z","steps":["trace[903367721] 'process raft request' (duration: 562.473026ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:21.652837Z","caller":"traceutil/trace.go:171","msg":"trace[1363791567] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"562.973397ms","start":"2026-04-18T22:15:21.089834Z","end":"2026-04-18T22:15:21.652808Z","steps":["trace[1363791567] 'process raft request' (duration: 562.550178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.652922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:21.089685Z","time spent":"563.184213ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:21.652992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:21.089828Z","time spent":"563.073249ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:21.653321Z","caller":"traceutil/trace.go:171","msg":"trace[2115431364] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:617; }","duration":"270.939859ms","start":"2026-04-18T22:15:21.382369Z","end":"2026-04-18T22:15:21.653309Z","steps":["trace[2115431364] 'read index received' (duration: 228.132385ms)","trace[2115431364] 'applied index is now lower than readState.Index' (duration: 42.806444ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:21.653441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.064622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:21.653479Z","caller":"traceutil/trace.go:171","msg":"trace[824495611] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"271.132693ms","start":"2026-04-18T22:15:21.382337Z","end":"2026-04-18T22:15:21.65347Z","steps":["trace[824495611] 'agreement among raft nodes before linearized reading' (duration: 271.026441ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:21.653608Z","caller":"traceutil/trace.go:171","msg":"trace[754660289] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"563.761785ms","start":"2026-04-18T22:15:21.089833Z","end":"2026-04-18T22:15:21.653595Z","steps":["trace[754660289] 'process raft request' (duration: 562.879625ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:21.653694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:21.089822Z","time spent":"563.831007ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:24.177712Z","caller":"traceutil/trace.go:171","msg":"trace[215975464] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"515.23483ms","start":"2026-04-18T22:15:23.662428Z","end":"2026-04-18T22:15:24.177663Z","steps":["trace[215975464] 'process raft request' (duration: 515.019225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:24.177986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:23.662402Z","time spent":"515.482785ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:24.596818Z","caller":"traceutil/trace.go:171","msg":"trace[737455389] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"933.780395ms","start":"2026-04-18T22:15:23.663011Z","end":"2026-04-18T22:15:24.596791Z","steps":["trace[737455389] 'process raft request' (duration: 933.674093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:24.597091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:23.663002Z","time spent":"934.003001ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:24.597142Z","caller":"traceutil/trace.go:171","msg":"trace[21160464] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"933.912749ms","start":"2026-04-18T22:15:23.663202Z","end":"2026-04-18T22:15:24.597115Z","steps":["trace[21160464] 'process raft request' (duration: 933.54359ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:24.597309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:23.66316Z","time spent":"934.051712ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:24.596842Z","caller":"traceutil/trace.go:171","msg":"trace[1438236784] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"933.98965ms","start":"2026-04-18T22:15:23.662805Z","end":"2026-04-18T22:15:24.596795Z","steps":["trace[1438236784] 'process raft request' (duration: 933.505519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:24.597553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:23.662781Z","time spent":"934.716867ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:24.597597Z","caller":"traceutil/trace.go:171","msg":"trace[210460155] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:624; }","duration":"215.479082ms","start":"2026-04-18T22:15:24.382066Z","end":"2026-04-18T22:15:24.597545Z","steps":["trace[210460155] 'read index received' (duration: 214.166401ms)","trace[210460155] 'applied index is now lower than readState.Index' (duration: 1.310361ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:24.597806Z","caller":"traceutil/trace.go:171","msg":"trace[563727214] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"884.01998ms","start":"2026-04-18T22:15:23.713776Z","end":"2026-04-18T22:15:24.597796Z","steps":["trace[563727214] 'process raft request' (duration: 883.233372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:24.597859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:23.713749Z","time spent":"884.088342ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:24.597913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.784809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:24.597987Z","caller":"traceutil/trace.go:171","msg":"trace[366709466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"215.990684ms","start":"2026-04-18T22:15:24.381983Z","end":"2026-04-18T22:15:24.597974Z","steps":["trace[366709466] 'agreement among raft nodes before linearized reading' (duration: 215.728518ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:25.28863Z","caller":"traceutil/trace.go:171","msg":"trace[1550789477] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"279.042096ms","start":"2026-04-18T22:15:25.009567Z","end":"2026-04-18T22:15:25.288609Z","steps":["trace[1550789477] 'process raft request' (duration: 278.890173ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:26.684717Z","caller":"traceutil/trace.go:171","msg":"trace[2097900290] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"498.623345ms","start":"2026-04-18T22:15:26.18607Z","end":"2026-04-18T22:15:26.684694Z","steps":["trace[2097900290] 'process raft request' (duration: 488.281534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:26.685168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:26.18605Z","time spent":"499.038034ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:26.702672Z","caller":"traceutil/trace.go:171","msg":"trace[589715197] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"321.198745ms","start":"2026-04-18T22:15:26.381444Z","end":"2026-04-18T22:15:26.702643Z","steps":["trace[589715197] 'read index received' (duration: 292.96658ms)","trace[589715197] 'applied index is now lower than readState.Index' (duration: 28.230665ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:15:26.702873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.40856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:26.70294Z","caller":"traceutil/trace.go:171","msg":"trace[1109415076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"321.541684ms","start":"2026-04-18T22:15:26.381382Z","end":"2026-04-18T22:15:26.702924Z","steps":["trace[1109415076] 'agreement among raft nodes before linearized reading' (duration: 321.40172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:26.702993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:26.381363Z","time spent":"321.617056ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:15:26.703155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.634535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:15:26.703201Z","caller":"traceutil/trace.go:171","msg":"trace[1688826889] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:613; }","duration":"100.713308ms","start":"2026-04-18T22:15:26.602476Z","end":"2026-04-18T22:15:26.70319Z","steps":["trace[1688826889] 'agreement among raft nodes before linearized reading' (duration: 100.495043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:26.703313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.563084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:15:26.703435Z","caller":"traceutil/trace.go:171","msg":"trace[358853458] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"100.771999ms","start":"2026-04-18T22:15:26.602629Z","end":"2026-04-18T22:15:26.703401Z","steps":["trace[358853458] 'agreement among raft nodes before linearized reading' (duration: 100.403251ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:26.703482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.80733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:15:26.703557Z","caller":"traceutil/trace.go:171","msg":"trace[710814974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"100.903782ms","start":"2026-04-18T22:15:26.602636Z","end":"2026-04-18T22:15:26.70354Z","steps":["trace[710814974] 'agreement among raft nodes before linearized reading' (duration: 100.737328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:29.546961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.925993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:15:29.547017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.247972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-18T22:15:29.547102Z","caller":"traceutil/trace.go:171","msg":"trace[538546204] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"154.370444ms","start":"2026-04-18T22:15:29.392709Z","end":"2026-04-18T22:15:29.547079Z","steps":["trace[538546204] 'range keys from in-memory index tree' (duration: 154.084138ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:29.547045Z","caller":"traceutil/trace.go:171","msg":"trace[1677275577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"166.060197ms","start":"2026-04-18T22:15:29.380964Z","end":"2026-04-18T22:15:29.547024Z","steps":["trace[1677275577] 'range keys from in-memory index tree' (duration: 165.82557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:40.88196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329245551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:15:41.383174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329245551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:15:41.874138Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.781774558s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:15:41.874673Z","caller":"traceutil/trace.go:171","msg":"trace[1456167167] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.782365512s","start":"2026-04-18T22:15:40.092282Z","end":"2026-04-18T22:15:41.874647Z","steps":["trace[1456167167] 'process raft request' (duration: 1.782192108s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.874902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.09227Z","time spent":"1.782532375s","remote":"127.0.0.1:33946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:15:41.957602Z","caller":"traceutil/trace.go:171","msg":"trace[715065735] linearizableReadLoop","detail":"{readStateIndex:785; appliedIndex:783; }","duration":"1.57639617s","start":"2026-04-18T22:15:40.381165Z","end":"2026-04-18T22:15:41.957562Z","steps":["trace[715065735] 'read index received' (duration: 1.49323046s)","trace[715065735] 'applied index is now lower than readState.Index' (duration: 83.16451ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:15:41.957732Z","caller":"traceutil/trace.go:171","msg":"trace[218670160] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"1.352496414s","start":"2026-04-18T22:15:40.605141Z","end":"2026-04-18T22:15:41.957638Z","steps":["trace[218670160] 'process raft request' (duration: 1.352221738s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.957798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.576608916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:15:41.957903Z","caller":"traceutil/trace.go:171","msg":"trace[206253420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:757; }","duration":"1.57679795s","start":"2026-04-18T22:15:40.381088Z","end":"2026-04-18T22:15:41.957886Z","steps":["trace[206253420] 'agreement among raft nodes before linearized reading' (duration: 1.576617186s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.95794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.381058Z","time spent":"1.576869572s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:15:41.957932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.605106Z","time spent":"1.352725769s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:15:41.958002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19945277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:15:41.957998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.553714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-04-18T22:15:41.958014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185690571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:15:41.958084Z","caller":"traceutil/trace.go:171","msg":"trace[1885663120] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:757; }","duration":"1.199610794s","start":"2026-04-18T22:15:40.758457Z","end":"2026-04-18T22:15:41.958067Z","steps":["trace[1885663120] 'agreement among raft nodes before linearized reading' (duration: 1.199345098s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:41.958102Z","caller":"traceutil/trace.go:171","msg":"trace[935292610] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:757; }","duration":"922.706277ms","start":"2026-04-18T22:15:41.035373Z","end":"2026-04-18T22:15:41.958079Z","steps":["trace[935292610] 'agreement among raft nodes before linearized reading' (duration: 922.450181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.958134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.758435Z","time spent":"1.199688036s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:15:41.958169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:41.035348Z","time spent":"922.80268ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-04-18T22:15:41.958199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.315905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:15:41.958203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.309171ms","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-04-18T22:15:41.958182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170921929s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:15:41.958239Z","caller":"traceutil/trace.go:171","msg":"trace[1513375905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"767.400223ms","start":"2026-04-18T22:15:41.190828Z","end":"2026-04-18T22:15:41.958229Z","steps":["trace[1513375905] 'agreement among raft nodes before linearized reading' (duration: 767.305871ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:41.958107Z","caller":"traceutil/trace.go:171","msg":"trace[724321572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:757; }","duration":"1.185831635s","start":"2026-04-18T22:15:40.772259Z","end":"2026-04-18T22:15:41.95809Z","steps":["trace[724321572] 'agreement among raft nodes before linearized reading' (duration: 1.18562181s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:41.95826Z","caller":"traceutil/trace.go:171","msg":"trace[739040806] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:757; }","duration":"220.393457ms","start":"2026-04-18T22:15:41.737848Z","end":"2026-04-18T22:15:41.958242Z","steps":["trace[739040806] 'agreement among raft nodes before linearized reading' (duration: 220.289475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.958274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:41.190794Z","time spent":"767.473055ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:41.957999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.178636708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:15:41.958313Z","caller":"traceutil/trace.go:171","msg":"trace[1324874293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:757; }","duration":"1.179685801s","start":"2026-04-18T22:15:40.778617Z","end":"2026-04-18T22:15:41.958303Z","steps":["trace[1324874293] 'agreement among raft nodes before linearized reading' (duration: 1.179221211s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:15:41.958297Z","caller":"traceutil/trace.go:171","msg":"trace[390654139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:757; }","duration":"1.171055912s","start":"2026-04-18T22:15:40.78721Z","end":"2026-04-18T22:15:41.958266Z","steps":["trace[390654139] 'agreement among raft nodes before linearized reading' (duration: 1.170649772s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:15:41.95828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.772233Z","time spent":"1.186039489s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:15:41.958364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.787157Z","time spent":"1.171191804s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:41.958341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:15:40.778572Z","time spent":"1.179763303s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:15:41.958116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.655902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:15:41.958471Z","caller":"traceutil/trace.go:171","msg":"trace[2018597694] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:757; }","duration":"299.053813ms","start":"2026-04-18T22:15:41.659402Z","end":"2026-04-18T22:15:41.958456Z","steps":["trace[2018597694] 'agreement among raft nodes before linearized reading' (duration: 298.681934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:17.849554Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.445008852s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:16:17.84979Z","caller":"traceutil/trace.go:171","msg":"trace[1224617734] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1012; }","duration":"468.764894ms","start":"2026-04-18T22:16:17.38101Z","end":"2026-04-18T22:16:17.849775Z","steps":["trace[1224617734] 'read index received' (duration: 468.629241ms)","trace[1224617734] 'applied index is now lower than readState.Index' (duration: 135.003µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:17.849843Z","caller":"traceutil/trace.go:171","msg":"trace[1173736901] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.445405151s","start":"2026-04-18T22:16:16.404432Z","end":"2026-04-18T22:16:17.849837Z","steps":["trace[1173736901] 'process raft request' (duration: 1.445243207s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:17.849914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:16.404407Z","time spent":"1.445451942s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:17.850169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.167494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:17.850192Z","caller":"traceutil/trace.go:171","msg":"trace[407570814] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"469.227515ms","start":"2026-04-18T22:16:17.380958Z","end":"2026-04-18T22:16:17.850185Z","steps":["trace[407570814] 'agreement among raft nodes before linearized reading' (duration: 469.190554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:17.850209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:17.380937Z","time spent":"469.268506ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:16:19.891978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619360345s","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-04-18T22:16:19.892047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619514078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:16:19.892086Z","caller":"traceutil/trace.go:171","msg":"trace[985265008] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"1.619512358s","start":"2026-04-18T22:16:18.272552Z","end":"2026-04-18T22:16:19.892064Z","steps":["trace[985265008] 'range keys from in-memory index tree' (duration: 1.619175771s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:19.892126Z","caller":"traceutil/trace.go:171","msg":"trace[1484379163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:974; }","duration":"1.619620421s","start":"2026-04-18T22:16:18.272484Z","end":"2026-04-18T22:16:19.892105Z","steps":["trace[1484379163] 'range keys from in-memory index tree' (duration: 1.619259913s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.892141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.272545Z","time spent":"1.61958642s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:19.892162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.272473Z","time spent":"1.619682472s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:19.892356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409616782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:19.892378Z","caller":"traceutil/trace.go:171","msg":"trace[201659574] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:974; }","duration":"1.409664673s","start":"2026-04-18T22:16:18.482705Z","end":"2026-04-18T22:16:19.892369Z","steps":["trace[201659574] 'count revisions from in-memory index tree' (duration: 1.409562371s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.892375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619735264s","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-04-18T22:16:19.892402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.48269Z","time spent":"1.409706614s","remote":"127.0.0.1:55134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:16:19.892408Z","caller":"traceutil/trace.go:171","msg":"trace[781541787] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:974; }","duration":"1.619784875s","start":"2026-04-18T22:16:18.272613Z","end":"2026-04-18T22:16:19.892398Z","steps":["trace[781541787] 'range keys from in-memory index tree' (duration: 1.619652742s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.892429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.272609Z","time spent":"1.619815735s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:16:19.892574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.511324141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:19.892593Z","caller":"traceutil/trace.go:171","msg":"trace[2117460806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"1.511341541s","start":"2026-04-18T22:16:18.381245Z","end":"2026-04-18T22:16:19.892586Z","steps":["trace[2117460806] 'range keys from in-memory index tree' (duration: 1.5112636s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.89261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.38119Z","time spent":"1.511416344s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:16:19.892611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758313607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:16:19.892636Z","caller":"traceutil/trace.go:171","msg":"trace[860186692] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:974; }","duration":"1.758369529s","start":"2026-04-18T22:16:18.134259Z","end":"2026-04-18T22:16:19.892629Z","steps":["trace[860186692] 'count revisions from in-memory index tree' (duration: 1.758256255s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.892656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.134244Z","time spent":"1.758405109s","remote":"127.0.0.1:34240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:16:19.892728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.387639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:16:19.892779Z","caller":"traceutil/trace.go:171","msg":"trace[1523957038] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:974; }","duration":"465.506161ms","start":"2026-04-18T22:16:19.427259Z","end":"2026-04-18T22:16:19.892765Z","steps":["trace[1523957038] 'count revisions from in-memory index tree' (duration: 465.323508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.892817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:19.427238Z","time spent":"465.567683ms","remote":"127.0.0.1:34136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:16:19.893005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.643841526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T22:16:19.893031Z","caller":"traceutil/trace.go:171","msg":"trace[1063657758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"1.643896548s","start":"2026-04-18T22:16:18.249127Z","end":"2026-04-18T22:16:19.893024Z","steps":["trace[1063657758] 'range keys from in-memory index tree' (duration: 1.643756435s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.89305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.249112Z","time spent":"1.643932748s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:16:19.893051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.791514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:19.893093Z","caller":"traceutil/trace.go:171","msg":"trace[1122669821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:974; }","duration":"350.858796ms","start":"2026-04-18T22:16:19.54222Z","end":"2026-04-18T22:16:19.893078Z","steps":["trace[1122669821] 'count revisions from in-memory index tree' (duration: 350.751274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.893126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:19.542207Z","time spent":"350.910507ms","remote":"127.0.0.1:55268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:16:19.893184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621497164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:16:19.893209Z","caller":"traceutil/trace.go:171","msg":"trace[999562422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"1.621540045s","start":"2026-04-18T22:16:18.271661Z","end":"2026-04-18T22:16:19.893201Z","steps":["trace[999562422] 'range keys from in-memory index tree' (duration: 1.621415012s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.893233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:18.27165Z","time spent":"1.621573956s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:19.893368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.695046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:19.893396Z","caller":"traceutil/trace.go:171","msg":"trace[1561388012] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:974; }","duration":"124.759678ms","start":"2026-04-18T22:16:19.768628Z","end":"2026-04-18T22:16:19.893388Z","steps":["trace[1561388012] 'count revisions from in-memory index tree' (duration: 124.630635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:19.8935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.865968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:16:19.893519Z","caller":"traceutil/trace.go:171","msg":"trace[2058179001] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:974; }","duration":"106.911868ms","start":"2026-04-18T22:16:19.786601Z","end":"2026-04-18T22:16:19.893512Z","steps":["trace[2058179001] 'count revisions from in-memory index tree' (duration: 106.820726ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:20.011546Z","caller":"traceutil/trace.go:171","msg":"trace[1818401327] linearizableReadLoop","detail":"{readStateIndex:1019; appliedIndex:1015; }","duration":"105.23163ms","start":"2026-04-18T22:16:19.906292Z","end":"2026-04-18T22:16:20.011524Z","steps":["trace[1818401327] 'read index received' (duration: 24.502941ms)","trace[1818401327] 'applied index is now lower than readState.Index' (duration: 80.727699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:20.011686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.373273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:20.011728Z","caller":"traceutil/trace.go:171","msg":"trace[1737045349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"105.461505ms","start":"2026-04-18T22:16:19.906255Z","end":"2026-04-18T22:16:20.011716Z","steps":["trace[1737045349] 'agreement among raft nodes before linearized reading' (duration: 105.365973ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:20.01199Z","caller":"traceutil/trace.go:171","msg":"trace[900511990] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"114.547593ms","start":"2026-04-18T22:16:19.897337Z","end":"2026-04-18T22:16:20.011885Z","steps":["trace[900511990] 'process raft request' (duration: 114.070433ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:20.012115Z","caller":"traceutil/trace.go:171","msg":"trace[1643264519] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"114.722917ms","start":"2026-04-18T22:16:19.89738Z","end":"2026-04-18T22:16:20.012103Z","steps":["trace[1643264519] 'process raft request' (duration: 114.072123ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:20.012166Z","caller":"traceutil/trace.go:171","msg":"trace[1005371071] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"115.026564ms","start":"2026-04-18T22:16:19.897131Z","end":"2026-04-18T22:16:20.012158Z","steps":["trace[1005371071] 'process raft request' (duration: 107.319038ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:20.012238Z","caller":"traceutil/trace.go:171","msg":"trace[1474995106] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"113.515609ms","start":"2026-04-18T22:16:19.898713Z","end":"2026-04-18T22:16:20.012229Z","steps":["trace[1474995106] 'process raft request' (duration: 112.773902ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:40.122259Z","caller":"traceutil/trace.go:171","msg":"trace[1568993113] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"137.242323ms","start":"2026-04-18T22:16:39.984995Z","end":"2026-04-18T22:16:40.122237Z","steps":["trace[1568993113] 'process raft request' (duration: 136.916506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.555651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.664967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:40.556123Z","caller":"traceutil/trace.go:171","msg":"trace[1129201048] linearizableReadLoop","detail":"{readStateIndex:1086; appliedIndex:1084; }","duration":"417.093532ms","start":"2026-04-18T22:16:40.139005Z","end":"2026-04-18T22:16:40.556099Z","steps":["trace[1129201048] 'read index received' (duration: 142.801431ms)","trace[1129201048] 'applied index is now lower than readState.Index' (duration: 274.290761ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:40.556239Z","caller":"traceutil/trace.go:171","msg":"trace[2049917593] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"430.322406ms","start":"2026-04-18T22:16:40.125876Z","end":"2026-04-18T22:16:40.556199Z","steps":["trace[2049917593] 'process raft request' (duration: 155.821859ms)","trace[2049917593] 'compare' (duration: 273.472312ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:40.556217Z","caller":"traceutil/trace.go:171","msg":"trace[466543398] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"430.173862ms","start":"2026-04-18T22:16:40.125983Z","end":"2026-04-18T22:16:40.556157Z","steps":["trace[466543398] 'process raft request' (duration: 429.883225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.896336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:40.55642Z","caller":"traceutil/trace.go:171","msg":"trace[1604057674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"175.006918ms","start":"2026-04-18T22:16:40.381394Z","end":"2026-04-18T22:16:40.556401Z","steps":["trace[1604057674] 'agreement among raft nodes before linearized reading' (duration: 174.904996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:40.125862Z","time spent":"430.458618ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:40.556431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.403529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-18T22:16:40.556447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:40.125963Z","time spent":"430.391347ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:40.556513Z","caller":"traceutil/trace.go:171","msg":"trace[1063842815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"417.511991ms","start":"2026-04-18T22:16:40.13898Z","end":"2026-04-18T22:16:40.556492Z","steps":["trace[1063842815] 'agreement among raft nodes before linearized reading' (duration: 417.290756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:40.13897Z","time spent":"417.582183ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:40.55654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.021998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:16:40.556643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.968929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:16:40.55672Z","caller":"traceutil/trace.go:171","msg":"trace[1448918920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"417.129993ms","start":"2026-04-18T22:16:40.139573Z","end":"2026-04-18T22:16:40.556703Z","steps":["trace[1448918920] 'agreement among raft nodes before linearized reading' (duration: 416.704863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.969472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:16:40.55668Z","caller":"traceutil/trace.go:171","msg":"trace[1931687894] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1043; }","duration":"244.320415ms","start":"2026-04-18T22:16:40.312345Z","end":"2026-04-18T22:16:40.556666Z","steps":["trace[1931687894] 'agreement among raft nodes before linearized reading' (duration: 243.975657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:40.139559Z","time spent":"417.225045ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-18T22:16:40.556835Z","caller":"traceutil/trace.go:171","msg":"trace[612545120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"411.072625ms","start":"2026-04-18T22:16:40.14575Z","end":"2026-04-18T22:16:40.556822Z","steps":["trace[612545120] 'agreement among raft nodes before linearized reading' (duration: 410.88404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.556928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:40.145737Z","time spent":"411.177007ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T22:16:40.740102Z","caller":"traceutil/trace.go:171","msg":"trace[1813553002] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"178.102078ms","start":"2026-04-18T22:16:40.561974Z","end":"2026-04-18T22:16:40.740076Z","steps":["trace[1813553002] 'process raft request' (duration: 177.938294ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:40.75039Z","caller":"traceutil/trace.go:171","msg":"trace[1220361722] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"187.792541ms","start":"2026-04-18T22:16:40.562574Z","end":"2026-04-18T22:16:40.750367Z","steps":["trace[1220361722] 'process raft request' (duration: 187.581666ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:40.750919Z","caller":"traceutil/trace.go:171","msg":"trace[508657560] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"185.876827ms","start":"2026-04-18T22:16:40.565017Z","end":"2026-04-18T22:16:40.750894Z","steps":["trace[508657560] 'process raft request' (duration: 185.235643ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:40.751186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.029067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:40.751269Z","caller":"traceutil/trace.go:171","msg":"trace[575018940] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1046; }","duration":"140.17921ms","start":"2026-04-18T22:16:40.611062Z","end":"2026-04-18T22:16:40.751241Z","steps":["trace[575018940] 'agreement among raft nodes before linearized reading' (duration: 139.930534ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:40.750956Z","caller":"traceutil/trace.go:171","msg":"trace[1419022160] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1086; }","duration":"139.825682ms","start":"2026-04-18T22:16:40.611105Z","end":"2026-04-18T22:16:40.750931Z","steps":["trace[1419022160] 'read index received' (duration: 128.738558ms)","trace[1419022160] 'applied index is now lower than readState.Index' (duration: 11.085234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:42.296833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258165513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-18T22:16:42.296906Z","caller":"traceutil/trace.go:171","msg":"trace[874400371] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1046; }","duration":"1.258293476s","start":"2026-04-18T22:16:41.038595Z","end":"2026-04-18T22:16:42.296888Z","steps":["trace[874400371] 'range keys from in-memory index tree' (duration: 1.257954608s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:42.296943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:41.038574Z","time spent":"1.258360678s","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"info","ts":"2026-04-18T22:16:42.339458Z","caller":"traceutil/trace.go:171","msg":"trace[830557084] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"211.953783ms","start":"2026-04-18T22:16:42.12748Z","end":"2026-04-18T22:16:42.339434Z","steps":["trace[830557084] 'process raft request' (duration: 211.80621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:42.666971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.297824Z","time spent":"369.140384ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-18T22:16:42.666986Z","caller":"traceutil/trace.go:171","msg":"trace[166844816] linearizableReadLoop","detail":"{readStateIndex:1091; appliedIndex:1090; }","duration":"284.883094ms","start":"2026-04-18T22:16:42.382074Z","end":"2026-04-18T22:16:42.666957Z","steps":["trace[166844816] 'read index received' (duration: 204.806961ms)","trace[166844816] 'applied index is now lower than readState.Index' (duration: 80.073973ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:42.667087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.044698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:42.66714Z","caller":"traceutil/trace.go:171","msg":"trace[701278332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"285.15762ms","start":"2026-04-18T22:16:42.381969Z","end":"2026-04-18T22:16:42.667127Z","steps":["trace[701278332] 'agreement among raft nodes before linearized reading' (duration: 285.058708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:42.667349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.320395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T22:16:42.667437Z","caller":"traceutil/trace.go:171","msg":"trace[2259846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"106.471608ms","start":"2026-04-18T22:16:42.560948Z","end":"2026-04-18T22:16:42.66742Z","steps":["trace[2259846] 'agreement among raft nodes before linearized reading' (duration: 106.232813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:42.66747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.062736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-18T22:16:42.66752Z","caller":"traceutil/trace.go:171","msg":"trace[987817739] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"105.137837ms","start":"2026-04-18T22:16:42.562372Z","end":"2026-04-18T22:16:42.66751Z","steps":["trace[987817739] 'agreement among raft nodes before linearized reading' (duration: 104.976833ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.460596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.526535ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:43.460714Z","caller":"traceutil/trace.go:171","msg":"trace[449707316] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"791.334413ms","start":"2026-04-18T22:16:42.669359Z","end":"2026-04-18T22:16:43.460693Z","steps":["trace[449707316] 'process raft request' (duration: 196.612762ms)","trace[449707316] 'compare' (duration: 594.407423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:43.460763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.669335Z","time spent":"791.407285ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:43.482948Z","caller":"traceutil/trace.go:171","msg":"trace[1067620076] linearizableReadLoop","detail":"{readStateIndex:1094; appliedIndex:1091; }","duration":"739.516055ms","start":"2026-04-18T22:16:42.74341Z","end":"2026-04-18T22:16:43.482926Z","steps":["trace[1067620076] 'read index received' (duration: 122.583547ms)","trace[1067620076] 'applied index is now lower than readState.Index' (duration: 616.931368ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:43.483224Z","caller":"traceutil/trace.go:171","msg":"trace[1941684721] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"811.004023ms","start":"2026-04-18T22:16:42.672208Z","end":"2026-04-18T22:16:43.483212Z","steps":["trace[1941684721] 'process raft request' (duration: 810.547552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.48337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.672185Z","time spent":"811.068575ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:43.483546Z","caller":"traceutil/trace.go:171","msg":"trace[2136242039] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"811.141986ms","start":"2026-04-18T22:16:42.672371Z","end":"2026-04-18T22:16:43.483512Z","steps":["trace[2136242039] 'process raft request' (duration: 810.512941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.483766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.67235Z","time spent":"811.27267ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:43.483862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.455258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:16:43.483911Z","caller":"traceutil/trace.go:171","msg":"trace[52070282] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1050; }","duration":"740.54343ms","start":"2026-04-18T22:16:42.743355Z","end":"2026-04-18T22:16:43.483899Z","steps":["trace[52070282] 'agreement among raft nodes before linearized reading' (duration: 740.439887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.483942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.743337Z","time spent":"740.597971ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:43.484019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.676101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:16:43.484254Z","caller":"traceutil/trace.go:171","msg":"trace[183486141] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1050; }","duration":"730.022179ms","start":"2026-04-18T22:16:42.754176Z","end":"2026-04-18T22:16:43.484198Z","steps":["trace[183486141] 'agreement among raft nodes before linearized reading' (duration: 729.313213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.4851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.754153Z","time spent":"730.870367ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:43.484557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.389963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:16:43.485511Z","caller":"traceutil/trace.go:171","msg":"trace[1692295033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1050; }","duration":"730.356935ms","start":"2026-04-18T22:16:42.75511Z","end":"2026-04-18T22:16:43.485466Z","steps":["trace[1692295033] 'agreement among raft nodes before linearized reading' (duration: 729.161188ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:43.48556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:42.75509Z","time spent":"730.457237ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:43.750525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.381416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:43.750732Z","caller":"traceutil/trace.go:171","msg":"trace[1380236828] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"140.444816ms","start":"2026-04-18T22:16:43.610271Z","end":"2026-04-18T22:16:43.750716Z","steps":["trace[1380236828] 'process raft request' (duration: 140.402465ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:43.750779Z","caller":"traceutil/trace.go:171","msg":"trace[1282553074] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"259.668336ms","start":"2026-04-18T22:16:43.491065Z","end":"2026-04-18T22:16:43.750734Z","steps":["trace[1282553074] 'process raft request' (duration: 145.995184ms)","trace[1282553074] 'compare' (duration: 113.250533ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:43.750814Z","caller":"traceutil/trace.go:171","msg":"trace[864321195] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"259.345128ms","start":"2026-04-18T22:16:43.491429Z","end":"2026-04-18T22:16:43.750774Z","steps":["trace[864321195] 'process raft request' (duration: 259.192095ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:44.482326Z","caller":"traceutil/trace.go:171","msg":"trace[1127162972] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1098; }","duration":"100.03544ms","start":"2026-04-18T22:16:44.382259Z","end":"2026-04-18T22:16:44.482295Z","steps":["trace[1127162972] 'read index received' (duration: 99.739943ms)","trace[1127162972] 'applied index is now lower than readState.Index' (duration: 293.877µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:44.482438Z","caller":"traceutil/trace.go:171","msg":"trace[198676286] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"137.058318ms","start":"2026-04-18T22:16:44.345342Z","end":"2026-04-18T22:16:44.482401Z","steps":["trace[198676286] 'process raft request' (duration: 136.766052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:44.482538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.255485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:44.482601Z","caller":"traceutil/trace.go:171","msg":"trace[2056916460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1055; }","duration":"100.386248ms","start":"2026-04-18T22:16:44.382203Z","end":"2026-04-18T22:16:44.482589Z","steps":["trace[2056916460] 'agreement among raft nodes before linearized reading' (duration: 100.266045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:46.02381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:16:46.524781Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:16:46.560397Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068318125s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:16:46.560848Z","caller":"traceutil/trace.go:171","msg":"trace[1081363028] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.068898018s","start":"2026-04-18T22:16:45.491923Z","end":"2026-04-18T22:16:46.560821Z","steps":["trace[1081363028] 'process raft request' (duration: 1.068721654s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:46.560979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:45.491906Z","time spent":"1.069000241s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:46.843491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.892205ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:46.843877Z","caller":"traceutil/trace.go:171","msg":"trace[2069345308] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.351208553s","start":"2026-04-18T22:16:45.492632Z","end":"2026-04-18T22:16:46.843841Z","steps":["trace[2069345308] 'process raft request' (duration: 1.213871048s)","trace[2069345308] 'compare' (duration: 136.796283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:46.844055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:45.492624Z","time spent":"1.351351677s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:47.192394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.786228ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-18T22:16:47.192972Z","caller":"traceutil/trace.go:171","msg":"trace[1225511994] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"744.92397ms","start":"2026-04-18T22:16:46.448026Z","end":"2026-04-18T22:16:47.19295Z","steps":["trace[1225511994] 'process raft request' (duration: 744.465999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:47.193105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:46.447994Z","time spent":"745.027942ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:47.424615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.516492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:47.424802Z","caller":"traceutil/trace.go:171","msg":"trace[358519408] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1099; }","duration":"1.901775843s","start":"2026-04-18T22:16:45.522999Z","end":"2026-04-18T22:16:47.424775Z","steps":["trace[358519408] 'read index received' (duration: 1.037716434s)","trace[358519408] 'applied index is now lower than readState.Index' (duration: 864.056969ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:47.424904Z","caller":"traceutil/trace.go:171","msg":"trace[1718562025] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"933.13377ms","start":"2026-04-18T22:16:46.491718Z","end":"2026-04-18T22:16:47.424852Z","steps":["trace[1718562025] 'process raft request' (duration: 701.222378ms)","trace[1718562025] 'compare' (duration: 231.193924ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:47.425021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.043733243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:47.425062Z","caller":"traceutil/trace.go:171","msg":"trace[1560614456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"1.043817425s","start":"2026-04-18T22:16:46.38123Z","end":"2026-04-18T22:16:47.425047Z","steps":["trace[1560614456] 'agreement among raft nodes before linearized reading' (duration: 1.043697952s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:47.425097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669307579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-18T22:16:47.425147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:46.381203Z","time spent":"1.043929338s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:16:47.42518Z","caller":"traceutil/trace.go:171","msg":"trace[771828951] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"1.669442923s","start":"2026-04-18T22:16:45.755718Z","end":"2026-04-18T22:16:47.425161Z","steps":["trace[771828951] 'agreement among raft nodes before linearized reading' (duration: 1.669200527s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:47.425158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669305379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T22:16:47.425221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:45.755693Z","time spent":"1.669518784s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T22:16:47.425257Z","caller":"traceutil/trace.go:171","msg":"trace[1477983395] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"1.669441212s","start":"2026-04-18T22:16:45.755792Z","end":"2026-04-18T22:16:47.425234Z","steps":["trace[1477983395] 'agreement among raft nodes before linearized reading' (duration: 1.669142426s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:47.425284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:46.491692Z","time spent":"933.309184ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:47.425322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:45.755774Z","time spent":"1.669533294s","remote":"127.0.0.1:34028","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-04-18T22:16:47.425852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.902841867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:16:47.42591Z","caller":"traceutil/trace.go:171","msg":"trace[992234028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"1.902908118s","start":"2026-04-18T22:16:45.522986Z","end":"2026-04-18T22:16:47.425894Z","steps":["trace[992234028] 'agreement among raft nodes before linearized reading' (duration: 1.902774345s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:47.425951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:45.52298Z","time spent":"1.90296002s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:16:47.939921Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246262,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:16:48.165466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.008662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:48.165773Z","caller":"traceutil/trace.go:171","msg":"trace[2094957966] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"734.619173ms","start":"2026-04-18T22:16:47.431132Z","end":"2026-04-18T22:16:48.165751Z","steps":["trace[2094957966] 'process raft request' (duration: 585.247493ms)","trace[2094957966] 'compare' (duration: 148.824048ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:48.16595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:47.431108Z","time spent":"734.758196ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:16:49.240153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.357333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:16:49.240365Z","caller":"traceutil/trace.go:171","msg":"trace[2036685375] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"1.809206613s","start":"2026-04-18T22:16:47.431135Z","end":"2026-04-18T22:16:49.240341Z","steps":["trace[2036685375] 'process raft request' (duration: 1.055538423s)","trace[2036685375] 'compare' (duration: 753.100946ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:49.240436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:47.431111Z","time spent":"1.809295235s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:49.240629Z","caller":"traceutil/trace.go:171","msg":"trace[1881987316] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1104; }","duration":"1.801355783s","start":"2026-04-18T22:16:47.43923Z","end":"2026-04-18T22:16:49.240586Z","steps":["trace[1881987316] 'read index received' (duration: 577.191528ms)","trace[1881987316] 'applied index is now lower than readState.Index' (duration: 1.224162715s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:49.240951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801689501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:16:49.241162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.115151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:16:49.241227Z","caller":"traceutil/trace.go:171","msg":"trace[1948069446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"675.265405ms","start":"2026-04-18T22:16:48.565945Z","end":"2026-04-18T22:16:49.24121Z","steps":["trace[1948069446] 'agreement among raft nodes before linearized reading' (duration: 674.970978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.241186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.000327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:49.240947Z","caller":"traceutil/trace.go:171","msg":"trace[86298894] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"1.809656573s","start":"2026-04-18T22:16:47.431258Z","end":"2026-04-18T22:16:49.240915Z","steps":["trace[86298894] 'process raft request' (duration: 1.808995748s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.241295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:48.565915Z","time spent":"675.369587ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T22:16:49.241178Z","caller":"traceutil/trace.go:171","msg":"trace[427857596] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"1.801962926s","start":"2026-04-18T22:16:47.439191Z","end":"2026-04-18T22:16:49.241154Z","steps":["trace[427857596] 'agreement among raft nodes before linearized reading' (duration: 1.801651929s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.241512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:47.439171Z","time spent":"1.802322135s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:16:49.24133Z","caller":"traceutil/trace.go:171","msg":"trace[336366055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1062; }","duration":"623.175141ms","start":"2026-04-18T22:16:48.618111Z","end":"2026-04-18T22:16:49.241286Z","steps":["trace[336366055] 'agreement among raft nodes before linearized reading' (duration: 622.976007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.242598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:48.618089Z","time spent":"624.49004ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:16:49.241113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.878114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:16:49.242861Z","caller":"traceutil/trace.go:171","msg":"trace[1475936543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1062; }","duration":"424.649625ms","start":"2026-04-18T22:16:48.818186Z","end":"2026-04-18T22:16:49.242836Z","steps":["trace[1475936543] 'agreement among raft nodes before linearized reading' (duration: 422.896605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.242935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:48.818164Z","time spent":"424.734568ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:16:49.241042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.412667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:16:49.243165Z","caller":"traceutil/trace.go:171","msg":"trace[995454803] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"394.586146ms","start":"2026-04-18T22:16:48.848566Z","end":"2026-04-18T22:16:49.243152Z","steps":["trace[995454803] 'agreement among raft nodes before linearized reading' (duration: 392.361575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:49.243204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:48.848545Z","time spent":"394.648188ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:16:49.241536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:47.431249Z","time spent":"1.810086572s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:49.556441Z","caller":"traceutil/trace.go:171","msg":"trace[2095116773] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"125.104375ms","start":"2026-04-18T22:16:49.431303Z","end":"2026-04-18T22:16:49.556407Z","steps":["trace[2095116773] 'process raft request' (duration: 124.90956ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:16:49.556319Z","caller":"traceutil/trace.go:171","msg":"trace[1839974713] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"308.071254ms","start":"2026-04-18T22:16:49.248207Z","end":"2026-04-18T22:16:49.556278Z","steps":["trace[1839974713] 'process raft request' (duration: 264.921357ms)","trace[1839974713] 'compare' (duration: 42.712248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:16:49.558164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:16:49.248196Z","time spent":"309.747993ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:16:59.889904Z","caller":"traceutil/trace.go:171","msg":"trace[1102670133] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"261.485048ms","start":"2026-04-18T22:16:59.628389Z","end":"2026-04-18T22:16:59.889874Z","steps":["trace[1102670133] 'read index received' (duration: 261.308444ms)","trace[1102670133] 'applied index is now lower than readState.Index' (duration: 175.584µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:16:59.889931Z","caller":"traceutil/trace.go:171","msg":"trace[1063037526] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"266.413431ms","start":"2026-04-18T22:16:59.623482Z","end":"2026-04-18T22:16:59.889895Z","steps":["trace[1063037526] 'process raft request' (duration: 266.234637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:16:59.890215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.805695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:16:59.890259Z","caller":"traceutil/trace.go:171","msg":"trace[1986380894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1148; }","duration":"261.901408ms","start":"2026-04-18T22:16:59.628347Z","end":"2026-04-18T22:16:59.890248Z","steps":["trace[1986380894] 'agreement among raft nodes before linearized reading' (duration: 261.710113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:00.587126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.12411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:00.587285Z","caller":"traceutil/trace.go:171","msg":"trace[774639427] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1149; }","duration":"206.359495ms","start":"2026-04-18T22:17:00.380891Z","end":"2026-04-18T22:17:00.587251Z","steps":["trace[774639427] 'range keys from in-memory index tree' (duration: 205.965516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:00.587256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.385819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:17:00.587393Z","caller":"traceutil/trace.go:171","msg":"trace[601810893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1149; }","duration":"362.593754ms","start":"2026-04-18T22:17:00.224757Z","end":"2026-04-18T22:17:00.58735Z","steps":["trace[601810893] 'range keys from in-memory index tree' (duration: 362.060952ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:00.587455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:00.224729Z","time spent":"362.713367ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:00.587481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.914859ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:00.58765Z","caller":"traceutil/trace.go:171","msg":"trace[1772068923] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1149; }","duration":"376.094303ms","start":"2026-04-18T22:17:00.211527Z","end":"2026-04-18T22:17:00.587622Z","steps":["trace[1772068923] 'range keys from in-memory index tree' (duration: 375.896298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.077496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.523183503s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:03.077638Z","caller":"traceutil/trace.go:171","msg":"trace[1791321803] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"1.63970348s","start":"2026-04-18T22:17:01.43791Z","end":"2026-04-18T22:17:03.077613Z","steps":["trace[1791321803] 'read index received' (duration: 116.19821ms)","trace[1791321803] 'applied index is now lower than readState.Index' (duration: 1.52350304s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:03.077657Z","caller":"traceutil/trace.go:171","msg":"trace[1511143948] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"1.689737416s","start":"2026-04-18T22:17:01.387881Z","end":"2026-04-18T22:17:03.077619Z","steps":["trace[1511143948] 'process raft request' (duration: 166.310908ms)","trace[1511143948] 'compare' (duration: 1.522727402s)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:03.077842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:01.387873Z","time spent":"1.68986135s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:03.077892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.639980846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:17:03.077929Z","caller":"traceutil/trace.go:171","msg":"trace[232435422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1153; }","duration":"1.640039348s","start":"2026-04-18T22:17:01.437878Z","end":"2026-04-18T22:17:03.077917Z","steps":["trace[232435422] 'agreement among raft nodes before linearized reading' (duration: 1.639826113s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.077967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:01.437865Z","time spent":"1.640094039s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:17:03.182844Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.286873141s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-18T22:17:03.971946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.781123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:03.972139Z","caller":"traceutil/trace.go:171","msg":"trace[1713341994] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"889.06171ms","start":"2026-04-18T22:17:03.083055Z","end":"2026-04-18T22:17:03.972117Z","steps":["trace[1713341994] 'process raft request' (duration: 888.974488ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:03.97214Z","caller":"traceutil/trace.go:171","msg":"trace[687179101] linearizableReadLoop","detail":"{readStateIndex:1204; appliedIndex:1203; }","duration":"894.445624ms","start":"2026-04-18T22:17:03.077667Z","end":"2026-04-18T22:17:03.972113Z","steps":["trace[687179101] 'read index received' (duration: 105.445675ms)","trace[687179101] 'applied index is now lower than readState.Index' (duration: 788.998009ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:03.972227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:03.083035Z","time spent":"889.158363ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:03.97226Z","caller":"traceutil/trace.go:171","msg":"trace[388772510] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"2.076376722s","start":"2026-04-18T22:17:01.895869Z","end":"2026-04-18T22:17:03.972246Z","steps":["trace[388772510] 'process raft request' (duration: 1.287227729s)","trace[388772510] 'compare' (duration: 788.64376ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:03.972359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:01.895851Z","time spent":"2.076438374s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:03.972564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.066622518s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:17:03.972583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.572968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:17:03.972616Z","caller":"traceutil/trace.go:171","msg":"trace[2081990235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1155; }","duration":"622.64494ms","start":"2026-04-18T22:17:03.349964Z","end":"2026-04-18T22:17:03.972609Z","steps":["trace[2081990235] 'agreement among raft nodes before linearized reading' (duration: 622.543738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.972637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35754688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:17:03.972592Z","caller":"traceutil/trace.go:171","msg":"trace[801687982] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1155; }","duration":"2.06667736s","start":"2026-04-18T22:17:01.905907Z","end":"2026-04-18T22:17:03.972584Z","steps":["trace[801687982] 'agreement among raft nodes before linearized reading' (duration: 2.066562007s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:03.972658Z","caller":"traceutil/trace.go:171","msg":"trace[1544239907] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1155; }","duration":"1.358331217s","start":"2026-04-18T22:17:02.614323Z","end":"2026-04-18T22:17:03.972654Z","steps":["trace[1544239907] 'agreement among raft nodes before linearized reading' (duration: 1.358280596s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.972682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:02.614302Z","time spent":"1.358376299s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:03.972797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.583131495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:03.972829Z","caller":"traceutil/trace.go:171","msg":"trace[980514027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1155; }","duration":"1.583198097s","start":"2026-04-18T22:17:02.389623Z","end":"2026-04-18T22:17:03.972821Z","steps":["trace[980514027] 'agreement among raft nodes before linearized reading' (duration: 1.583151476s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.972836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.882752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:17:03.972859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:02.389604Z","time spent":"1.583249908s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:17:03.972899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.523633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:17:03.972923Z","caller":"traceutil/trace.go:171","msg":"trace[821515488] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1155; }","duration":"300.575394ms","start":"2026-04-18T22:17:03.672342Z","end":"2026-04-18T22:17:03.972917Z","steps":["trace[821515488] 'agreement among raft nodes before linearized reading' (duration: 300.536363ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.972946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:03.672301Z","time spent":"300.640216ms","remote":"127.0.0.1:49988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:17:03.97264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:03.349905Z","time spent":"622.730252ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:03.972662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:01.905888Z","time spent":"2.066769881s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:17:03.973065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.756067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:17:03.972864Z","caller":"traceutil/trace.go:171","msg":"trace[820506448] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1155; }","duration":"235.908253ms","start":"2026-04-18T22:17:03.736946Z","end":"2026-04-18T22:17:03.972854Z","steps":["trace[820506448] 'agreement among raft nodes before linearized reading' (duration: 235.870862ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:03.973088Z","caller":"traceutil/trace.go:171","msg":"trace[1007659061] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1155; }","duration":"369.817709ms","start":"2026-04-18T22:17:03.603264Z","end":"2026-04-18T22:17:03.973082Z","steps":["trace[1007659061] 'agreement among raft nodes before linearized reading' (duration: 369.760088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:03.973109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:03.60321Z","time spent":"369.893911ms","remote":"127.0.0.1:34150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-04-18T22:17:16.208138Z","caller":"traceutil/trace.go:171","msg":"trace[1195709912] linearizableReadLoop","detail":"{readStateIndex:1246; appliedIndex:1245; }","duration":"185.554359ms","start":"2026-04-18T22:17:16.022563Z","end":"2026-04-18T22:17:16.208117Z","steps":["trace[1195709912] 'read index received' (duration: 185.407845ms)","trace[1195709912] 'applied index is now lower than readState.Index' (duration: 145.564µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:16.208209Z","caller":"traceutil/trace.go:171","msg":"trace[1021966969] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"201.821662ms","start":"2026-04-18T22:17:16.006344Z","end":"2026-04-18T22:17:16.208166Z","steps":["trace[1021966969] 'process raft request' (duration: 201.624467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:16.2085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.885697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-18T22:17:16.208619Z","caller":"traceutil/trace.go:171","msg":"trace[404234908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1194; }","duration":"186.0753ms","start":"2026-04-18T22:17:16.022519Z","end":"2026-04-18T22:17:16.208594Z","steps":["trace[404234908] 'agreement among raft nodes before linearized reading' (duration: 185.724892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:16.208365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.708399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-18T22:17:16.20871Z","caller":"traceutil/trace.go:171","msg":"trace[171011022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1194; }","duration":"154.082588ms","start":"2026-04-18T22:17:16.054604Z","end":"2026-04-18T22:17:16.208686Z","steps":["trace[171011022] 'agreement among raft nodes before linearized reading' (duration: 153.640407ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:16.208495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.574276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:17:16.208781Z","caller":"traceutil/trace.go:171","msg":"trace[283102392] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1194; }","duration":"146.921315ms","start":"2026-04-18T22:17:16.061847Z","end":"2026-04-18T22:17:16.208768Z","steps":["trace[283102392] 'agreement among raft nodes before linearized reading' (duration: 146.446214ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:16.208493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.501371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:17:16.208863Z","caller":"traceutil/trace.go:171","msg":"trace[2098563423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1194; }","duration":"145.911301ms","start":"2026-04-18T22:17:16.062937Z","end":"2026-04-18T22:17:16.208848Z","steps":["trace[2098563423] 'agreement among raft nodes before linearized reading' (duration: 145.347887ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:18.399162Z","caller":"traceutil/trace.go:171","msg":"trace[2125429115] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"164.508117ms","start":"2026-04-18T22:17:18.234625Z","end":"2026-04-18T22:17:18.399133Z","steps":["trace[2125429115] 'process raft request' (duration: 88.433235ms)","trace[2125429115] 'compare' (duration: 75.858797ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:18.39992Z","caller":"traceutil/trace.go:171","msg":"trace[458358326] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"164.879776ms","start":"2026-04-18T22:17:18.235017Z","end":"2026-04-18T22:17:18.399897Z","steps":["trace[458358326] 'process raft request' (duration: 164.045117ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:18.400272Z","caller":"traceutil/trace.go:171","msg":"trace[1605032944] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"163.783421ms","start":"2026-04-18T22:17:18.236464Z","end":"2026-04-18T22:17:18.400247Z","steps":["trace[1605032944] 'process raft request' (duration: 163.380302ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:19.940907Z","caller":"traceutil/trace.go:171","msg":"trace[1206003372] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"371.558169ms","start":"2026-04-18T22:17:19.569317Z","end":"2026-04-18T22:17:19.940875Z","steps":["trace[1206003372] 'read index received' (duration: 371.373035ms)","trace[1206003372] 'applied index is now lower than readState.Index' (duration: 183.554µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:19.941019Z","caller":"traceutil/trace.go:171","msg":"trace[901470705] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"776.203605ms","start":"2026-04-18T22:17:19.164765Z","end":"2026-04-18T22:17:19.940969Z","steps":["trace[901470705] 'process raft request' (duration: 775.909549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:19.941175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.605993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:17:19.941208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:19.164748Z","time spent":"776.36387ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:19.941176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.817736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:19.94131Z","caller":"traceutil/trace.go:171","msg":"trace[2123072693] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1215; }","duration":"372.0221ms","start":"2026-04-18T22:17:19.569258Z","end":"2026-04-18T22:17:19.941281Z","steps":["trace[2123072693] 'agreement among raft nodes before linearized reading' (duration: 371.729844ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:19.941236Z","caller":"traceutil/trace.go:171","msg":"trace[698141795] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1215; }","duration":"127.748576ms","start":"2026-04-18T22:17:19.813471Z","end":"2026-04-18T22:17:19.941219Z","steps":["trace[698141795] 'agreement among raft nodes before linearized reading' (duration: 127.618303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:19.941388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:19.569238Z","time spent":"372.130482ms","remote":"127.0.0.1:55202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:17:20.749274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246602,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:17:21.711975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.500577305s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:21.712077Z","caller":"traceutil/trace.go:171","msg":"trace[753649723] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1215; }","duration":"1.500708328s","start":"2026-04-18T22:17:20.211356Z","end":"2026-04-18T22:17:21.712064Z","steps":["trace[753649723] 'range keys from in-memory index tree' (duration: 1.500562895s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.712779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.831448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:21.712823Z","caller":"traceutil/trace.go:171","msg":"trace[578755410] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1268; }","duration":"1.464491177s","start":"2026-04-18T22:17:20.248325Z","end":"2026-04-18T22:17:21.712817Z","steps":["trace[578755410] 'read index received' (duration: 529.531756ms)","trace[578755410] 'applied index is now lower than readState.Index' (duration: 934.958741ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:21.712981Z","caller":"traceutil/trace.go:171","msg":"trace[2070341918] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"1.477706871s","start":"2026-04-18T22:17:20.235265Z","end":"2026-04-18T22:17:21.712972Z","steps":["trace[2070341918] 'process raft request' (duration: 542.625267ms)","trace[2070341918] 'compare' (duration: 934.583603ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:21.713027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.235239Z","time spent":"1.477767122s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:21.713194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464880827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:17:21.713221Z","caller":"traceutil/trace.go:171","msg":"trace[1049319641] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1216; }","duration":"1.464935787s","start":"2026-04-18T22:17:20.24828Z","end":"2026-04-18T22:17:21.713215Z","steps":["trace[1049319641] 'agreement among raft nodes before linearized reading' (duration: 1.464853645s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.71324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.248267Z","time spent":"1.464968899s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:21.713275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.331264697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:21.713324Z","caller":"traceutil/trace.go:171","msg":"trace[788428444] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"1.331362461s","start":"2026-04-18T22:17:20.381955Z","end":"2026-04-18T22:17:21.713318Z","steps":["trace[788428444] 'agreement among raft nodes before linearized reading' (duration: 1.331290738s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.713346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.381929Z","time spent":"1.331412532s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:17:21.713489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.032714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-18T22:17:21.71351Z","caller":"traceutil/trace.go:171","msg":"trace[544926985] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1216; }","duration":"673.105636ms","start":"2026-04-18T22:17:21.0404Z","end":"2026-04-18T22:17:21.713505Z","steps":["trace[544926985] 'agreement among raft nodes before linearized reading' (duration: 673.054745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.71351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310214786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-18T22:17:21.713516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310325148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-18T22:17:21.71353Z","caller":"traceutil/trace.go:171","msg":"trace[1622947949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1216; }","duration":"1.310236066s","start":"2026-04-18T22:17:20.403288Z","end":"2026-04-18T22:17:21.713524Z","steps":["trace[1622947949] 'agreement among raft nodes before linearized reading' (duration: 1.310181785s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.713539Z","caller":"traceutil/trace.go:171","msg":"trace[62563028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1216; }","duration":"1.310366179s","start":"2026-04-18T22:17:20.403167Z","end":"2026-04-18T22:17:21.713533Z","steps":["trace[62563028] 'agreement among raft nodes before linearized reading' (duration: 1.310305298s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.713548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.403252Z","time spent":"1.310292678s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:17:21.713554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.403158Z","time spent":"1.31039186s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:21.713523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:21.04037Z","time spent":"673.149727ms","remote":"127.0.0.1:33768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-04-18T22:17:21.71362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310129024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-18T22:17:21.713633Z","caller":"traceutil/trace.go:171","msg":"trace[1182080160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"1.310163625s","start":"2026-04-18T22:17:20.403466Z","end":"2026-04-18T22:17:21.71363Z","steps":["trace[1182080160] 'agreement among raft nodes before linearized reading' (duration: 1.310126374s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:21.713647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:20.403455Z","time spent":"1.310190295s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T22:17:21.949354Z","caller":"traceutil/trace.go:171","msg":"trace[1041841855] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"232.017154ms","start":"2026-04-18T22:17:21.717294Z","end":"2026-04-18T22:17:21.949311Z","steps":["trace[1041841855] 'process raft request' (duration: 231.676836ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.949618Z","caller":"traceutil/trace.go:171","msg":"trace[748238294] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"232.210598ms","start":"2026-04-18T22:17:21.717378Z","end":"2026-04-18T22:17:21.949589Z","steps":["trace[748238294] 'process raft request' (duration: 232.159247ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.949673Z","caller":"traceutil/trace.go:171","msg":"trace[1927805716] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"232.208848ms","start":"2026-04-18T22:17:21.717445Z","end":"2026-04-18T22:17:21.949654Z","steps":["trace[1927805716] 'process raft request' (duration: 232.003033ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.949708Z","caller":"traceutil/trace.go:171","msg":"trace[1674990303] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"232.26507ms","start":"2026-04-18T22:17:21.717396Z","end":"2026-04-18T22:17:21.949661Z","steps":["trace[1674990303] 'process raft request' (duration: 231.84611ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.984231Z","caller":"traceutil/trace.go:171","msg":"trace[1061721261] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"215.995447ms","start":"2026-04-18T22:17:21.768197Z","end":"2026-04-18T22:17:21.984192Z","steps":["trace[1061721261] 'process raft request' (duration: 215.884575ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:21.984194Z","caller":"traceutil/trace.go:171","msg":"trace[1335899596] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1270; }","duration":"208.640878ms","start":"2026-04-18T22:17:21.775536Z","end":"2026-04-18T22:17:21.984177Z","steps":["trace[1335899596] 'read index received' (duration: 173.32667ms)","trace[1335899596] 'applied index is now lower than readState.Index' (duration: 35.312848ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:21.984409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.861693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:21.984444Z","caller":"traceutil/trace.go:171","msg":"trace[148682508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1221; }","duration":"208.947765ms","start":"2026-04-18T22:17:21.775485Z","end":"2026-04-18T22:17:21.984433Z","steps":["trace[148682508] 'agreement among raft nodes before linearized reading' (duration: 208.842082ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:22.977285Z","caller":"traceutil/trace.go:171","msg":"trace[1558734725] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"173.236768ms","start":"2026-04-18T22:17:22.804022Z","end":"2026-04-18T22:17:22.977259Z","steps":["trace[1558734725] 'process raft request' (duration: 103.808718ms)","trace[1558734725] 'compare' (duration: 69.296337ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:23.88195Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246623,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:17:24.268748Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229929116s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:17:24.269144Z","caller":"traceutil/trace.go:171","msg":"trace[2066995281] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"1.230370877s","start":"2026-04-18T22:17:23.038745Z","end":"2026-04-18T22:17:24.269116Z","steps":["trace[2066995281] 'process raft request' (duration: 1.230184802s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:24.269296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.038734Z","time spent":"1.230474329s","remote":"127.0.0.1:33932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:25.329406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.214114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:17:25.3295Z","caller":"traceutil/trace.go:171","msg":"trace[1172055493] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1279; }","duration":"1.948129383s","start":"2026-04-18T22:17:23.381354Z","end":"2026-04-18T22:17:25.329484Z","steps":["trace[1172055493] 'read index received' (duration: 887.557226ms)","trace[1172055493] 'applied index is now lower than readState.Index' (duration: 1.060570827s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:25.329757Z","caller":"traceutil/trace.go:171","msg":"trace[44525758] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"1.611401682s","start":"2026-04-18T22:17:23.718342Z","end":"2026-04-18T22:17:25.329743Z","steps":["trace[44525758] 'process raft request' (duration: 1.324774639s)","trace[44525758] 'compare' (duration: 286.05998ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:25.329823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.718315Z","time spent":"1.611479024s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:25.330381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.949016035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:25.330458Z","caller":"traceutil/trace.go:171","msg":"trace[1979173504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"1.949139987s","start":"2026-04-18T22:17:23.381302Z","end":"2026-04-18T22:17:25.330442Z","steps":["trace[1979173504] 'agreement among raft nodes before linearized reading' (duration: 1.948544282s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.330499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.381283Z","time spent":"1.949207829s","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:17:25.830231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415872164329246627,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T22:17:25.952327Z","caller":"traceutil/trace.go:171","msg":"trace[635941603] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1281; }","duration":"622.781954ms","start":"2026-04-18T22:17:25.329522Z","end":"2026-04-18T22:17:25.952303Z","steps":["trace[635941603] 'read index received' (duration: 622.557228ms)","trace[635941603] 'applied index is now lower than readState.Index' (duration: 223.566µs)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:25.952475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.916305736s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-18T22:17:25.95253Z","caller":"traceutil/trace.go:171","msg":"trace[529595878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.916390747s","start":"2026-04-18T22:17:24.036127Z","end":"2026-04-18T22:17:25.952517Z","steps":["trace[529595878] 'agreement among raft nodes before linearized reading' (duration: 1.916217173s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:24.036113Z","time spent":"1.916437888s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:25.952636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998050077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:17:25.952694Z","caller":"traceutil/trace.go:171","msg":"trace[1080677402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.998134679s","start":"2026-04-18T22:17:23.954549Z","end":"2026-04-18T22:17:25.952684Z","steps":["trace[1080677402] 'agreement among raft nodes before linearized reading' (duration: 1.997995576s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.954534Z","time spent":"1.99818983s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:25.952856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998622261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:17:25.95282Z","caller":"traceutil/trace.go:171","msg":"trace[1134071342] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"1.230966941s","start":"2026-04-18T22:17:24.721816Z","end":"2026-04-18T22:17:25.952783Z","steps":["trace[1134071342] 'process raft request' (duration: 1.230326167s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678796426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-18T22:17:25.952875Z","caller":"traceutil/trace.go:171","msg":"trace[604637080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1228; }","duration":"1.998662702s","start":"2026-04-18T22:17:23.954207Z","end":"2026-04-18T22:17:25.95287Z","steps":["trace[604637080] 'agreement among raft nodes before linearized reading' (duration: 1.998613391s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.954195Z","time spent":"1.998700593s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-18T22:17:25.952911Z","caller":"traceutil/trace.go:171","msg":"trace[1958744616] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:1228; }","duration":"1.678912379s","start":"2026-04-18T22:17:24.273981Z","end":"2026-04-18T22:17:25.952893Z","steps":["trace[1958744616] 'agreement among raft nodes before linearized reading' (duration: 1.678739685s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998969959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-18T22:17:25.95295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:24.273963Z","time spent":"1.67898011s","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":687,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-18T22:17:25.952521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.961868759s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:17:25.952989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:24.721764Z","time spent":"1.231133335s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:25.952999Z","caller":"traceutil/trace.go:171","msg":"trace[773008482] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1228; }","duration":"1.962412602s","start":"2026-04-18T22:17:23.990576Z","end":"2026-04-18T22:17:25.952989Z","steps":["trace[773008482] 'agreement among raft nodes before linearized reading' (duration: 1.9618623s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:25.953002Z","caller":"traceutil/trace.go:171","msg":"trace[1183647584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.999108032s","start":"2026-04-18T22:17:23.953872Z","end":"2026-04-18T22:17:25.95298Z","steps":["trace[1183647584] 'agreement among raft nodes before linearized reading' (duration: 1.998835936s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.998616121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-18T22:17:25.953038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.99056Z","time spent":"1.962468893s","remote":"127.0.0.1:55274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:17:25.953051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.953853Z","time spent":"1.999189544s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T22:17:25.953073Z","caller":"traceutil/trace.go:171","msg":"trace[58932587] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"1.998748923s","start":"2026-04-18T22:17:23.954306Z","end":"2026-04-18T22:17:25.953055Z","steps":["trace[58932587] 'agreement among raft nodes before linearized reading' (duration: 1.998488738s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.952955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.592446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:17:25.953127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:23.954285Z","time spent":"1.998824706s","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-18T22:17:25.953134Z","caller":"traceutil/trace.go:171","msg":"trace[1608527940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"621.80968ms","start":"2026-04-18T22:17:25.331316Z","end":"2026-04-18T22:17:25.953126Z","steps":["trace[1608527940] 'agreement among raft nodes before linearized reading' (duration: 621.614366ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:25.953158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:25.331296Z","time spent":"621.856412ms","remote":"127.0.0.1:33760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:17:26.092637Z","caller":"traceutil/trace.go:171","msg":"trace[768753269] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"135.523605ms","start":"2026-04-18T22:17:25.957086Z","end":"2026-04-18T22:17:26.092609Z","steps":["trace[768753269] 'process raft request' (duration: 135.33746ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:26.0929Z","caller":"traceutil/trace.go:171","msg":"trace[1354597516] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"135.40066ms","start":"2026-04-18T22:17:25.95747Z","end":"2026-04-18T22:17:26.092871Z","steps":["trace[1354597516] 'process raft request' (duration: 135.102904ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:26.093009Z","caller":"traceutil/trace.go:171","msg":"trace[346733614] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"132.797402ms","start":"2026-04-18T22:17:25.960175Z","end":"2026-04-18T22:17:26.092972Z","steps":["trace[346733614] 'process raft request' (duration: 132.561306ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:26.093016Z","caller":"traceutil/trace.go:171","msg":"trace[1011822378] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1282; }","duration":"128.412781ms","start":"2026-04-18T22:17:25.964585Z","end":"2026-04-18T22:17:26.092998Z","steps":["trace[1011822378] 'read index received' (duration: 19.705782ms)","trace[1011822378] 'applied index is now lower than readState.Index' (duration: 108.706139ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:26.09314Z","caller":"traceutil/trace.go:171","msg":"trace[451828058] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"131.649435ms","start":"2026-04-18T22:17:25.961473Z","end":"2026-04-18T22:17:26.093122Z","steps":["trace[451828058] 'process raft request' (duration: 131.44202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:26.093232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.628906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:26.093152Z","caller":"traceutil/trace.go:171","msg":"trace[128600495] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"130.861118ms","start":"2026-04-18T22:17:25.962279Z","end":"2026-04-18T22:17:26.09314Z","steps":["trace[128600495] 'process raft request' (duration: 130.675003ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:26.093264Z","caller":"traceutil/trace.go:171","msg":"trace[1547741550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"128.691907ms","start":"2026-04-18T22:17:25.964563Z","end":"2026-04-18T22:17:26.093255Z","steps":["trace[1547741550] 'agreement among raft nodes before linearized reading' (duration: 128.612805ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:27.613498Z","caller":"traceutil/trace.go:171","msg":"trace[135743668] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"111.729249ms","start":"2026-04-18T22:17:27.501744Z","end":"2026-04-18T22:17:27.613473Z","steps":["trace[135743668] 'process raft request' (duration: 111.581896ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:28.327163Z","caller":"traceutil/trace.go:171","msg":"trace[2109821856] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"521.739828ms","start":"2026-04-18T22:17:27.805396Z","end":"2026-04-18T22:17:28.327136Z","steps":["trace[2109821856] 'read index received' (duration: 441.559312ms)","trace[2109821856] 'applied index is now lower than readState.Index' (duration: 80.179306ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:28.327222Z","caller":"traceutil/trace.go:171","msg":"trace[1444502217] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"563.213518ms","start":"2026-04-18T22:17:27.763976Z","end":"2026-04-18T22:17:28.32719Z","steps":["trace[1444502217] 'process raft request' (duration: 482.94055ms)","trace[1444502217] 'compare' (duration: 80.099664ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:28.327323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.914603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:28.327364Z","caller":"traceutil/trace.go:171","msg":"trace[612416980] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1237; }","duration":"521.972755ms","start":"2026-04-18T22:17:27.80538Z","end":"2026-04-18T22:17:28.327353Z","steps":["trace[612416980] 'agreement among raft nodes before linearized reading' (duration: 521.891112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.327367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:27.76395Z","time spent":"563.345182ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:28.3274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:27.805326Z","time spent":"522.063447ms","remote":"127.0.0.1:34082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:17:28.327431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.904555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-18T22:17:28.3275Z","caller":"traceutil/trace.go:171","msg":"trace[618198358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1237; }","duration":"340.034668ms","start":"2026-04-18T22:17:27.987448Z","end":"2026-04-18T22:17:28.327482Z","steps":["trace[618198358] 'agreement among raft nodes before linearized reading' (duration: 339.855243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.327569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:27.987426Z","time spent":"340.13469ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-18T22:17:28.327803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.78784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:17:28.32784Z","caller":"traceutil/trace.go:171","msg":"trace[1793340971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1237; }","duration":"228.897463ms","start":"2026-04-18T22:17:28.098932Z","end":"2026-04-18T22:17:28.32783Z","steps":["trace[1793340971] 'agreement among raft nodes before linearized reading' (duration: 228.758339ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.328095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.76219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-18T22:17:28.328134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.051806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:17:28.328168Z","caller":"traceutil/trace.go:171","msg":"trace[698999549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1237; }","duration":"229.128748ms","start":"2026-04-18T22:17:28.09903Z","end":"2026-04-18T22:17:28.328158Z","steps":["trace[698999549] 'agreement among raft nodes before linearized reading' (duration: 229.045916ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:28.32828Z","caller":"traceutil/trace.go:171","msg":"trace[545095482] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1237; }","duration":"263.857523ms","start":"2026-04-18T22:17:28.064282Z","end":"2026-04-18T22:17:28.328139Z","steps":["trace[545095482] 'agreement among raft nodes before linearized reading' (duration: 263.768131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.328371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.55504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:17:28.32843Z","caller":"traceutil/trace.go:171","msg":"trace[297472109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1237; }","duration":"230.656312ms","start":"2026-04-18T22:17:28.097762Z","end":"2026-04-18T22:17:28.328418Z","steps":["trace[297472109] 'agreement among raft nodes before linearized reading' (duration: 230.517379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.328892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.039838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-18T22:17:28.328974Z","caller":"traceutil/trace.go:171","msg":"trace[247897971] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1237; }","duration":"230.168971ms","start":"2026-04-18T22:17:28.098785Z","end":"2026-04-18T22:17:28.328954Z","steps":["trace[247897971] 'agreement among raft nodes before linearized reading' (duration: 229.026595ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:28.834263Z","caller":"traceutil/trace.go:171","msg":"trace[1158206281] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"499.036568ms","start":"2026-04-18T22:17:28.335205Z","end":"2026-04-18T22:17:28.834241Z","steps":["trace[1158206281] 'process raft request' (duration: 491.561848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.834354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.335171Z","time spent":"499.135581ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:28.83448Z","caller":"traceutil/trace.go:171","msg":"trace[1315915014] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"498.514197ms","start":"2026-04-18T22:17:28.33596Z","end":"2026-04-18T22:17:28.834474Z","steps":["trace[1315915014] 'process raft request' (duration: 498.493506ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.834515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.335948Z","time spent":"498.552208ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:28.834559Z","caller":"traceutil/trace.go:171","msg":"trace[1872046910] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"499.179812ms","start":"2026-04-18T22:17:28.335374Z","end":"2026-04-18T22:17:28.834553Z","steps":["trace[1872046910] 'process raft request' (duration: 498.777452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.834591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.335363Z","time spent":"499.211494ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:28.834655Z","caller":"traceutil/trace.go:171","msg":"trace[121904162] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"499.032659ms","start":"2026-04-18T22:17:28.335615Z","end":"2026-04-18T22:17:28.834648Z","steps":["trace[121904162] 'process raft request' (duration: 498.814445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.834686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.335601Z","time spent":"499.06444ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:28.845429Z","caller":"traceutil/trace.go:171","msg":"trace[1991615046] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1293; }","duration":"464.116268ms","start":"2026-04-18T22:17:28.381293Z","end":"2026-04-18T22:17:28.845409Z","steps":["trace[1991615046] 'read index received' (duration: 445.489882ms)","trace[1991615046] 'applied index is now lower than readState.Index' (duration: 18.625416ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:28.845484Z","caller":"traceutil/trace.go:171","msg":"trace[835587760] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"482.815567ms","start":"2026-04-18T22:17:28.362625Z","end":"2026-04-18T22:17:28.84544Z","steps":["trace[835587760] 'process raft request' (duration: 482.681164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.84562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.362611Z","time spent":"482.938439ms","remote":"127.0.0.1:33810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:17:28.845639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.311354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:28.84574Z","caller":"traceutil/trace.go:171","msg":"trace[299539646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1243; }","duration":"464.459897ms","start":"2026-04-18T22:17:28.381259Z","end":"2026-04-18T22:17:28.845719Z","steps":["trace[299539646] 'agreement among raft nodes before linearized reading' (duration: 464.234931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:28.845796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:28.381247Z","time spent":"464.540088ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-18T22:17:29.501462Z","caller":"traceutil/trace.go:171","msg":"trace[730718046] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"124.677265ms","start":"2026-04-18T22:17:29.376761Z","end":"2026-04-18T22:17:29.501438Z","steps":["trace[730718046] 'process raft request' (duration: 124.331097ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:32.554538Z","caller":"traceutil/trace.go:171","msg":"trace[591174207] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"173.512534ms","start":"2026-04-18T22:17:32.38093Z","end":"2026-04-18T22:17:32.554442Z","steps":["trace[591174207] 'read index received' (duration: 171.518979ms)","trace[591174207] 'applied index is now lower than readState.Index' (duration: 1.980264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:17:32.554744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.797491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:32.554789Z","caller":"traceutil/trace.go:171","msg":"trace[1260979655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"173.889293ms","start":"2026-04-18T22:17:32.380877Z","end":"2026-04-18T22:17:32.554766Z","steps":["trace[1260979655] 'agreement among raft nodes before linearized reading' (duration: 173.77958ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:32.554902Z","caller":"traceutil/trace.go:171","msg":"trace[1523368464] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"185.203832ms","start":"2026-04-18T22:17:32.369662Z","end":"2026-04-18T22:17:32.554865Z","steps":["trace[1523368464] 'process raft request' (duration: 182.777216ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.251845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.174423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-18T22:17:33.252227Z","caller":"traceutil/trace.go:171","msg":"trace[2137914359] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"403.615374ms","start":"2026-04-18T22:17:32.848579Z","end":"2026-04-18T22:17:33.252195Z","steps":["trace[2137914359] 'range keys from in-memory index tree' (duration: 402.959439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.252498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:32.848557Z","time spent":"403.917321ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-18T22:17:33.252324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.028483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-18T22:17:33.252824Z","caller":"traceutil/trace.go:171","msg":"trace[1175975344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"404.567216ms","start":"2026-04-18T22:17:32.848199Z","end":"2026-04-18T22:17:33.252766Z","steps":["trace[1175975344] 'range keys from in-memory index tree' (duration: 403.823388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.251845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.663119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-18T22:17:33.252929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:32.848188Z","time spent":"404.706019ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-18T22:17:33.252928Z","caller":"traceutil/trace.go:171","msg":"trace[253459462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1270; }","duration":"406.795546ms","start":"2026-04-18T22:17:32.846104Z","end":"2026-04-18T22:17:33.2529Z","steps":["trace[253459462] 'range keys from in-memory index tree' (duration: 405.369333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.252319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.967389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-18T22:17:33.253258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:32.846087Z","time spent":"406.886048ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-18T22:17:33.25338Z","caller":"traceutil/trace.go:171","msg":"trace[1765235160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"404.073013ms","start":"2026-04-18T22:17:32.849243Z","end":"2026-04-18T22:17:33.253316Z","steps":["trace[1765235160] 'range keys from in-memory index tree' (duration: 402.655771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.253478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:32.849232Z","time spent":"404.229908ms","remote":"127.0.0.1:34028","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-04-18T22:17:33.617518Z","caller":"traceutil/trace.go:171","msg":"trace[2111751206] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"360.274762ms","start":"2026-04-18T22:17:33.257227Z","end":"2026-04-18T22:17:33.617501Z","steps":["trace[2111751206] 'process raft request' (duration: 360.18344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.617652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.257202Z","time spent":"360.391984ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:33.8833Z","caller":"traceutil/trace.go:171","msg":"trace[1543385690] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1326; }","duration":"501.710119ms","start":"2026-04-18T22:17:33.381564Z","end":"2026-04-18T22:17:33.883274Z","steps":["trace[1543385690] 'read index received' (duration: 235.889552ms)","trace[1543385690] 'applied index is now lower than readState.Index' (duration: 265.819477ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:33.883333Z","caller":"traceutil/trace.go:171","msg":"trace[1658241264] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"625.936676ms","start":"2026-04-18T22:17:33.257353Z","end":"2026-04-18T22:17:33.883289Z","steps":["trace[1658241264] 'process raft request' (duration: 625.637889ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:33.883397Z","caller":"traceutil/trace.go:171","msg":"trace[2121424642] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"625.341211ms","start":"2026-04-18T22:17:33.257989Z","end":"2026-04-18T22:17:33.883331Z","steps":["trace[2121424642] 'process raft request' (duration: 625.205518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.883504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.929345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:17:33.883567Z","caller":"traceutil/trace.go:171","msg":"trace[248934616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"502.051277ms","start":"2026-04-18T22:17:33.381486Z","end":"2026-04-18T22:17:33.883537Z","steps":["trace[248934616] 'agreement among raft nodes before linearized reading' (duration: 501.937795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.883611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.381458Z","time spent":"502.14452ms","remote":"127.0.0.1:33744","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-18T22:17:33.883677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.257965Z","time spent":"625.559246ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:33.883802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.257335Z","time spent":"626.081539ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:33.883815Z","caller":"traceutil/trace.go:171","msg":"trace[1392777697] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"363.524595ms","start":"2026-04-18T22:17:33.519965Z","end":"2026-04-18T22:17:33.88349Z","steps":["trace[1392777697] 'process raft request' (duration: 363.268759ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:33.884128Z","caller":"traceutil/trace.go:171","msg":"trace[880854805] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"626.522639ms","start":"2026-04-18T22:17:33.257587Z","end":"2026-04-18T22:17:33.88411Z","steps":["trace[880854805] 'process raft request' (duration: 625.568038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.88422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.257548Z","time spent":"626.626781ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:17:33.884216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.948605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:17:33.884347Z","caller":"traceutil/trace.go:171","msg":"trace[1580103615] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1275; }","duration":"481.139819ms","start":"2026-04-18T22:17:33.403174Z","end":"2026-04-18T22:17:33.884314Z","steps":["trace[1580103615] 'agreement among raft nodes before linearized reading' (duration: 480.779751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:33.884425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.40316Z","time spent":"481.253832ms","remote":"127.0.0.1:34110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-04-18T22:17:33.884688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:17:33.519937Z","time spent":"364.480837ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:17:36.039417Z","caller":"traceutil/trace.go:171","msg":"trace[277362250] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"147.782384ms","start":"2026-04-18T22:17:35.891609Z","end":"2026-04-18T22:17:36.039392Z","steps":["trace[277362250] 'process raft request' (duration: 147.657181ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:36.075319Z","caller":"traceutil/trace.go:171","msg":"trace[80061139] linearizableReadLoop","detail":"{readStateIndex:1345; appliedIndex:1341; }","duration":"136.554526ms","start":"2026-04-18T22:17:35.938745Z","end":"2026-04-18T22:17:36.0753Z","steps":["trace[80061139] 'read index received' (duration: 100.580592ms)","trace[80061139] 'applied index is now lower than readState.Index' (duration: 35.972894ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:17:36.075351Z","caller":"traceutil/trace.go:171","msg":"trace[745602131] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"182.895248ms","start":"2026-04-18T22:17:35.892428Z","end":"2026-04-18T22:17:36.075323Z","steps":["trace[745602131] 'process raft request' (duration: 182.795786ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:36.075375Z","caller":"traceutil/trace.go:171","msg":"trace[676174115] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"182.876348ms","start":"2026-04-18T22:17:35.89248Z","end":"2026-04-18T22:17:36.075357Z","steps":["trace[676174115] 'process raft request' (duration: 182.781225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:17:36.075514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.745641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-18T22:17:36.075556Z","caller":"traceutil/trace.go:171","msg":"trace[130134029] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1289; }","duration":"136.837093ms","start":"2026-04-18T22:17:35.938709Z","end":"2026-04-18T22:17:36.075546Z","steps":["trace[130134029] 'agreement among raft nodes before linearized reading' (duration: 136.676129ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:36.075835Z","caller":"traceutil/trace.go:171","msg":"trace[310364127] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"183.099012ms","start":"2026-04-18T22:17:35.892195Z","end":"2026-04-18T22:17:36.075294Z","steps":["trace[310364127] 'process raft request' (duration: 182.919098ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:17:36.793598Z","caller":"traceutil/trace.go:171","msg":"trace[1066545376] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"139.581577ms","start":"2026-04-18T22:17:36.653995Z","end":"2026-04-18T22:17:36.793576Z","steps":["trace[1066545376] 'process raft request' (duration: 139.383913ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:35.538963Z","caller":"traceutil/trace.go:171","msg":"trace[1309219223] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"100.086533ms","start":"2026-04-18T22:18:35.438854Z","end":"2026-04-18T22:18:35.538941Z","steps":["trace[1309219223] 'process raft request' (duration: 99.97604ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:38.229727Z","caller":"traceutil/trace.go:171","msg":"trace[139688441] transaction","detail":"{read_only:false; response_revision:1589; number_of_response:1; }","duration":"126.129683ms","start":"2026-04-18T22:18:38.103572Z","end":"2026-04-18T22:18:38.229701Z","steps":["trace[139688441] 'process raft request' (duration: 125.9974ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:44.864086Z","caller":"traceutil/trace.go:171","msg":"trace[1122919949] transaction","detail":"{read_only:false; response_revision:1620; number_of_response:1; }","duration":"137.773777ms","start":"2026-04-18T22:18:44.726286Z","end":"2026-04-18T22:18:44.86406Z","steps":["trace[1122919949] 'process raft request' (duration: 91.534047ms)","trace[1122919949] 'compare' (duration: 46.018386ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:51.248757Z","caller":"traceutil/trace.go:171","msg":"trace[83724888] transaction","detail":"{read_only:false; response_revision:1650; number_of_response:1; }","duration":"135.934997ms","start":"2026-04-18T22:18:51.112789Z","end":"2026-04-18T22:18:51.248724Z","steps":["trace[83724888] 'process raft request' (duration: 64.966055ms)","trace[83724888] 'compare' (duration: 70.780737ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:51.446238Z","caller":"traceutil/trace.go:171","msg":"trace[364655218] linearizableReadLoop","detail":"{readStateIndex:1723; appliedIndex:1722; }","duration":"192.188932ms","start":"2026-04-18T22:18:51.254016Z","end":"2026-04-18T22:18:51.446205Z","steps":["trace[364655218] 'read index received' (duration: 191.508147ms)","trace[364655218] 'applied index is now lower than readState.Index' (duration: 679.435µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:18:51.446322Z","caller":"traceutil/trace.go:171","msg":"trace[453579297] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"193.734427ms","start":"2026-04-18T22:18:51.252537Z","end":"2026-04-18T22:18:51.446271Z","steps":["trace[453579297] 'process raft request' (duration: 193.092983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:51.446414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.364286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-18T22:18:51.446474Z","caller":"traceutil/trace.go:171","msg":"trace[554310051] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1651; }","duration":"192.475628ms","start":"2026-04-18T22:18:51.25398Z","end":"2026-04-18T22:18:51.446456Z","steps":["trace[554310051] 'agreement among raft nodes before linearized reading' (duration: 192.347126ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:52.880491Z","caller":"traceutil/trace.go:171","msg":"trace[804462335] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"118.676203ms","start":"2026-04-18T22:18:52.761766Z","end":"2026-04-18T22:18:52.880442Z","steps":["trace[804462335] 'process raft request' (duration: 118.434378ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:18:59.438841Z","caller":"traceutil/trace.go:171","msg":"trace[1775512970] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"317.271481ms","start":"2026-04-18T22:18:59.121544Z","end":"2026-04-18T22:18:59.438815Z","steps":["trace[1775512970] 'process raft request' (duration: 317.093887ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:59.439008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:59.121519Z","time spent":"317.407834ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:59.493579Z","caller":"traceutil/trace.go:171","msg":"trace[1614156646] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"370.93174ms","start":"2026-04-18T22:18:59.122404Z","end":"2026-04-18T22:18:59.493336Z","steps":["trace[1614156646] 'process raft request' (duration: 370.789557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:18:59.493903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:18:59.122393Z","time spent":"371.432601ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:18:59.640936Z","caller":"traceutil/trace.go:171","msg":"trace[15649692] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"126.322856ms","start":"2026-04-18T22:18:59.514517Z","end":"2026-04-18T22:18:59.64084Z","steps":["trace[15649692] 'process raft request' (duration: 96.93687ms)","trace[15649692] 'compare' (duration: 29.09924ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:00.025194Z","caller":"traceutil/trace.go:171","msg":"trace[221506991] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"118.07624ms","start":"2026-04-18T22:18:59.907087Z","end":"2026-04-18T22:19:00.025163Z","steps":["trace[221506991] 'process raft request' (duration: 117.749662ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:29.39534Z","caller":"traceutil/trace.go:171","msg":"trace[107778870] linearizableReadLoop","detail":"{readStateIndex:1899; appliedIndex:1898; }","duration":"176.957624ms","start":"2026-04-18T22:19:29.218358Z","end":"2026-04-18T22:19:29.395315Z","steps":["trace[107778870] 'read index received' (duration: 176.704988ms)","trace[107778870] 'applied index is now lower than readState.Index' (duration: 251.576µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:19:29.395432Z","caller":"traceutil/trace.go:171","msg":"trace[954067143] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"225.146872ms","start":"2026-04-18T22:19:29.170244Z","end":"2026-04-18T22:19:29.39539Z","steps":["trace[954067143] 'process raft request' (duration: 224.923427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:29.395606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.22171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-18T22:19:29.395748Z","caller":"traceutil/trace.go:171","msg":"trace[602228420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1820; }","duration":"177.387154ms","start":"2026-04-18T22:19:29.218316Z","end":"2026-04-18T22:19:29.395703Z","steps":["trace[602228420] 'agreement among raft nodes before linearized reading' (duration: 177.098637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:29.618931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.420866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-18T22:19:29.619015Z","caller":"traceutil/trace.go:171","msg":"trace[2008324133] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1821; }","duration":"149.550819ms","start":"2026-04-18T22:19:29.469443Z","end":"2026-04-18T22:19:29.618993Z","steps":["trace[2008324133] 'count revisions from in-memory index tree' (duration: 149.319844ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:19:32.557468Z","caller":"traceutil/trace.go:171","msg":"trace[1259370539] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"215.440951ms","start":"2026-04-18T22:19:32.341995Z","end":"2026-04-18T22:19:32.557436Z","steps":["trace[1259370539] 'process raft request' (duration: 215.134934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:19:54.096333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.061105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:19:54.096454Z","caller":"traceutil/trace.go:171","msg":"trace[1777342280] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"178.708193ms","start":"2026-04-18T22:19:53.917724Z","end":"2026-04-18T22:19:54.096433Z","steps":["trace[1777342280] 'process raft request' (duration: 55.416252ms)","trace[1777342280] 'compare' (duration: 122.951723ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:20:43.866901Z","caller":"traceutil/trace.go:171","msg":"trace[1038848770] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"122.498569ms","start":"2026-04-18T22:20:43.744369Z","end":"2026-04-18T22:20:43.866868Z","steps":["trace[1038848770] 'process raft request' (duration: 122.318685ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:52.636478Z","caller":"traceutil/trace.go:171","msg":"trace[407757468] transaction","detail":"{read_only:false; response_revision:2234; number_of_response:1; }","duration":"110.488705ms","start":"2026-04-18T22:20:52.525963Z","end":"2026-04-18T22:20:52.636452Z","steps":["trace[407757468] 'process raft request' (duration: 73.890605ms)","trace[407757468] 'compare' (duration: 36.497288ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:52.892162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.167095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-04-18T22:20:52.892569Z","caller":"traceutil/trace.go:171","msg":"trace[700424297] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2234; }","duration":"105.646136ms","start":"2026-04-18T22:20:52.786903Z","end":"2026-04-18T22:20:52.892549Z","steps":["trace[700424297] 'range keys from in-memory index tree' (duration: 104.816997ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:20:53.241544Z","caller":"traceutil/trace.go:171","msg":"trace[1857249973] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"344.259708ms","start":"2026-04-18T22:20:52.897256Z","end":"2026-04-18T22:20:53.241516Z","steps":["trace[1857249973] 'process raft request' (duration: 291.641384ms)","trace[1857249973] 'compare' (duration: 52.47913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:20:53.241725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:20:52.897238Z","time spent":"344.370421ms","remote":"127.0.0.1:34028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}