{"level":"warn","ts":"2026-04-02T00:59:43.880252Z","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-02T00:59:43.880356Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.200: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://162.253.55.200:2380","--initial-cluster=instance=https://162.253.55.200:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.200:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.200: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-02T00:59:43.880568Z","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-02T00:59:43.880586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-02T00:59:43.880658Z","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-02T00:59:43.881764Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"]} {"level":"info","ts":"2026-04-02T00:59:43.881904Z","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://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.200: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-02T00:59:43.886783Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.606469ms"} {"level":"info","ts":"2026-04-02T00:59:43.893268Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e10bb51ed7578852","cluster-id":"aa14459abd2161c3"} {"level":"info","ts":"2026-04-02T00:59:43.893371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T00:59:43.893406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 0"} {"level":"info","ts":"2026-04-02T00:59:43.89343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e10bb51ed7578852 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T00:59:43.893451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became follower at term 1"} {"level":"info","ts":"2026-04-02T00:59:43.893506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"warn","ts":"2026-04-02T00:59:43.903434Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T00:59:43.905029Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T00:59:43.906376Z","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-02T00:59:43.908696Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e10bb51ed7578852","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T00:59:43.908946Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e10bb51ed7578852","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T00:59:43.909096Z","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-02T00:59:43.909161Z","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-02T00:59:43.909176Z","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-02T00:59:43.913215Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 switched to configuration voters=(16216254027344087122)"} {"level":"info","ts":"2026-04-02T00:59:43.913473Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","added-peer-id":"e10bb51ed7578852","added-peer-peer-urls":["https://162.253.55.200:2380"]} {"level":"info","ts":"2026-04-02T00:59:43.91463Z","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-02T00:59:43.914955Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e10bb51ed7578852","initial-advertise-peer-urls":["https://162.253.55.200:2380"],"listen-peer-urls":["https://162.253.55.200:2380"],"advertise-client-urls":["https://162.253.55.200:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.200:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T00:59:43.915013Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T00:59:43.915194Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-02T00:59:43.915229Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.200:2380"} {"level":"info","ts":"2026-04-02T00:59:43.994495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T00:59:43.994572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T00:59:43.994597Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgPreVoteResp from e10bb51ed7578852 at term 1"} {"level":"info","ts":"2026-04-02T00:59:43.994617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became candidate at term 2"} {"level":"info","ts":"2026-04-02T00:59:43.994635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 received MsgVoteResp from e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-02T00:59:43.994652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e10bb51ed7578852 became leader at term 2"} {"level":"info","ts":"2026-04-02T00:59:43.994672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e10bb51ed7578852 elected leader e10bb51ed7578852 at term 2"} {"level":"info","ts":"2026-04-02T00:59:43.995629Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T00:59:43.996341Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e10bb51ed7578852","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.200:2379]}","request-path":"/0/members/e10bb51ed7578852/attributes","cluster-id":"aa14459abd2161c3","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T00:59:43.996518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T00:59:43.996571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T00:59:43.996701Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa14459abd2161c3","local-member-id":"e10bb51ed7578852","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T00:59:43.996864Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T00:59:43.996909Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T00:59:43.997027Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T00:59:43.997097Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T00:59:44.001004Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T00:59:44.001076Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.200:2379"} {"level":"info","ts":"2026-04-02T00:59:49.024103Z","caller":"traceutil/trace.go:171","msg":"trace[797854393] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"182.087036ms","start":"2026-04-02T00:59:48.841987Z","end":"2026-04-02T00:59:49.024074Z","steps":["trace[797854393] 'process raft request' (duration: 181.904767ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:49.156195Z","caller":"traceutil/trace.go:171","msg":"trace[1550912889] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"124.813089ms","start":"2026-04-02T00:59:49.031348Z","end":"2026-04-02T00:59:49.156161Z","steps":["trace[1550912889] 'process raft request' (duration: 81.024403ms)","trace[1550912889] 'compare' (duration: 43.642029ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:49.381017Z","caller":"traceutil/trace.go:171","msg":"trace[2035001698] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"128.609238ms","start":"2026-04-02T00:59:49.25237Z","end":"2026-04-02T00:59:49.38098Z","steps":["trace[2035001698] 'process raft request' (duration: 128.23479ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:49.57361Z","caller":"traceutil/trace.go:171","msg":"trace[1553329552] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"188.510308ms","start":"2026-04-02T00:59:49.385078Z","end":"2026-04-02T00:59:49.573588Z","steps":["trace[1553329552] 'process raft request' (duration: 188.459606ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:49.57366Z","caller":"traceutil/trace.go:171","msg":"trace[1354567961] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"188.660075ms","start":"2026-04-02T00:59:49.384969Z","end":"2026-04-02T00:59:49.573629Z","steps":["trace[1354567961] 'process raft request' (duration: 103.40663ms)","trace[1354567961] 'compare' (duration: 85.050215ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:49.830367Z","caller":"traceutil/trace.go:171","msg":"trace[837889789] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"139.139053ms","start":"2026-04-02T00:59:49.691197Z","end":"2026-04-02T00:59:49.830337Z","steps":["trace[837889789] 'read index received' (duration: 56.30181ms)","trace[837889789] 'applied index is now lower than readState.Index' (duration: 82.835823ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:49.830474Z","caller":"traceutil/trace.go:171","msg":"trace[1141684227] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"164.420854ms","start":"2026-04-02T00:59:49.666016Z","end":"2026-04-02T00:59:49.830437Z","steps":["trace[1141684227] 'process raft request' (duration: 81.519488ms)","trace[1141684227] 'compare' (duration: 82.343719ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:49.830577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.363814ms","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-02T00:59:49.830649Z","caller":"traceutil/trace.go:171","msg":"trace[2075118750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:274; }","duration":"139.470099ms","start":"2026-04-02T00:59:49.691165Z","end":"2026-04-02T00:59:49.830635Z","steps":["trace[2075118750] 'agreement among raft nodes before linearized reading' (duration: 139.311021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:49.830906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.727544ms","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-02T00:59:49.830952Z","caller":"traceutil/trace.go:171","msg":"trace[1582800277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:274; }","duration":"127.834339ms","start":"2026-04-02T00:59:49.703103Z","end":"2026-04-02T00:59:49.830937Z","steps":["trace[1582800277] 'agreement among raft nodes before linearized reading' (duration: 127.726224ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:50.28711Z","caller":"traceutil/trace.go:171","msg":"trace[1130304922] transaction","detail":"{read_only:false; number_of_response:0; response_revision:289; }","duration":"104.301311ms","start":"2026-04-02T00:59:50.182783Z","end":"2026-04-02T00:59:50.287084Z","steps":["trace[1130304922] 'process raft request' (duration: 104.159134ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:50.287406Z","caller":"traceutil/trace.go:171","msg":"trace[1812539799] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"104.919902ms","start":"2026-04-02T00:59:50.182462Z","end":"2026-04-02T00:59:50.287382Z","steps":["trace[1812539799] 'process raft request' (duration: 81.592246ms)","trace[1812539799] 'compare' (duration: 22.734826ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:50.28761Z","caller":"traceutil/trace.go:171","msg":"trace[1792651076] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:294; }","duration":"101.365894ms","start":"2026-04-02T00:59:50.186197Z","end":"2026-04-02T00:59:50.287563Z","steps":["trace[1792651076] 'read index received' (duration: 77.893652ms)","trace[1792651076] 'applied index is now lower than readState.Index' (duration: 23.469782ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:50.287776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.489311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-02T00:59:50.287802Z","caller":"traceutil/trace.go:171","msg":"trace[231558275] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:291; }","duration":"107.575456ms","start":"2026-04-02T00:59:50.180218Z","end":"2026-04-02T00:59:50.287794Z","steps":["trace[231558275] 'agreement among raft nodes before linearized reading' (duration: 107.449069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.135354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.684858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-02T00:59:54.135446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.392724ms","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-02T00:59:54.13546Z","caller":"traceutil/trace.go:171","msg":"trace[1836210195] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:342; }","duration":"206.834736ms","start":"2026-04-02T00:59:53.928598Z","end":"2026-04-02T00:59:54.135433Z","steps":["trace[1836210195] 'range keys from in-memory index tree' (duration: 206.583123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.135485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.254052ms","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-02T00:59:54.135577Z","caller":"traceutil/trace.go:171","msg":"trace[531996296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"255.459242ms","start":"2026-04-02T00:59:53.880094Z","end":"2026-04-02T00:59:54.135553Z","steps":["trace[531996296] 'range keys from in-memory index tree' (duration: 255.061933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.135755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.350395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-04-02T00:59:54.135842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.20717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-02T00:59:54.135687Z","caller":"traceutil/trace.go:171","msg":"trace[1213754464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:342; }","duration":"219.652277ms","start":"2026-04-02T00:59:53.916014Z","end":"2026-04-02T00:59:54.135667Z","steps":["trace[1213754464] 'range keys from in-memory index tree' (duration: 219.286509ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.135882Z","caller":"traceutil/trace.go:171","msg":"trace[1160733454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:342; }","duration":"257.271513ms","start":"2026-04-02T00:59:53.878597Z","end":"2026-04-02T00:59:54.135869Z","steps":["trace[1160733454] 'range keys from in-memory index tree' (duration: 257.128157ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.135875Z","caller":"traceutil/trace.go:171","msg":"trace[1341034618] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"157.506002ms","start":"2026-04-02T00:59:53.97834Z","end":"2026-04-02T00:59:54.135846Z","steps":["trace[1341034618] 'range keys from in-memory index tree' (duration: 157.225838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.136011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.506495ms","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-02T00:59:54.136054Z","caller":"traceutil/trace.go:171","msg":"trace[65472419] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:342; }","duration":"113.567778ms","start":"2026-04-02T00:59:54.022473Z","end":"2026-04-02T00:59:54.136041Z","steps":["trace[65472419] 'range keys from in-memory index tree' (duration: 113.417821ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.13616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.726645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T00:59:54.136251Z","caller":"traceutil/trace.go:171","msg":"trace[959838070] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:342; }","duration":"107.8349ms","start":"2026-04-02T00:59:54.0284Z","end":"2026-04-02T00:59:54.136235Z","steps":["trace[959838070] 'range keys from in-memory index tree' (duration: 107.630951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.586008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.536355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-02T00:59:54.586024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.585359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T00:59:54.586109Z","caller":"traceutil/trace.go:171","msg":"trace[1477031742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:352; }","duration":"157.680032ms","start":"2026-04-02T00:59:54.428412Z","end":"2026-04-02T00:59:54.586092Z","steps":["trace[1477031742] 'range keys from in-memory index tree' (duration: 157.43596ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.586145Z","caller":"traceutil/trace.go:171","msg":"trace[1123347309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:352; }","duration":"107.703945ms","start":"2026-04-02T00:59:54.478404Z","end":"2026-04-02T00:59:54.586108Z","steps":["trace[1123347309] 'range keys from in-memory index tree' (duration: 107.513535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.792632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.213439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:54.792784Z","caller":"traceutil/trace.go:171","msg":"trace[1690853218] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"197.431768ms","start":"2026-04-02T00:59:54.595326Z","end":"2026-04-02T00:59:54.792757Z","steps":["trace[1690853218] 'process raft request' (duration: 95.688031ms)","trace[1690853218] 'compare' (duration: 101.071252ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:54.79282Z","caller":"traceutil/trace.go:171","msg":"trace[426967498] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:366; }","duration":"164.225551ms","start":"2026-04-02T00:59:54.628581Z","end":"2026-04-02T00:59:54.792806Z","steps":["trace[426967498] 'read index received' (duration: 49.923575ms)","trace[426967498] 'applied index is now lower than readState.Index' (duration: 114.301136ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:54.792835Z","caller":"traceutil/trace.go:171","msg":"trace[550744393] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"197.47655ms","start":"2026-04-02T00:59:54.595332Z","end":"2026-04-02T00:59:54.792809Z","steps":["trace[550744393] 'process raft request' (duration: 197.380875ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.792878Z","caller":"traceutil/trace.go:171","msg":"trace[433768618] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"194.88291ms","start":"2026-04-02T00:59:54.597989Z","end":"2026-04-02T00:59:54.792872Z","steps":["trace[433768618] 'process raft request' (duration: 194.764354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.79295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.372069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-02T00:59:54.792969Z","caller":"traceutil/trace.go:171","msg":"trace[23540770] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:356; }","duration":"164.439802ms","start":"2026-04-02T00:59:54.628522Z","end":"2026-04-02T00:59:54.792962Z","steps":["trace[23540770] 'agreement among raft nodes before linearized reading' (duration: 164.368168ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.833707Z","caller":"traceutil/trace.go:171","msg":"trace[1917533332] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"148.286512ms","start":"2026-04-02T00:59:54.685396Z","end":"2026-04-02T00:59:54.833682Z","steps":["trace[1917533332] 'process raft request' (duration: 148.194497ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:54.83377Z","caller":"traceutil/trace.go:171","msg":"trace[1363007711] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"150.667091ms","start":"2026-04-02T00:59:54.683072Z","end":"2026-04-02T00:59:54.833739Z","steps":["trace[1363007711] 'process raft request' (duration: 150.412588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.834398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.658821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-02T00:59:54.834442Z","caller":"traceutil/trace.go:171","msg":"trace[152974289] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:358; }","duration":"104.763397ms","start":"2026-04-02T00:59:54.729666Z","end":"2026-04-02T00:59:54.83443Z","steps":["trace[152974289] 'agreement among raft nodes before linearized reading' (duration: 104.480313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:54.834396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.213168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-02T00:59:54.834537Z","caller":"traceutil/trace.go:171","msg":"trace[1604005024] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:358; }","duration":"156.408559ms","start":"2026-04-02T00:59:54.678098Z","end":"2026-04-02T00:59:54.834507Z","steps":["trace[1604005024] 'agreement among raft nodes before linearized reading' (duration: 156.167326ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.036423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.126543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:55.036595Z","caller":"traceutil/trace.go:171","msg":"trace[1117042385] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:372; }","duration":"202.889574ms","start":"2026-04-02T00:59:54.833687Z","end":"2026-04-02T00:59:55.036577Z","steps":["trace[1117042385] 'read index received' (duration: 67.529869ms)","trace[1117042385] 'applied index is now lower than readState.Index' (duration: 135.358435ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.036755Z","caller":"traceutil/trace.go:171","msg":"trace[340410734] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"238.640307ms","start":"2026-04-02T00:59:54.798102Z","end":"2026-04-02T00:59:55.036743Z","steps":["trace[340410734] 'process raft request' (duration: 103.101393ms)","trace[340410734] 'compare' (duration: 134.985417ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.036988Z","caller":"traceutil/trace.go:171","msg":"trace[2051539691] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"237.578835ms","start":"2026-04-02T00:59:54.7994Z","end":"2026-04-02T00:59:55.036979Z","steps":["trace[2051539691] 'process raft request' (duration: 237.117152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.037112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.732936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-02T00:59:55.03719Z","caller":"traceutil/trace.go:171","msg":"trace[1089814072] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:360; }","duration":"207.872574ms","start":"2026-04-02T00:59:54.829297Z","end":"2026-04-02T00:59:55.03717Z","steps":["trace[1089814072] 'agreement among raft nodes before linearized reading' (duration: 207.704085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.267251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.514715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:55.267495Z","caller":"traceutil/trace.go:171","msg":"trace[1971423654] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"426.198321ms","start":"2026-04-02T00:59:54.841256Z","end":"2026-04-02T00:59:55.267454Z","steps":["trace[1971423654] 'process raft request' (duration: 302.740879ms)","trace[1971423654] 'compare' (duration: 122.068103ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.267769Z","caller":"traceutil/trace.go:171","msg":"trace[542761656] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"223.55071ms","start":"2026-04-02T00:59:55.044199Z","end":"2026-04-02T00:59:55.26775Z","steps":["trace[542761656] 'process raft request' (duration: 223.494057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.267749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.841234Z","time spent":"426.37169ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:55.268071Z","caller":"traceutil/trace.go:171","msg":"trace[1525157951] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"426.635543ms","start":"2026-04-02T00:59:54.841428Z","end":"2026-04-02T00:59:55.268064Z","steps":["trace[1525157951] 'process raft request' (duration: 425.942588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.268118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.841422Z","time spent":"426.675415ms","remote":"127.0.0.1:54236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T00:59:55.268194Z","caller":"traceutil/trace.go:171","msg":"trace[1204613273] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:374; }","duration":"231.548391ms","start":"2026-04-02T00:59:55.036627Z","end":"2026-04-02T00:59:55.268175Z","steps":["trace[1204613273] 'read index received' (duration: 107.31086ms)","trace[1204613273] 'applied index is now lower than readState.Index' (duration: 124.235011ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.268284Z","caller":"traceutil/trace.go:171","msg":"trace[1166822706] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"226.899808ms","start":"2026-04-02T00:59:55.04137Z","end":"2026-04-02T00:59:55.26827Z","steps":["trace[1166822706] 'process raft request' (duration: 226.241595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.268381Z","caller":"traceutil/trace.go:171","msg":"trace[1418710619] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"226.470055ms","start":"2026-04-02T00:59:55.041904Z","end":"2026-04-02T00:59:55.268374Z","steps":["trace[1418710619] 'process raft request' (duration: 225.733638ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.268509Z","caller":"traceutil/trace.go:171","msg":"trace[1283508425] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"425.534077ms","start":"2026-04-02T00:59:54.842917Z","end":"2026-04-02T00:59:55.268451Z","steps":["trace[1283508425] 'process raft request' (duration: 424.523636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.26859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.842908Z","time spent":"425.635602ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:55.268657Z","caller":"traceutil/trace.go:171","msg":"trace[2070508949] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"224.9383ms","start":"2026-04-02T00:59:55.043707Z","end":"2026-04-02T00:59:55.268645Z","steps":["trace[2070508949] 'process raft request' (duration: 223.957941ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.268755Z","caller":"traceutil/trace.go:171","msg":"trace[180327134] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"425.38564ms","start":"2026-04-02T00:59:54.843357Z","end":"2026-04-02T00:59:55.268743Z","steps":["trace[180327134] 'process raft request' (duration: 424.133817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.268829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.843351Z","time spent":"425.443974ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:55.269134Z","caller":"traceutil/trace.go:171","msg":"trace[1363217039] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"425.596021ms","start":"2026-04-02T00:59:54.843526Z","end":"2026-04-02T00:59:55.269122Z","steps":["trace[1363217039] 'process raft request' (duration: 424.015221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.269206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.84352Z","time spent":"425.652634ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:55.269336Z","caller":"traceutil/trace.go:171","msg":"trace[644367615] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"425.662704ms","start":"2026-04-02T00:59:54.843663Z","end":"2026-04-02T00:59:55.269326Z","steps":["trace[644367615] 'process raft request' (duration: 423.911086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.26939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.843639Z","time spent":"425.724057ms","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T00:59:55.269579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.036587ms","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-02T00:59:55.269617Z","caller":"traceutil/trace.go:171","msg":"trace[368403620] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:370; }","duration":"392.118471ms","start":"2026-04-02T00:59:54.877489Z","end":"2026-04-02T00:59:55.269607Z","steps":["trace[368403620] 'agreement among raft nodes before linearized reading' (duration: 392.027047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.269662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.877478Z","time spent":"392.175964ms","remote":"127.0.0.1:54344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-04-02T00:59:55.269979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.656769ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T00:59:55.270071Z","caller":"traceutil/trace.go:171","msg":"trace[388658549] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:370; }","duration":"155.748204ms","start":"2026-04-02T00:59:55.11431Z","end":"2026-04-02T00:59:55.270058Z","steps":["trace[388658549] 'agreement among raft nodes before linearized reading' (duration: 155.638148ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.270268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.034967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T00:59:55.269857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.130528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-02T00:59:55.270523Z","caller":"traceutil/trace.go:171","msg":"trace[549492859] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:370; }","duration":"428.800792ms","start":"2026-04-02T00:59:54.841686Z","end":"2026-04-02T00:59:55.270487Z","steps":["trace[549492859] 'agreement among raft nodes before linearized reading' (duration: 428.125379ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.270638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:54.841666Z","time spent":"428.928409ms","remote":"127.0.0.1:54628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-04-02T00:59:55.270302Z","caller":"traceutil/trace.go:171","msg":"trace[1134679652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"107.10483ms","start":"2026-04-02T00:59:55.163189Z","end":"2026-04-02T00:59:55.270293Z","steps":["trace[1134679652] 'agreement among raft nodes before linearized reading' (duration: 107.049877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.587711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.43788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:55.58782Z","caller":"traceutil/trace.go:171","msg":"trace[1649008407] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"268.594811ms","start":"2026-04-02T00:59:55.319205Z","end":"2026-04-02T00:59:55.587799Z","steps":["trace[1649008407] 'process raft request' (duration: 147.983813ms)","trace[1649008407] 'compare' (duration: 120.199248ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.61528Z","caller":"traceutil/trace.go:171","msg":"trace[165386672] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"293.368267ms","start":"2026-04-02T00:59:55.321886Z","end":"2026-04-02T00:59:55.615254Z","steps":["trace[165386672] 'process raft request' (duration: 293.184997ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.615474Z","caller":"traceutil/trace.go:171","msg":"trace[321692981] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:389; }","duration":"191.742021ms","start":"2026-04-02T00:59:55.423718Z","end":"2026-04-02T00:59:55.61546Z","steps":["trace[321692981] 'read index received' (duration: 43.673323ms)","trace[321692981] 'applied index is now lower than readState.Index' (duration: 148.068048ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.615591Z","caller":"traceutil/trace.go:171","msg":"trace[1494441151] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"293.23906ms","start":"2026-04-02T00:59:55.322301Z","end":"2026-04-02T00:59:55.61554Z","steps":["trace[1494441151] 'process raft request' (duration: 293.084082ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.615609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.841666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-02T00:59:55.615639Z","caller":"traceutil/trace.go:171","msg":"trace[306613547] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"191.954811ms","start":"2026-04-02T00:59:55.423674Z","end":"2026-04-02T00:59:55.615629Z","steps":["trace[306613547] 'agreement among raft nodes before linearized reading' (duration: 191.847946ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.615616Z","caller":"traceutil/trace.go:171","msg":"trace[2023177892] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"293.377456ms","start":"2026-04-02T00:59:55.322146Z","end":"2026-04-02T00:59:55.615524Z","steps":["trace[2023177892] 'process raft request' (duration: 293.036479ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.878912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.998197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:55.87909Z","caller":"traceutil/trace.go:171","msg":"trace[1257161273] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"286.919912ms","start":"2026-04-02T00:59:55.592121Z","end":"2026-04-02T00:59:55.879041Z","steps":["trace[1257161273] 'process raft request' (duration: 127.714876ms)","trace[1257161273] 'compare' (duration: 158.823457ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.879172Z","caller":"traceutil/trace.go:171","msg":"trace[700150866] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"262.252513ms","start":"2026-04-02T00:59:55.616889Z","end":"2026-04-02T00:59:55.879142Z","steps":["trace[700150866] 'read index received' (duration: 102.959592ms)","trace[700150866] 'applied index is now lower than readState.Index' (duration: 159.289621ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:55.879281Z","caller":"traceutil/trace.go:171","msg":"trace[876110053] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"259.295495ms","start":"2026-04-02T00:59:55.619946Z","end":"2026-04-02T00:59:55.879242Z","steps":["trace[876110053] 'process raft request' (duration: 259.068224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.879398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.487705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4f8h2\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-02T00:59:55.87944Z","caller":"traceutil/trace.go:171","msg":"trace[573962710] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4f8h2; range_end:; response_count:1; response_revision:382; }","duration":"262.57209ms","start":"2026-04-02T00:59:55.616858Z","end":"2026-04-02T00:59:55.87943Z","steps":["trace[573962710] 'agreement among raft nodes before linearized reading' (duration: 262.431242ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.879543Z","caller":"traceutil/trace.go:171","msg":"trace[1380043887] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"253.559837ms","start":"2026-04-02T00:59:55.625924Z","end":"2026-04-02T00:59:55.879484Z","steps":["trace[1380043887] 'process raft request' (duration: 253.473053ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.879631Z","caller":"traceutil/trace.go:171","msg":"trace[1457461466] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"258.541408ms","start":"2026-04-02T00:59:55.621078Z","end":"2026-04-02T00:59:55.879619Z","steps":["trace[1457461466] 'process raft request' (duration: 258.082694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.879648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.268676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4f8h2\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-02T00:59:55.879689Z","caller":"traceutil/trace.go:171","msg":"trace[1068504972] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"257.833412ms","start":"2026-04-02T00:59:55.621846Z","end":"2026-04-02T00:59:55.879679Z","steps":["trace[1068504972] 'process raft request' (duration: 257.502695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:55.879737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.471591ms","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-02T00:59:55.87977Z","caller":"traceutil/trace.go:171","msg":"trace[2042250927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"242.531233ms","start":"2026-04-02T00:59:55.637231Z","end":"2026-04-02T00:59:55.879763Z","steps":["trace[2042250927] 'agreement among raft nodes before linearized reading' (duration: 242.481771ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:55.879694Z","caller":"traceutil/trace.go:171","msg":"trace[1653388719] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4f8h2; range_end:; response_count:1; response_revision:384; }","duration":"261.332298ms","start":"2026-04-02T00:59:55.618351Z","end":"2026-04-02T00:59:55.879683Z","steps":["trace[1653388719] 'agreement among raft nodes before linearized reading' (duration: 261.198172ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.369827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.428743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:56.370181Z","caller":"traceutil/trace.go:171","msg":"trace[1041959643] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"484.934137ms","start":"2026-04-02T00:59:55.885212Z","end":"2026-04-02T00:59:56.370146Z","steps":["trace[1041959643] 'process raft request' (duration: 304.058202ms)","trace[1041959643] 'compare' (duration: 180.218931ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:56.370419Z","caller":"traceutil/trace.go:171","msg":"trace[1601775561] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"482.634422ms","start":"2026-04-02T00:59:55.887754Z","end":"2026-04-02T00:59:56.370388Z","steps":["trace[1601775561] 'process raft request' (duration: 482.490995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.371673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.885174Z","time spent":"486.39409ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T00:59:56.37184Z","caller":"traceutil/trace.go:171","msg":"trace[1060390550] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:399; }","duration":"201.177846ms","start":"2026-04-02T00:59:56.170586Z","end":"2026-04-02T00:59:56.371764Z","steps":["trace[1060390550] 'read index received' (duration: 18.819377ms)","trace[1060390550] 'applied index is now lower than readState.Index' (duration: 182.265424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:56.372129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.887746Z","time spent":"483.97034ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:56.372389Z","caller":"traceutil/trace.go:171","msg":"trace[726356973] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"390.4225ms","start":"2026-04-02T00:59:55.981936Z","end":"2026-04-02T00:59:56.372359Z","steps":["trace[726356973] 'process raft request' (duration: 388.373297ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:56.370276Z","caller":"traceutil/trace.go:171","msg":"trace[169611356] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"484.820752ms","start":"2026-04-02T00:59:55.885394Z","end":"2026-04-02T00:59:56.370215Z","steps":["trace[169611356] 'process raft request' (duration: 484.58613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.372771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.981928Z","time spent":"390.495753ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T00:59:56.372884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.867385ms","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-02T00:59:56.372959Z","caller":"traceutil/trace.go:171","msg":"trace[1342902884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:391; }","duration":"148.979661ms","start":"2026-04-02T00:59:56.223964Z","end":"2026-04-02T00:59:56.372943Z","steps":["trace[1342902884] 'agreement among raft nodes before linearized reading' (duration: 148.839964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.373188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.092493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T00:59:56.373249Z","caller":"traceutil/trace.go:171","msg":"trace[532757993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"203.173477ms","start":"2026-04-02T00:59:56.170043Z","end":"2026-04-02T00:59:56.373216Z","steps":["trace[532757993] 'agreement among raft nodes before linearized reading' (duration: 203.099493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.373322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.023901ms","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-02T00:59:56.37338Z","caller":"traceutil/trace.go:171","msg":"trace[2081006653] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"141.112027ms","start":"2026-04-02T00:59:56.232244Z","end":"2026-04-02T00:59:56.373356Z","steps":["trace[2081006653] 'agreement among raft nodes before linearized reading' (duration: 140.97097ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.373607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.885383Z","time spent":"488.15879ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T00:59:56.373686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.726345ms","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-02T00:59:56.373917Z","caller":"traceutil/trace.go:171","msg":"trace[1543633061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"139.978419ms","start":"2026-04-02T00:59:56.233923Z","end":"2026-04-02T00:59:56.373902Z","steps":["trace[1543633061] 'agreement among raft nodes before linearized reading' (duration: 139.704195ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:56.370368Z","caller":"traceutil/trace.go:171","msg":"trace[577736206] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"484.755288ms","start":"2026-04-02T00:59:55.885592Z","end":"2026-04-02T00:59:56.370347Z","steps":["trace[577736206] 'process raft request' (duration: 484.508416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.374103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.885585Z","time spent":"488.452535ms","remote":"127.0.0.1:54650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:56.370418Z","caller":"traceutil/trace.go:171","msg":"trace[2055487535] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"483.58038ms","start":"2026-04-02T00:59:55.886803Z","end":"2026-04-02T00:59:56.370384Z","steps":["trace[2055487535] 'process raft request' (duration: 483.38377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:56.374205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:55.886787Z","time spent":"487.365ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T00:59:56.605046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.02682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:56.605333Z","caller":"traceutil/trace.go:171","msg":"trace[1123366166] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"213.023031ms","start":"2026-04-02T00:59:56.39224Z","end":"2026-04-02T00:59:56.605263Z","steps":["trace[1123366166] 'process raft request' (duration: 108.674634ms)","trace[1123366166] 'compare' (duration: 103.906944ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:56.60551Z","caller":"traceutil/trace.go:171","msg":"trace[1144914541] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:405; }","duration":"211.088305ms","start":"2026-04-02T00:59:56.394403Z","end":"2026-04-02T00:59:56.605491Z","steps":["trace[1144914541] 'read index received' (duration: 106.520246ms)","trace[1144914541] 'applied index is now lower than readState.Index' (duration: 104.567349ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:56.605614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.20339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-7c96b6546b\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-04-02T00:59:56.605603Z","caller":"traceutil/trace.go:171","msg":"trace[1336117705] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"213.065663ms","start":"2026-04-02T00:59:56.392511Z","end":"2026-04-02T00:59:56.605577Z","steps":["trace[1336117705] 'process raft request' (duration: 212.888394ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:56.605644Z","caller":"traceutil/trace.go:171","msg":"trace[1656768681] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-7c96b6546b; range_end:; response_count:1; response_revision:395; }","duration":"211.260893ms","start":"2026-04-02T00:59:56.394371Z","end":"2026-04-02T00:59:56.605632Z","steps":["trace[1656768681] 'agreement among raft nodes before linearized reading' (duration: 211.176358ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:56.605626Z","caller":"traceutil/trace.go:171","msg":"trace[1471724937] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"204.156746ms","start":"2026-04-02T00:59:56.401437Z","end":"2026-04-02T00:59:56.605594Z","steps":["trace[1471724937] 'process raft request' (duration: 203.999048ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:56.605914Z","caller":"traceutil/trace.go:171","msg":"trace[2079761610] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"212.9921ms","start":"2026-04-02T00:59:56.392497Z","end":"2026-04-02T00:59:56.605489Z","steps":["trace[2079761610] 'process raft request' (duration: 212.839892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:57.010915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.665291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:57.011044Z","caller":"traceutil/trace.go:171","msg":"trace[2054545372] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"395.596881ms","start":"2026-04-02T00:59:56.615425Z","end":"2026-04-02T00:59:57.011021Z","steps":["trace[2054545372] 'process raft request' (duration: 193.730721ms)","trace[2054545372] 'compare' (duration: 201.527513ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:57.011318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:56.615363Z","time spent":"395.916098ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:57.043248Z","caller":"traceutil/trace.go:171","msg":"trace[1138725949] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"426.794211ms","start":"2026-04-02T00:59:56.616429Z","end":"2026-04-02T00:59:57.043224Z","steps":["trace[1138725949] 'process raft request' (duration: 426.628323ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:57.043464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:56.616411Z","time spent":"426.935618ms","remote":"127.0.0.1:54650","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T00:59:57.560098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.320287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:57.560274Z","caller":"traceutil/trace.go:171","msg":"trace[1326769041] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"224.413474ms","start":"2026-04-02T00:59:57.33582Z","end":"2026-04-02T00:59:57.560234Z","steps":["trace[1326769041] 'read index received' (duration: 34.76218ms)","trace[1326769041] 'applied index is now lower than readState.Index' (duration: 189.649774ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:57.560325Z","caller":"traceutil/trace.go:171","msg":"trace[421253809] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"508.858671ms","start":"2026-04-02T00:59:57.051408Z","end":"2026-04-02T00:59:57.560266Z","steps":["trace[421253809] 'process raft request' (duration: 319.231657ms)","trace[421253809] 'compare' (duration: 189.142159ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:57.56036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.536471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T00:59:57.56049Z","caller":"traceutil/trace.go:171","msg":"trace[1178251799] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:398; }","duration":"224.688939ms","start":"2026-04-02T00:59:57.335786Z","end":"2026-04-02T00:59:57.560475Z","steps":["trace[1178251799] 'agreement among raft nodes before linearized reading' (duration: 224.522181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:57.560558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:57.051363Z","time spent":"509.068531ms","remote":"127.0.0.1:54628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T00:59:57.949534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.242838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:57.949655Z","caller":"traceutil/trace.go:171","msg":"trace[664609524] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"347.876478ms","start":"2026-04-02T00:59:57.60176Z","end":"2026-04-02T00:59:57.949636Z","steps":["trace[664609524] 'read index received' (duration: 116.342496ms)","trace[664609524] 'applied index is now lower than readState.Index' (duration: 231.532482ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T00:59:57.949767Z","caller":"traceutil/trace.go:171","msg":"trace[424072728] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"348.958633ms","start":"2026-04-02T00:59:57.600718Z","end":"2026-04-02T00:59:57.949677Z","steps":["trace[424072728] 'process raft request' (duration: 117.496774ms)","trace[424072728] 'compare' (duration: 231.09878ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:57.950017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:57.600705Z","time spent":"349.175133ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":777,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T00:59:57.950059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.249768ms","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-02T00:59:57.950182Z","caller":"traceutil/trace.go:171","msg":"trace[1587106114] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:400; }","duration":"348.434577ms","start":"2026-04-02T00:59:57.601716Z","end":"2026-04-02T00:59:57.950151Z","steps":["trace[1587106114] 'agreement among raft nodes before linearized reading' (duration: 348.081929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:57.950253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:57.601696Z","time spent":"348.544972ms","remote":"127.0.0.1:54432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-04-02T00:59:59.05151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.387167ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T00:59:59.05158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:58.381483Z","time spent":"670.090519ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-02T00:59:59.051637Z","caller":"traceutil/trace.go:171","msg":"trace[799184093] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:402; }","duration":"326.540645ms","start":"2026-04-02T00:59:58.72508Z","end":"2026-04-02T00:59:59.051621Z","steps":["trace[799184093] 'range keys from in-memory index tree' (duration: 326.367366ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:59.090825Z","caller":"traceutil/trace.go:171","msg":"trace[320458938] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:417; }","duration":"481.553027ms","start":"2026-04-02T00:59:58.609246Z","end":"2026-04-02T00:59:59.090799Z","steps":["trace[320458938] 'read index received' (duration: 390.747896ms)","trace[320458938] 'applied index is now lower than readState.Index' (duration: 90.804141ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:59.091169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.868314ms","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-02T00:59:59.091243Z","caller":"traceutil/trace.go:171","msg":"trace[1423590264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:403; }","duration":"482.000583ms","start":"2026-04-02T00:59:58.60923Z","end":"2026-04-02T00:59:59.091231Z","steps":["trace[1423590264] 'agreement among raft nodes before linearized reading' (duration: 481.661726ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:59.090863Z","caller":"traceutil/trace.go:171","msg":"trace[616576988] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"685.81931ms","start":"2026-04-02T00:59:58.405016Z","end":"2026-04-02T00:59:59.090836Z","steps":["trace[616576988] 'process raft request' (duration: 685.610832ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:59.091288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:58.609174Z","time spent":"482.104275ms","remote":"127.0.0.1:54428","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-02T00:59:59.091494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:58.404988Z","time spent":"686.383155ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T00:59:59.685031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.005663ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T00:59:59.685159Z","caller":"traceutil/trace.go:171","msg":"trace[83035179] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"589.05453ms","start":"2026-04-02T00:59:59.096098Z","end":"2026-04-02T00:59:59.685153Z","steps":["trace[83035179] 'process raft request' (duration: 589.00361ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:59.685198Z","caller":"traceutil/trace.go:171","msg":"trace[1869148208] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"594.225627ms","start":"2026-04-02T00:59:59.090964Z","end":"2026-04-02T00:59:59.685189Z","steps":["trace[1869148208] 'read index received' (duration: 119.058536ms)","trace[1869148208] 'applied index is now lower than readState.Index' (duration: 475.165791ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:59.685222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:59.096066Z","time spent":"589.125599ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T00:59:59.685146Z","caller":"traceutil/trace.go:171","msg":"trace[694249988] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"632.292214ms","start":"2026-04-02T00:59:59.052831Z","end":"2026-04-02T00:59:59.685123Z","steps":["trace[694249988] 'process raft request' (duration: 157.141653ms)","trace[694249988] 'compare' (duration: 474.904484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T00:59:59.68532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074564847s","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-02T00:59:59.685345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:59.052805Z","time spent":"632.530311ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T00:59:59.685348Z","caller":"traceutil/trace.go:171","msg":"trace[985346521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"1.074640809s","start":"2026-04-02T00:59:58.610698Z","end":"2026-04-02T00:59:59.685339Z","steps":["trace[985346521] 'agreement among raft nodes before linearized reading' (duration: 1.07456148s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:59.685376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:58.610689Z","time spent":"1.074680648s","remote":"127.0.0.1:54428","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-02T00:59:59.685443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.389623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T00:59:59.685552Z","caller":"traceutil/trace.go:171","msg":"trace[861063959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:405; }","duration":"521.565912ms","start":"2026-04-02T00:59:59.163967Z","end":"2026-04-02T00:59:59.685533Z","steps":["trace[861063959] 'agreement among raft nodes before linearized reading' (duration: 521.327024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:59.685537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.075208994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-04-02T00:59:59.685499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.390473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-4f8h2\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-04-02T00:59:59.685637Z","caller":"traceutil/trace.go:171","msg":"trace[537219318] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"1.075339464s","start":"2026-04-02T00:59:58.610277Z","end":"2026-04-02T00:59:59.685617Z","steps":["trace[537219318] 'agreement among raft nodes before linearized reading' (duration: 1.075193915s)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:59.685656Z","caller":"traceutil/trace.go:171","msg":"trace[1233901522] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-4f8h2; range_end:; response_count:1; response_revision:405; }","duration":"521.553002ms","start":"2026-04-02T00:59:59.164084Z","end":"2026-04-02T00:59:59.685637Z","steps":["trace[1233901522] 'agreement among raft nodes before linearized reading' (duration: 521.393393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T00:59:59.685684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:58.610265Z","time spent":"1.075410444s","remote":"127.0.0.1:54428","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-02T00:59:59.685652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:59.163952Z","time spent":"521.687291ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T00:59:59.685712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T00:59:59.164072Z","time spent":"521.622541ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4490,"request content":"key:\"/registry/pods/kube-system/kube-proxy-4f8h2\" "} {"level":"info","ts":"2026-04-02T00:59:59.820256Z","caller":"traceutil/trace.go:171","msg":"trace[272713503] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"124.110136ms","start":"2026-04-02T00:59:59.696126Z","end":"2026-04-02T00:59:59.820236Z","steps":["trace[272713503] 'process raft request' (duration: 124.051267ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T00:59:59.82032Z","caller":"traceutil/trace.go:171","msg":"trace[1349320613] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"129.316813ms","start":"2026-04-02T00:59:59.690973Z","end":"2026-04-02T00:59:59.820289Z","steps":["trace[1349320613] 'process raft request' (duration: 128.461562ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:00.524623Z","caller":"traceutil/trace.go:171","msg":"trace[1560072198] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"138.475551ms","start":"2026-04-02T01:00:00.386125Z","end":"2026-04-02T01:00:00.5246Z","steps":["trace[1560072198] 'process raft request' (duration: 138.268313ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:01.796168Z","caller":"traceutil/trace.go:171","msg":"trace[750822146] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"103.300013ms","start":"2026-04-02T01:00:01.692842Z","end":"2026-04-02T01:00:01.796142Z","steps":["trace[750822146] 'process raft request' (duration: 103.140764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:02.279445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.990506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:02.279548Z","caller":"traceutil/trace.go:171","msg":"trace[1058875414] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"454.211697ms","start":"2026-04-02T01:00:01.825321Z","end":"2026-04-02T01:00:02.279532Z","steps":["trace[1058875414] 'read index received' (duration: 126.004932ms)","trace[1058875414] 'applied index is now lower than readState.Index' (duration: 328.205505ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:02.279659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.344776ms","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-02T01:00:02.279641Z","caller":"traceutil/trace.go:171","msg":"trace[48752343] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"479.363356ms","start":"2026-04-02T01:00:01.800211Z","end":"2026-04-02T01:00:02.279575Z","steps":["trace[48752343] 'process raft request' (duration: 151.162552ms)","trace[48752343] 'compare' (duration: 327.839237ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:02.279708Z","caller":"traceutil/trace.go:171","msg":"trace[438932791] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"454.420925ms","start":"2026-04-02T01:00:01.825264Z","end":"2026-04-02T01:00:02.279685Z","steps":["trace[438932791] 'agreement among raft nodes before linearized reading' (duration: 454.355176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:02.279754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:01.825233Z","time spent":"454.507915ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T01:00:02.279789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.128814ms","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-02T01:00:02.279842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:01.800193Z","time spent":"479.541064ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:02.279867Z","caller":"traceutil/trace.go:171","msg":"trace[1245766261] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"203.249942ms","start":"2026-04-02T01:00:02.076596Z","end":"2026-04-02T01:00:02.279845Z","steps":["trace[1245766261] 'agreement among raft nodes before linearized reading' (duration: 203.129673ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:04.432096Z","caller":"traceutil/trace.go:171","msg":"trace[541976384] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"104.559781ms","start":"2026-04-02T01:00:04.327514Z","end":"2026-04-02T01:00:04.432074Z","steps":["trace[541976384] 'read index received' (duration: 104.392132ms)","trace[541976384] 'applied index is now lower than readState.Index' (duration: 166.359µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:04.432256Z","caller":"traceutil/trace.go:171","msg":"trace[1361970162] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"143.529589ms","start":"2026-04-02T01:00:04.288679Z","end":"2026-04-02T01:00:04.432209Z","steps":["trace[1361970162] 'process raft request' (duration: 143.236391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:04.432315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.786089ms","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-02T01:00:04.43254Z","caller":"traceutil/trace.go:171","msg":"trace[251436668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:417; }","duration":"105.077167ms","start":"2026-04-02T01:00:04.327448Z","end":"2026-04-02T01:00:04.432525Z","steps":["trace[251436668] 'agreement among raft nodes before linearized reading' (duration: 104.795738ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.116725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:08.854196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.570747ms","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-02T01:00:08.854272Z","caller":"traceutil/trace.go:171","msg":"trace[1705751042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"691.265084ms","start":"2026-04-02T01:00:08.162988Z","end":"2026-04-02T01:00:08.854253Z","steps":["trace[1705751042] 'range keys from in-memory index tree' (duration: 691.045356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.162969Z","time spent":"691.338484ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T01:00:08.854306Z","caller":"traceutil/trace.go:171","msg":"trace[884232830] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:426; }","duration":"128.742076ms","start":"2026-04-02T01:00:08.725545Z","end":"2026-04-02T01:00:08.854287Z","steps":["trace[884232830] 'range keys from in-memory index tree' (duration: 128.550957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.232843ms","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-02T01:00:08.854489Z","caller":"traceutil/trace.go:171","msg":"trace[779954968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"384.460962ms","start":"2026-04-02T01:00:08.470003Z","end":"2026-04-02T01:00:08.854464Z","steps":["trace[779954968] 'range keys from in-memory index tree' (duration: 384.106343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.400222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T01:00:08.854578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.469981Z","time spent":"384.561991ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T01:00:08.854622Z","caller":"traceutil/trace.go:171","msg":"trace[802123703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"405.592291ms","start":"2026-04-02T01:00:08.449Z","end":"2026-04-02T01:00:08.854592Z","steps":["trace[802123703] 'range keys from in-memory index tree' (duration: 405.235993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.863764ms","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-02T01:00:08.854672Z","caller":"traceutil/trace.go:171","msg":"trace[1333480873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"358.899594ms","start":"2026-04-02T01:00:08.495764Z","end":"2026-04-02T01:00:08.854664Z","steps":["trace[1333480873] 'range keys from in-memory index tree' (duration: 358.660135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:08.854683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.44898Z","time spent":"405.692041ms","remote":"127.0.0.1:54428","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-02T01:00:08.854715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.495723Z","time spent":"358.984214ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T01:00:09.664579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717239998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T01:00:10.165272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717239998,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T01:00:10.278529Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419908396s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T01:00:10.27892Z","caller":"traceutil/trace.go:171","msg":"trace[1784196573] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.420411665s","start":"2026-04-02T01:00:08.858481Z","end":"2026-04-02T01:00:10.278892Z","steps":["trace[1784196573] 'process raft request' (duration: 1.420268875s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:10.27911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.858465Z","time spent":"1.420508894s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:10.999725Z","caller":"traceutil/trace.go:171","msg":"trace[1611830705] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:444; }","duration":"1.836406765s","start":"2026-04-02T01:00:09.163297Z","end":"2026-04-02T01:00:10.999704Z","steps":["trace[1611830705] 'read index received' (duration: 1.115457468s)","trace[1611830705] 'applied index is now lower than readState.Index' (duration: 720.948337ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:10.999791Z","caller":"traceutil/trace.go:171","msg":"trace[322114009] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"2.140337595s","start":"2026-04-02T01:00:08.859419Z","end":"2026-04-02T01:00:10.999756Z","steps":["trace[322114009] 'process raft request' (duration: 2.140070976s)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:10.999769Z","caller":"traceutil/trace.go:171","msg":"trace[2083113185] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"2.140071786s","start":"2026-04-02T01:00:08.859684Z","end":"2026-04-02T01:00:10.999755Z","steps":["trace[2083113185] 'process raft request' (duration: 2.139953696s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:10.999937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.836587955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:11Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.859675Z","time spent":"2.140243186s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:11.000033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:08.859399Z","time spent":"2.140474645s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:11.000069Z","caller":"traceutil/trace.go:171","msg":"trace[1147017004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"1.836779526s","start":"2026-04-02T01:00:09.163262Z","end":"2026-04-02T01:00:11.000042Z","steps":["trace[1147017004] 'agreement among raft nodes before linearized reading' (duration: 1.836539156s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:11.000147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:09.163248Z","time spent":"1.836878676s","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T01:00:11.070606Z","caller":"traceutil/trace.go:171","msg":"trace[1306073613] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"487.910741ms","start":"2026-04-02T01:00:10.582671Z","end":"2026-04-02T01:00:11.070582Z","steps":["trace[1306073613] 'process raft request' (duration: 487.712921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:11.070763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:10.582644Z","time spent":"488.048581ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:11.070829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167278521s","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-02T01:00:11.070935Z","caller":"traceutil/trace.go:171","msg":"trace[1646029030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:430; }","duration":"1.167449291s","start":"2026-04-02T01:00:09.90347Z","end":"2026-04-02T01:00:11.070919Z","steps":["trace[1646029030] 'agreement among raft nodes before linearized reading' (duration: 1.167125461s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:11.070978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:09.903453Z","time spent":"1.167514972s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T01:00:11.61068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.294825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:11.611038Z","caller":"traceutil/trace.go:171","msg":"trace[1041754520] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"529.697528ms","start":"2026-04-02T01:00:11.081317Z","end":"2026-04-02T01:00:11.611014Z","steps":["trace[1041754520] 'read index received' (duration: 154.961213ms)","trace[1041754520] 'applied index is now lower than readState.Index' (duration: 374.734165ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:11.611053Z","caller":"traceutil/trace.go:171","msg":"trace[1688227255] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"535.357615ms","start":"2026-04-02T01:00:11.075655Z","end":"2026-04-02T01:00:11.611012Z","steps":["trace[1688227255] 'process raft request' (duration: 160.62446ms)","trace[1688227255] 'compare' (duration: 374.159536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:11.6112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.878168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:11.611296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:11.075632Z","time spent":"535.533925ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:11.611342Z","caller":"traceutil/trace.go:171","msg":"trace[2080323656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:431; }","duration":"529.960348ms","start":"2026-04-02T01:00:11.081288Z","end":"2026-04-02T01:00:11.611248Z","steps":["trace[2080323656] 'agreement among raft nodes before linearized reading' (duration: 529.862518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:11.6114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:11.081279Z","time spent":"530.104748ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T01:00:16.904685Z","caller":"traceutil/trace.go:171","msg":"trace[1788292485] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"165.050576ms","start":"2026-04-02T01:00:16.739605Z","end":"2026-04-02T01:00:16.904656Z","steps":["trace[1788292485] 'process raft request' (duration: 162.673209ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:21.149486Z","caller":"traceutil/trace.go:171","msg":"trace[842510744] linearizableReadLoop","detail":"{readStateIndex:502; appliedIndex:501; }","duration":"101.006227ms","start":"2026-04-02T01:00:21.048421Z","end":"2026-04-02T01:00:21.149428Z","steps":["trace[842510744] 'read index received' (duration: 100.782456ms)","trace[842510744] 'applied index is now lower than readState.Index' (duration: 219.871µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:21.149571Z","caller":"traceutil/trace.go:171","msg":"trace[2125101035] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"101.269859ms","start":"2026-04-02T01:00:21.048274Z","end":"2026-04-02T01:00:21.149544Z","steps":["trace[2125101035] 'process raft request' (duration: 100.974767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:21.149642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.195188ms","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-02T01:00:21.149687Z","caller":"traceutil/trace.go:171","msg":"trace[1232172913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"101.275628ms","start":"2026-04-02T01:00:21.048396Z","end":"2026-04-02T01:00:21.149672Z","steps":["trace[1232172913] 'agreement among raft nodes before linearized reading' (duration: 101.164068ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:21.235077Z","caller":"traceutil/trace.go:171","msg":"trace[1109218711] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"130.795466ms","start":"2026-04-02T01:00:21.104261Z","end":"2026-04-02T01:00:21.235056Z","steps":["trace[1109218711] 'process raft request' (duration: 79.94529ms)","trace[1109218711] 'compare' (duration: 50.682195ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:24.465193Z","caller":"traceutil/trace.go:171","msg":"trace[1090184649] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"148.355368ms","start":"2026-04-02T01:00:24.316782Z","end":"2026-04-02T01:00:24.465137Z","steps":["trace[1090184649] 'process raft request' (duration: 111.41698ms)","trace[1090184649] 'compare' (duration: 36.703276ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:24.465214Z","caller":"traceutil/trace.go:171","msg":"trace[2035450570] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"148.304518ms","start":"2026-04-02T01:00:24.316882Z","end":"2026-04-02T01:00:24.465186Z","steps":["trace[2035450570] 'process raft request' (duration: 148.193337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:24.798991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.033105ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:24.799209Z","caller":"traceutil/trace.go:171","msg":"trace[748460045] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"251.246661ms","start":"2026-04-02T01:00:24.54791Z","end":"2026-04-02T01:00:24.799156Z","steps":["trace[748460045] 'process raft request' (duration: 29.971954ms)","trace[748460045] 'compare' (duration: 220.902875ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:24.799258Z","caller":"traceutil/trace.go:171","msg":"trace[121064687] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"250.930198ms","start":"2026-04-02T01:00:24.548304Z","end":"2026-04-02T01:00:24.799234Z","steps":["trace[121064687] 'process raft request' (duration: 250.792667ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.111738Z","caller":"traceutil/trace.go:171","msg":"trace[1268960695] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"144.486722ms","start":"2026-04-02T01:00:24.967216Z","end":"2026-04-02T01:00:25.111702Z","steps":["trace[1268960695] 'process raft request' (duration: 143.15554ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.111789Z","caller":"traceutil/trace.go:171","msg":"trace[1837565112] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"144.26876ms","start":"2026-04-02T01:00:24.967486Z","end":"2026-04-02T01:00:25.111755Z","steps":["trace[1837565112] 'process raft request' (duration: 144.058138ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.112048Z","caller":"traceutil/trace.go:171","msg":"trace[171147515] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"143.223961ms","start":"2026-04-02T01:00:24.968791Z","end":"2026-04-02T01:00:25.112015Z","steps":["trace[171147515] 'process raft request' (duration: 142.833808ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.113138Z","caller":"traceutil/trace.go:171","msg":"trace[1910305174] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"124.597806ms","start":"2026-04-02T01:00:24.988517Z","end":"2026-04-02T01:00:25.113115Z","steps":["trace[1910305174] 'process raft request' (duration: 123.292305ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.138469Z","caller":"traceutil/trace.go:171","msg":"trace[1291234926] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"128.941711ms","start":"2026-04-02T01:00:25.009505Z","end":"2026-04-02T01:00:25.138447Z","steps":["trace[1291234926] 'process raft request' (duration: 128.76225ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.138541Z","caller":"traceutil/trace.go:171","msg":"trace[830694201] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"124.658225ms","start":"2026-04-02T01:00:25.013852Z","end":"2026-04-02T01:00:25.13851Z","steps":["trace[830694201] 'process raft request' (duration: 124.521044ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.13849Z","caller":"traceutil/trace.go:171","msg":"trace[1484602460] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"106.372171ms","start":"2026-04-02T01:00:25.032107Z","end":"2026-04-02T01:00:25.13848Z","steps":["trace[1484602460] 'process raft request' (duration: 106.321011ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.334933Z","caller":"traceutil/trace.go:171","msg":"trace[1360213812] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"217.435103ms","start":"2026-04-02T01:00:25.117464Z","end":"2026-04-02T01:00:25.334899Z","steps":["trace[1360213812] 'process raft request' (duration: 150.235359ms)","trace[1360213812] 'compare' (duration: 66.932752ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:25.334987Z","caller":"traceutil/trace.go:171","msg":"trace[719041773] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"191.873318ms","start":"2026-04-02T01:00:25.143089Z","end":"2026-04-02T01:00:25.334962Z","steps":["trace[719041773] 'process raft request' (duration: 191.836478ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.335057Z","caller":"traceutil/trace.go:171","msg":"trace[233614657] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:530; }","duration":"214.620449ms","start":"2026-04-02T01:00:25.120423Z","end":"2026-04-02T01:00:25.335044Z","steps":["trace[233614657] 'read index received' (duration: 17.897849ms)","trace[233614657] 'applied index is now lower than readState.Index' (duration: 196.72086ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:25.335063Z","caller":"traceutil/trace.go:171","msg":"trace[1205972897] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"216.049192ms","start":"2026-04-02T01:00:25.118985Z","end":"2026-04-02T01:00:25.335034Z","steps":["trace[1205972897] 'process raft request' (duration: 215.893901ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.33497Z","caller":"traceutil/trace.go:171","msg":"trace[901970833] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"216.720837ms","start":"2026-04-02T01:00:25.118231Z","end":"2026-04-02T01:00:25.334952Z","steps":["trace[901970833] 'process raft request' (duration: 216.584336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.335285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.839961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"warn","ts":"2026-04-02T01:00:25.335331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.592397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4jggb\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-04-02T01:00:25.335354Z","caller":"traceutil/trace.go:171","msg":"trace[1441686046] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:515; }","duration":"214.951003ms","start":"2026-04-02T01:00:25.120389Z","end":"2026-04-02T01:00:25.33534Z","steps":["trace[1441686046] 'agreement among raft nodes before linearized reading' (duration: 214.766621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.335493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.588512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T01:00:25.335376Z","caller":"traceutil/trace.go:171","msg":"trace[1251155056] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4jggb; range_end:; response_count:1; response_revision:515; }","duration":"191.680339ms","start":"2026-04-02T01:00:25.143683Z","end":"2026-04-02T01:00:25.335363Z","steps":["trace[1251155056] 'agreement among raft nodes before linearized reading' (duration: 191.476126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.335571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.736066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:25.33561Z","caller":"traceutil/trace.go:171","msg":"trace[1891609898] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"174.775416ms","start":"2026-04-02T01:00:25.160826Z","end":"2026-04-02T01:00:25.335601Z","steps":["trace[1891609898] 'agreement among raft nodes before linearized reading' (duration: 174.721816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.335567Z","caller":"traceutil/trace.go:171","msg":"trace[1814232200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:515; }","duration":"163.683863ms","start":"2026-04-02T01:00:25.171862Z","end":"2026-04-02T01:00:25.335546Z","steps":["trace[1814232200] 'agreement among raft nodes before linearized reading' (duration: 163.547982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.335869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.333923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-04-02T01:00:25.336533Z","caller":"traceutil/trace.go:171","msg":"trace[71367786] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:515; }","duration":"187.036079ms","start":"2026-04-02T01:00:25.149488Z","end":"2026-04-02T01:00:25.336524Z","steps":["trace[71367786] 'agreement among raft nodes before linearized reading' (duration: 186.263713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.336097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.898023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-02T01:00:25.336679Z","caller":"traceutil/trace.go:171","msg":"trace[488617380] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:515; }","duration":"153.533508ms","start":"2026-04-02T01:00:25.183115Z","end":"2026-04-02T01:00:25.336649Z","steps":["trace[488617380] 'agreement among raft nodes before linearized reading' (duration: 152.478249ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:25.560371Z","caller":"traceutil/trace.go:171","msg":"trace[937983404] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"131.671034ms","start":"2026-04-02T01:00:25.42867Z","end":"2026-04-02T01:00:25.560341Z","steps":["trace[937983404] 'process raft request' (duration: 56.931437ms)","trace[937983404] 'compare' (duration: 74.621576ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:25.564702Z","caller":"traceutil/trace.go:171","msg":"trace[682797775] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"128.900672ms","start":"2026-04-02T01:00:25.435783Z","end":"2026-04-02T01:00:25.564684Z","steps":["trace[682797775] 'read index received' (duration: 49.897249ms)","trace[682797775] 'applied index is now lower than readState.Index' (duration: 79.002573ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:25.564788Z","caller":"traceutil/trace.go:171","msg":"trace[721022783] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"135.965139ms","start":"2026-04-02T01:00:25.4288Z","end":"2026-04-02T01:00:25.564766Z","steps":["trace[721022783] 'process raft request' (duration: 135.797228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.564897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.092593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-04-02T01:00:25.564934Z","caller":"traceutil/trace.go:171","msg":"trace[1266620564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:522; }","duration":"129.165253ms","start":"2026-04-02T01:00:25.435754Z","end":"2026-04-02T01:00:25.564919Z","steps":["trace[1266620564] 'agreement among raft nodes before linearized reading' (duration: 129.035922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:25.565414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.506282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-04-02T01:00:25.565488Z","caller":"traceutil/trace.go:171","msg":"trace[533711977] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:522; }","duration":"125.587273ms","start":"2026-04-02T01:00:25.439877Z","end":"2026-04-02T01:00:25.565464Z","steps":["trace[533711977] 'agreement among raft nodes before linearized reading' (duration: 125.054848ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.044409Z","caller":"traceutil/trace.go:171","msg":"trace[1990874843] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"262.0398ms","start":"2026-04-02T01:00:26.782347Z","end":"2026-04-02T01:00:27.044386Z","steps":["trace[1990874843] 'process raft request' (duration: 261.885339ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.06441Z","caller":"traceutil/trace.go:171","msg":"trace[118360620] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"280.593867ms","start":"2026-04-02T01:00:26.783791Z","end":"2026-04-02T01:00:27.064385Z","steps":["trace[118360620] 'process raft request' (duration: 280.434025ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064483Z","caller":"traceutil/trace.go:171","msg":"trace[1857985553] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"280.421425ms","start":"2026-04-02T01:00:26.784039Z","end":"2026-04-02T01:00:27.06446Z","steps":["trace[1857985553] 'process raft request' (duration: 280.294734ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064633Z","caller":"traceutil/trace.go:171","msg":"trace[1926227434] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"263.591835ms","start":"2026-04-02T01:00:26.801032Z","end":"2026-04-02T01:00:27.064623Z","steps":["trace[1926227434] 'process raft request' (duration: 263.340803ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064656Z","caller":"traceutil/trace.go:171","msg":"trace[979494334] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"262.441365ms","start":"2026-04-02T01:00:26.802186Z","end":"2026-04-02T01:00:27.064627Z","steps":["trace[979494334] 'process raft request' (duration: 262.223203ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064729Z","caller":"traceutil/trace.go:171","msg":"trace[1132571803] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"261.355144ms","start":"2026-04-02T01:00:26.803331Z","end":"2026-04-02T01:00:27.064686Z","steps":["trace[1132571803] 'process raft request' (duration: 261.169683ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064814Z","caller":"traceutil/trace.go:171","msg":"trace[1265096057] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"260.401006ms","start":"2026-04-02T01:00:26.804401Z","end":"2026-04-02T01:00:27.064802Z","steps":["trace[1265096057] 'process raft request' (duration: 260.186595ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064825Z","caller":"traceutil/trace.go:171","msg":"trace[1890381489] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"262.418705ms","start":"2026-04-02T01:00:26.802231Z","end":"2026-04-02T01:00:27.06465Z","steps":["trace[1890381489] 'process raft request' (duration: 262.217243ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.064991Z","caller":"traceutil/trace.go:171","msg":"trace[115627559] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"247.344859ms","start":"2026-04-02T01:00:26.817631Z","end":"2026-04-02T01:00:27.064976Z","steps":["trace[115627559] 'process raft request' (duration: 247.007426ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.065171Z","caller":"traceutil/trace.go:171","msg":"trace[1191075285] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"238.791793ms","start":"2026-04-02T01:00:26.826371Z","end":"2026-04-02T01:00:27.065162Z","steps":["trace[1191075285] 'process raft request' (duration: 238.610481ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.065389Z","caller":"traceutil/trace.go:171","msg":"trace[1405164540] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"226.910457ms","start":"2026-04-02T01:00:26.838467Z","end":"2026-04-02T01:00:27.065377Z","steps":["trace[1405164540] 'process raft request' (duration: 226.590744ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.065574Z","caller":"traceutil/trace.go:171","msg":"trace[1250818065] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"113.45341ms","start":"2026-04-02T01:00:26.952109Z","end":"2026-04-02T01:00:27.065563Z","steps":["trace[1250818065] 'process raft request' (duration: 113.154418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:27.383808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.519071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:27.384528Z","caller":"traceutil/trace.go:171","msg":"trace[1803094686] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"278.37962ms","start":"2026-04-02T01:00:27.106129Z","end":"2026-04-02T01:00:27.384509Z","steps":["trace[1803094686] 'read index received' (duration: 143.035102ms)","trace[1803094686] 'applied index is now lower than readState.Index' (duration: 135.343078ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:27.384598Z","caller":"traceutil/trace.go:171","msg":"trace[156140492] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"287.677109ms","start":"2026-04-02T01:00:27.09691Z","end":"2026-04-02T01:00:27.384587Z","steps":["trace[156140492] 'process raft request' (duration: 152.2422ms)","trace[156140492] 'compare' (duration: 134.359699ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:27.384903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.744693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-04-02T01:00:27.38499Z","caller":"traceutil/trace.go:171","msg":"trace[857235978] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"278.875904ms","start":"2026-04-02T01:00:27.106094Z","end":"2026-04-02T01:00:27.38497Z","steps":["trace[857235978] 'agreement among raft nodes before linearized reading' (duration: 278.623421ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:27.387287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.947131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:27.387305Z","caller":"traceutil/trace.go:171","msg":"trace[1731204978] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"205.996171ms","start":"2026-04-02T01:00:27.181289Z","end":"2026-04-02T01:00:27.387285Z","steps":["trace[1731204978] 'process raft request' (duration: 205.777959ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.387335Z","caller":"traceutil/trace.go:171","msg":"trace[1374848580] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"275.254879ms","start":"2026-04-02T01:00:27.112073Z","end":"2026-04-02T01:00:27.387328Z","steps":["trace[1374848580] 'process raft request' (duration: 274.605903ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.387348Z","caller":"traceutil/trace.go:171","msg":"trace[943971106] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"226.029851ms","start":"2026-04-02T01:00:27.161301Z","end":"2026-04-02T01:00:27.387331Z","steps":["trace[943971106] 'agreement among raft nodes before linearized reading' (duration: 225.932031ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.387421Z","caller":"traceutil/trace.go:171","msg":"trace[231226154] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"237.229218ms","start":"2026-04-02T01:00:27.150183Z","end":"2026-04-02T01:00:27.387412Z","steps":["trace[231226154] 'process raft request' (duration: 236.825884ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.387492Z","caller":"traceutil/trace.go:171","msg":"trace[438404873] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"239.464659ms","start":"2026-04-02T01:00:27.148019Z","end":"2026-04-02T01:00:27.387484Z","steps":["trace[438404873] 'process raft request' (duration: 238.808493ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.387284Z","caller":"traceutil/trace.go:171","msg":"trace[1332534113] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"238.988735ms","start":"2026-04-02T01:00:27.148263Z","end":"2026-04-02T01:00:27.387252Z","steps":["trace[1332534113] 'process raft request' (duration: 238.664622ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.594192Z","caller":"traceutil/trace.go:171","msg":"trace[725682474] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"123.001031ms","start":"2026-04-02T01:00:27.471174Z","end":"2026-04-02T01:00:27.594175Z","steps":["trace[725682474] 'read index received' (duration: 11.312437ms)","trace[725682474] 'applied index is now lower than readState.Index' (duration: 111.687744ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:27.594369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.163172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T01:00:27.59439Z","caller":"traceutil/trace.go:171","msg":"trace[893533385] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:567; }","duration":"123.234654ms","start":"2026-04-02T01:00:27.47115Z","end":"2026-04-02T01:00:27.594385Z","steps":["trace[893533385] 'agreement among raft nodes before linearized reading' (duration: 123.091852ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.594617Z","caller":"traceutil/trace.go:171","msg":"trace[133528585] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"114.063266ms","start":"2026-04-02T01:00:27.480549Z","end":"2026-04-02T01:00:27.594612Z","steps":["trace[133528585] 'process raft request' (duration: 113.498791ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.594652Z","caller":"traceutil/trace.go:171","msg":"trace[947196786] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"124.813949ms","start":"2026-04-02T01:00:27.469819Z","end":"2026-04-02T01:00:27.594633Z","steps":["trace[947196786] 'process raft request' (duration: 44.546174ms)","trace[947196786] 'compare' (duration: 79.530218ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:27.789561Z","caller":"traceutil/trace.go:171","msg":"trace[1927064077] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"114.961624ms","start":"2026-04-02T01:00:27.67457Z","end":"2026-04-02T01:00:27.789531Z","steps":["trace[1927064077] 'process raft request' (duration: 101.457316ms)","trace[1927064077] 'compare' (duration: 13.317157ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:27.789737Z","caller":"traceutil/trace.go:171","msg":"trace[99052523] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"103.245843ms","start":"2026-04-02T01:00:27.686481Z","end":"2026-04-02T01:00:27.789727Z","steps":["trace[99052523] 'process raft request' (duration: 102.977811ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:27.941209Z","caller":"traceutil/trace.go:171","msg":"trace[1845941684] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"134.178977ms","start":"2026-04-02T01:00:27.807009Z","end":"2026-04-02T01:00:27.941188Z","steps":["trace[1845941684] 'process raft request' (duration: 113.214427ms)","trace[1845941684] 'compare' (duration: 20.833209ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:28.187309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.708776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:28.187472Z","caller":"traceutil/trace.go:171","msg":"trace[324679553] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"198.781744ms","start":"2026-04-02T01:00:27.988672Z","end":"2026-04-02T01:00:28.187454Z","steps":["trace[324679553] 'process raft request' (duration: 84.859356ms)","trace[324679553] 'compare' (duration: 113.574845ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:28.192775Z","caller":"traceutil/trace.go:171","msg":"trace[579978059] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"102.15483ms","start":"2026-04-02T01:00:28.09061Z","end":"2026-04-02T01:00:28.192765Z","steps":["trace[579978059] 'process raft request' (duration: 101.415782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:28.431504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.543665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:28.431609Z","caller":"traceutil/trace.go:171","msg":"trace[1160177618] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"223.811224ms","start":"2026-04-02T01:00:28.20778Z","end":"2026-04-02T01:00:28.431591Z","steps":["trace[1160177618] 'process raft request' (duration: 95.097537ms)","trace[1160177618] 'compare' (duration: 128.276593ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:28.485303Z","caller":"traceutil/trace.go:171","msg":"trace[1963869143] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"192.58183ms","start":"2026-04-02T01:00:28.29269Z","end":"2026-04-02T01:00:28.485272Z","steps":["trace[1963869143] 'process raft request' (duration: 192.52224ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:28.485373Z","caller":"traceutil/trace.go:171","msg":"trace[172862388] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"270.850018ms","start":"2026-04-02T01:00:28.214485Z","end":"2026-04-02T01:00:28.485335Z","steps":["trace[172862388] 'process raft request' (duration: 270.522025ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:28.73587Z","caller":"traceutil/trace.go:171","msg":"trace[893400534] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"241.733425ms","start":"2026-04-02T01:00:28.494109Z","end":"2026-04-02T01:00:28.735842Z","steps":["trace[893400534] 'process raft request' (duration: 189.832163ms)","trace[893400534] 'compare' (duration: 51.71583ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:28.737816Z","caller":"traceutil/trace.go:171","msg":"trace[1196169672] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"233.231179ms","start":"2026-04-02T01:00:28.504555Z","end":"2026-04-02T01:00:28.737786Z","steps":["trace[1196169672] 'process raft request' (duration: 232.918186ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:28.737917Z","caller":"traceutil/trace.go:171","msg":"trace[1492426489] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"113.322291ms","start":"2026-04-02T01:00:28.624574Z","end":"2026-04-02T01:00:28.737896Z","steps":["trace[1492426489] 'process raft request' (duration: 113.24761ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:28.737829Z","caller":"traceutil/trace.go:171","msg":"trace[898089678] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"225.953056ms","start":"2026-04-02T01:00:28.511858Z","end":"2026-04-02T01:00:28.737811Z","steps":["trace[898089678] 'process raft request' (duration: 225.796034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:29.123782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.93342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:00:29.123963Z","caller":"traceutil/trace.go:171","msg":"trace[2076066828] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"315.668716ms","start":"2026-04-02T01:00:28.808253Z","end":"2026-04-02T01:00:29.123922Z","steps":["trace[2076066828] 'process raft request' (duration: 96.500943ms)","trace[2076066828] 'compare' (duration: 218.761269ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:29.124148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:28.808234Z","time spent":"315.821267ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:29.124327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.998606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:59631"} {"level":"info","ts":"2026-04-02T01:00:29.124428Z","caller":"traceutil/trace.go:171","msg":"trace[250915904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gateways.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:592; }","duration":"284.115848ms","start":"2026-04-02T01:00:28.840256Z","end":"2026-04-02T01:00:29.124372Z","steps":["trace[250915904] 'agreement among raft nodes before linearized reading' (duration: 283.787344ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:29.123956Z","caller":"traceutil/trace.go:171","msg":"trace[1684675355] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"283.600892ms","start":"2026-04-02T01:00:28.840277Z","end":"2026-04-02T01:00:29.123877Z","steps":["trace[1684675355] 'read index received' (duration: 64.576841ms)","trace[1684675355] 'applied index is now lower than readState.Index' (duration: 219.022661ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:29.126279Z","caller":"traceutil/trace.go:171","msg":"trace[912702938] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"272.020417ms","start":"2026-04-02T01:00:28.854226Z","end":"2026-04-02T01:00:29.126246Z","steps":["trace[912702938] 'process raft request' (duration: 271.861675ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:30.143745Z","caller":"traceutil/trace.go:171","msg":"trace[255618285] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"191.66793ms","start":"2026-04-02T01:00:29.952051Z","end":"2026-04-02T01:00:30.143719Z","steps":["trace[255618285] 'process raft request' (duration: 187.639845ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:30.395719Z","caller":"traceutil/trace.go:171","msg":"trace[1639648725] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"136.787206ms","start":"2026-04-02T01:00:30.258909Z","end":"2026-04-02T01:00:30.395696Z","steps":["trace[1639648725] 'process raft request' (duration: 136.611194ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:30.404092Z","caller":"traceutil/trace.go:171","msg":"trace[1840197965] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"140.609768ms","start":"2026-04-02T01:00:30.263464Z","end":"2026-04-02T01:00:30.404073Z","steps":["trace[1840197965] 'process raft request' (duration: 140.514387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.561919Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717240364,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T01:00:31.605918Z","caller":"traceutil/trace.go:171","msg":"trace[2024554398] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"862.169303ms","start":"2026-04-02T01:00:30.743726Z","end":"2026-04-02T01:00:31.605895Z","steps":["trace[2024554398] 'process raft request' (duration: 862.032402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.606087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:30.743697Z","time spent":"862.283164ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:31.687362Z","caller":"traceutil/trace.go:171","msg":"trace[1486170674] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"625.862099ms","start":"2026-04-02T01:00:31.061475Z","end":"2026-04-02T01:00:31.687337Z","steps":["trace[1486170674] 'read index received' (duration: 544.370568ms)","trace[1486170674] 'applied index is now lower than readState.Index' (duration: 81.490031ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:31.687369Z","caller":"traceutil/trace.go:171","msg":"trace[447342539] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"823.297882ms","start":"2026-04-02T01:00:30.864029Z","end":"2026-04-02T01:00:31.687327Z","steps":["trace[447342539] 'process raft request' (duration: 823.05809ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:31.687451Z","caller":"traceutil/trace.go:171","msg":"trace[1972918756] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"222.856293ms","start":"2026-04-02T01:00:31.464575Z","end":"2026-04-02T01:00:31.687431Z","steps":["trace[1972918756] 'process raft request' (duration: 222.726382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.68752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:30.863997Z","time spent":"823.459655ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T01:00:31.687383Z","caller":"traceutil/trace.go:171","msg":"trace[1956075702] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"403.452213ms","start":"2026-04-02T01:00:31.283904Z","end":"2026-04-02T01:00:31.687357Z","steps":["trace[1956075702] 'process raft request' (duration: 403.334161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.687525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.028091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:31.687619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.164783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:31.687644Z","caller":"traceutil/trace.go:171","msg":"trace[2013028916] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"626.197354ms","start":"2026-04-02T01:00:31.061434Z","end":"2026-04-02T01:00:31.687631Z","steps":["trace[2013028916] 'agreement among raft nodes before linearized reading' (duration: 626.037782ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.68767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.283888Z","time spent":"403.729486ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:31.687697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.061419Z","time spent":"626.257984ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-02T01:00:31.687654Z","caller":"traceutil/trace.go:171","msg":"trace[101446733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"524.224183ms","start":"2026-04-02T01:00:31.16342Z","end":"2026-04-02T01:00:31.687644Z","steps":["trace[101446733] 'agreement among raft nodes before linearized reading' (duration: 524.136982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.687767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.339929ms","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-02T01:00:31.687776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.163403Z","time spent":"524.367124ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T01:00:31.6878Z","caller":"traceutil/trace.go:171","msg":"trace[1033337] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:644; }","duration":"146.40828ms","start":"2026-04-02T01:00:31.541384Z","end":"2026-04-02T01:00:31.687792Z","steps":["trace[1033337] 'agreement among raft nodes before linearized reading' (duration: 146.287498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.687923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.578082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:31.687956Z","caller":"traceutil/trace.go:171","msg":"trace[402690140] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:644; }","duration":"426.645873ms","start":"2026-04-02T01:00:31.2613Z","end":"2026-04-02T01:00:31.687946Z","steps":["trace[402690140] 'agreement among raft nodes before linearized reading' (duration: 426.603373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:31.687989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.261279Z","time spent":"426.702694ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-04-02T01:00:31.688016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.587842ms","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-02T01:00:31.688061Z","caller":"traceutil/trace.go:171","msg":"trace[343493778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:644; }","duration":"146.688883ms","start":"2026-04-02T01:00:31.54136Z","end":"2026-04-02T01:00:31.688049Z","steps":["trace[343493778] 'agreement among raft nodes before linearized reading' (duration: 146.542982ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:32.417045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.925938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-04-02T01:00:32.417084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.057534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-04-02T01:00:32.417118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.704582ms","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-02T01:00:32.417125Z","caller":"traceutil/trace.go:171","msg":"trace[1911189691] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:651; }","duration":"556.045011ms","start":"2026-04-02T01:00:31.861058Z","end":"2026-04-02T01:00:32.417103Z","steps":["trace[1911189691] 'range keys from in-memory index tree' (duration: 555.751936ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:32.417155Z","caller":"traceutil/trace.go:171","msg":"trace[1195581561] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:651; }","duration":"352.162186ms","start":"2026-04-02T01:00:32.064972Z","end":"2026-04-02T01:00:32.417135Z","steps":["trace[1195581561] 'range keys from in-memory index tree' (duration: 351.959453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:32.417181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.510095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-04-02T01:00:32.417184Z","caller":"traceutil/trace.go:171","msg":"trace[1773953281] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"594.811833ms","start":"2026-04-02T01:00:31.822359Z","end":"2026-04-02T01:00:32.417171Z","steps":["trace[1773953281] 'range keys from in-memory index tree' (duration: 594.540789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:32.417178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.861046Z","time spent":"556.122002ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"warn","ts":"2026-04-02T01:00:32.417197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:32.06495Z","time spent":"352.238307ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-02T01:00:32.41722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.82233Z","time spent":"594.882504ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T01:00:32.417272Z","caller":"traceutil/trace.go:171","msg":"trace[849755670] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:651; }","duration":"591.614136ms","start":"2026-04-02T01:00:31.825638Z","end":"2026-04-02T01:00:32.417252Z","steps":["trace[849755670] 'range keys from in-memory index tree' (duration: 591.427224ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:32.417092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.738579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-04-02T01:00:32.417306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:31.825623Z","time spent":"591.674967ms","remote":"127.0.0.1:54174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7732,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-02T01:00:32.417316Z","caller":"traceutil/trace.go:171","msg":"trace[120867819] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:651; }","duration":"155.982803ms","start":"2026-04-02T01:00:32.261323Z","end":"2026-04-02T01:00:32.417306Z","steps":["trace[120867819] 'range keys from in-memory index tree' (duration: 155.581137ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:38.03776Z","caller":"traceutil/trace.go:171","msg":"trace[773747486] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"394.993183ms","start":"2026-04-02T01:00:37.642742Z","end":"2026-04-02T01:00:38.037736Z","steps":["trace[773747486] 'process raft request' (duration: 394.859022ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.038041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:37.642718Z","time spent":"395.123026ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:38.058553Z","caller":"traceutil/trace.go:171","msg":"trace[1485689673] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"346.169107ms","start":"2026-04-02T01:00:37.712214Z","end":"2026-04-02T01:00:38.058383Z","steps":["trace[1485689673] 'process raft request' (duration: 343.131151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.058747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:37.712173Z","time spent":"346.456971ms","remote":"127.0.0.1:54224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:38.059032Z","caller":"traceutil/trace.go:171","msg":"trace[739302652] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:720; }","duration":"287.432675ms","start":"2026-04-02T01:00:37.771532Z","end":"2026-04-02T01:00:38.058965Z","steps":["trace[739302652] 'read index received' (duration: 266.519438ms)","trace[739302652] 'applied index is now lower than readState.Index' (duration: 20.909177ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:38.059269Z","caller":"traceutil/trace.go:171","msg":"trace[1290524679] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"145.129198ms","start":"2026-04-02T01:00:37.914036Z","end":"2026-04-02T01:00:38.059165Z","steps":["trace[1290524679] 'process raft request' (duration: 142.782802ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.060449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.898277ms","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-02T01:00:38.060516Z","caller":"traceutil/trace.go:171","msg":"trace[953216917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:694; }","duration":"289.037029ms","start":"2026-04-02T01:00:37.771449Z","end":"2026-04-02T01:00:38.060486Z","steps":["trace[953216917] 'agreement among raft nodes before linearized reading' (duration: 287.790711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.061087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.515262ms","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-02T01:00:38.06117Z","caller":"traceutil/trace.go:171","msg":"trace[951652649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:694; }","duration":"275.635963ms","start":"2026-04-02T01:00:37.785502Z","end":"2026-04-02T01:00:38.061138Z","steps":["trace[951652649] 'agreement among raft nodes before linearized reading' (duration: 273.810296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.062277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.694467ms","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-02T01:00:38.062455Z","caller":"traceutil/trace.go:171","msg":"trace[1957947009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"269.797029ms","start":"2026-04-02T01:00:37.792531Z","end":"2026-04-02T01:00:38.062328Z","steps":["trace[1957947009] 'agreement among raft nodes before linearized reading' (duration: 267.523345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.664625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717240525,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T01:00:38.914145Z","caller":"traceutil/trace.go:171","msg":"trace[833883852] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"750.55275ms","start":"2026-04-02T01:00:38.163569Z","end":"2026-04-02T01:00:38.914122Z","steps":["trace[833883852] 'read index received' (duration: 750.439689ms)","trace[833883852] 'applied index is now lower than readState.Index' (duration: 111.961µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:38.914252Z","caller":"traceutil/trace.go:171","msg":"trace[2134316646] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"776.91231ms","start":"2026-04-02T01:00:38.13733Z","end":"2026-04-02T01:00:38.914242Z","steps":["trace[2134316646] 'process raft request' (duration: 776.663086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:38.91454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.137319Z","time spent":"777.102594ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T01:00:39.00052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.364422ms","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-02T01:00:39.000612Z","caller":"traceutil/trace.go:171","msg":"trace[1117962751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"545.563526ms","start":"2026-04-02T01:00:38.455023Z","end":"2026-04-02T01:00:39.000586Z","steps":["trace[1117962751] 'agreement among raft nodes before linearized reading' (duration: 459.316969ms)","trace[1117962751] 'range keys from in-memory index tree' (duration: 85.935682ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:39.000523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.042132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4jggb\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-02T01:00:39.000787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.455011Z","time spent":"545.639117ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T01:00:39.000891Z","caller":"traceutil/trace.go:171","msg":"trace[1866558483] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4jggb; range_end:; response_count:1; response_revision:700; }","duration":"738.373836ms","start":"2026-04-02T01:00:38.262403Z","end":"2026-04-02T01:00:39.000777Z","steps":["trace[1866558483] 'agreement among raft nodes before linearized reading' (duration: 651.900436ms)","trace[1866558483] 'range keys from in-memory index tree' (duration: 86.100355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:39.000694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.130142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:39.001083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.26238Z","time spent":"738.584189ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-4jggb\" "} {"level":"info","ts":"2026-04-02T01:00:39.001091Z","caller":"traceutil/trace.go:171","msg":"trace[575295460] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"837.482418ms","start":"2026-04-02T01:00:38.163532Z","end":"2026-04-02T01:00:39.001014Z","steps":["trace[575295460] 'agreement among raft nodes before linearized reading' (duration: 750.796384ms)","trace[575295460] 'range keys from in-memory index tree' (duration: 86.343488ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:39.001301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.16352Z","time spent":"837.758892ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T01:00:39.018963Z","caller":"traceutil/trace.go:171","msg":"trace[1888693611] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"100.161814ms","start":"2026-04-02T01:00:38.918709Z","end":"2026-04-02T01:00:39.018871Z","steps":["trace[1888693611] 'process raft request' (duration: 100.047012ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:39.450684Z","caller":"traceutil/trace.go:171","msg":"trace[1214272907] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"530.967571ms","start":"2026-04-02T01:00:38.919691Z","end":"2026-04-02T01:00:39.450658Z","steps":["trace[1214272907] 'process raft request' (duration: 530.818169ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:39.450678Z","caller":"traceutil/trace.go:171","msg":"trace[112323832] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"531.287546ms","start":"2026-04-02T01:00:38.919349Z","end":"2026-04-02T01:00:39.450637Z","steps":["trace[112323832] 'process raft request' (duration: 498.85739ms)","trace[112323832] 'compare' (duration: 32.140322ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:39.450748Z","caller":"traceutil/trace.go:171","msg":"trace[2097736823] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"529.929086ms","start":"2026-04-02T01:00:38.92079Z","end":"2026-04-02T01:00:39.450719Z","steps":["trace[2097736823] 'process raft request' (duration: 529.817624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:39.450852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.91931Z","time spent":"531.456168ms","remote":"127.0.0.1:54192","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T01:00:39.450935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.919679Z","time spent":"531.073313ms","remote":"127.0.0.1:54390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:39.450941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:38.920768Z","time spent":"530.051038ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:39.451192Z","caller":"traceutil/trace.go:171","msg":"trace[1159850503] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"445.411375ms","start":"2026-04-02T01:00:39.00577Z","end":"2026-04-02T01:00:39.451181Z","steps":["trace[1159850503] 'process raft request' (duration: 444.884927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:39.451252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:39.005756Z","time spent":"445.463896ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:39.451377Z","caller":"traceutil/trace.go:171","msg":"trace[976396569] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:728; }","duration":"448.621445ms","start":"2026-04-02T01:00:39.002737Z","end":"2026-04-02T01:00:39.451359Z","steps":["trace[976396569] 'read index received' (duration: 15.995999ms)","trace[976396569] 'applied index is now lower than readState.Index' (duration: 432.623456ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:39.451654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.901929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:39.451663Z","caller":"traceutil/trace.go:171","msg":"trace[1446611145] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"438.282164ms","start":"2026-04-02T01:00:39.013363Z","end":"2026-04-02T01:00:39.451645Z","steps":["trace[1446611145] 'process raft request' (duration: 437.634343ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:39.451705Z","caller":"traceutil/trace.go:171","msg":"trace[416471328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"449.050492ms","start":"2026-04-02T01:00:39.002632Z","end":"2026-04-02T01:00:39.451683Z","steps":["trace[416471328] 'agreement among raft nodes before linearized reading' (duration: 448.787498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:39.451747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:39.002615Z","time spent":"449.120383ms","remote":"127.0.0.1:54130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T01:00:39.451752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:39.013339Z","time spent":"438.362845ms","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:40.146174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.337205ms","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-02T01:00:40.146246Z","caller":"traceutil/trace.go:171","msg":"trace[301842895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:706; }","duration":"102.467748ms","start":"2026-04-02T01:00:40.04376Z","end":"2026-04-02T01:00:40.146228Z","steps":["trace[301842895] 'range keys from in-memory index tree' (duration: 102.218414ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:40.284734Z","caller":"traceutil/trace.go:171","msg":"trace[1881654563] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"134.980919ms","start":"2026-04-02T01:00:40.149731Z","end":"2026-04-02T01:00:40.284712Z","steps":["trace[1881654563] 'process raft request' (duration: 134.844957ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:40.397173Z","caller":"traceutil/trace.go:171","msg":"trace[1192044388] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"247.185773ms","start":"2026-04-02T01:00:40.149939Z","end":"2026-04-02T01:00:40.397124Z","steps":["trace[1192044388] 'process raft request' (duration: 197.345802ms)","trace[1192044388] 'compare' (duration: 49.582237ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:40.397201Z","caller":"traceutil/trace.go:171","msg":"trace[1252108922] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"247.133812ms","start":"2026-04-02T01:00:40.150028Z","end":"2026-04-02T01:00:40.397162Z","steps":["trace[1252108922] 'process raft request' (duration: 247.01974ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:40.397397Z","caller":"traceutil/trace.go:171","msg":"trace[1129108346] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"246.743256ms","start":"2026-04-02T01:00:40.150638Z","end":"2026-04-02T01:00:40.397381Z","steps":["trace[1129108346] 'process raft request' (duration: 246.469441ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:40.397376Z","caller":"traceutil/trace.go:171","msg":"trace[115979527] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:734; }","duration":"236.879557ms","start":"2026-04-02T01:00:40.160468Z","end":"2026-04-02T01:00:40.397347Z","steps":["trace[115979527] 'read index received' (duration: 124.139355ms)","trace[115979527] 'applied index is now lower than readState.Index' (duration: 112.738842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:40.39751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.016329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:40.397543Z","caller":"traceutil/trace.go:171","msg":"trace[307040817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"237.08734ms","start":"2026-04-02T01:00:40.160444Z","end":"2026-04-02T01:00:40.397531Z","steps":["trace[307040817] 'agreement among raft nodes before linearized reading' (duration: 237.005509ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:42.789527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717240547,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T01:00:43.121651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.958602561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:43.121737Z","caller":"traceutil/trace.go:171","msg":"trace[199100411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:710; }","duration":"1.958742854s","start":"2026-04-02T01:00:41.162972Z","end":"2026-04-02T01:00:43.121715Z","steps":["trace[199100411] 'range keys from in-memory index tree' (duration: 1.95848595s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.121777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:41.162947Z","time spent":"1.958821515s","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T01:00:43.121933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664212458s","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-02T01:00:43.122012Z","caller":"traceutil/trace.go:171","msg":"trace[1987068243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:710; }","duration":"1.66435683s","start":"2026-04-02T01:00:41.457636Z","end":"2026-04-02T01:00:43.121993Z","steps":["trace[1987068243] 'range keys from in-memory index tree' (duration: 1.663954954s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.122057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:41.457602Z","time spent":"1.664445391s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T01:00:43.150114Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105973339s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T01:00:43.150544Z","caller":"traceutil/trace.go:171","msg":"trace[1651756553] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"1.106495569s","start":"2026-04-02T01:00:42.044024Z","end":"2026-04-02T01:00:43.150519Z","steps":["trace[1651756553] 'process raft request' (duration: 1.106355186s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.150703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:42.043994Z","time spent":"1.106623691s","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:43.157644Z","caller":"traceutil/trace.go:171","msg":"trace[1238039017] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"868.652047ms","start":"2026-04-02T01:00:42.288954Z","end":"2026-04-02T01:00:43.157606Z","steps":["trace[1238039017] 'read index received' (duration: 861.706936ms)","trace[1238039017] 'applied index is now lower than readState.Index' (duration: 6.944461ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:00:43.157841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.895361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T01:00:43.158056Z","caller":"traceutil/trace.go:171","msg":"trace[427243609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:712; }","duration":"869.227167ms","start":"2026-04-02T01:00:42.288821Z","end":"2026-04-02T01:00:43.158048Z","steps":["trace[427243609] 'agreement among raft nodes before linearized reading' (duration: 868.954322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.158079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:42.2888Z","time spent":"869.272587ms","remote":"127.0.0.1:54428","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-02T01:00:43.158003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.667899ms","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-02T01:00:43.158176Z","caller":"traceutil/trace.go:171","msg":"trace[464621434] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"755.872653ms","start":"2026-04-02T01:00:42.402289Z","end":"2026-04-02T01:00:43.158162Z","steps":["trace[464621434] 'agreement among raft nodes before linearized reading' (duration: 755.664289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.157997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.431076ms","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-02T01:00:43.158238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:42.402245Z","time spent":"755.983105ms","remote":"127.0.0.1:54428","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-02T01:00:43.158258Z","caller":"traceutil/trace.go:171","msg":"trace[1155338392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"755.74595ms","start":"2026-04-02T01:00:42.402501Z","end":"2026-04-02T01:00:43.158247Z","steps":["trace[1155338392] 'agreement among raft nodes before linearized reading' (duration: 755.387994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.15829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:42.402479Z","time spent":"755.803132ms","remote":"127.0.0.1:54428","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-02T01:00:43.158032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.697626ms","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-02T01:00:43.159162Z","caller":"traceutil/trace.go:171","msg":"trace[808553842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:712; }","duration":"757.867427ms","start":"2026-04-02T01:00:42.401275Z","end":"2026-04-02T01:00:43.159142Z","steps":["trace[808553842] 'agreement among raft nodes before linearized reading' (duration: 756.610745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:43.159284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:42.401253Z","time spent":"758.013609ms","remote":"127.0.0.1:54428","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-02T01:00:47.644154Z","caller":"traceutil/trace.go:171","msg":"trace[1633425172] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"134.6577ms","start":"2026-04-02T01:00:47.509465Z","end":"2026-04-02T01:00:47.644122Z","steps":["trace[1633425172] 'process raft request' (duration: 133.08017ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:55.539968Z","caller":"traceutil/trace.go:171","msg":"trace[1938068913] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"310.220399ms","start":"2026-04-02T01:00:55.229723Z","end":"2026-04-02T01:00:55.539943Z","steps":["trace[1938068913] 'read index received' (duration: 310.120797ms)","trace[1938068913] 'applied index is now lower than readState.Index' (duration: 98.482µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:00:55.54002Z","caller":"traceutil/trace.go:171","msg":"trace[1575168145] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"310.460075ms","start":"2026-04-02T01:00:55.229527Z","end":"2026-04-02T01:00:55.539987Z","steps":["trace[1575168145] 'process raft request' (duration: 310.23344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.540113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.363263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.540151Z","caller":"traceutil/trace.go:171","msg":"trace[830991608] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:806; }","duration":"310.435564ms","start":"2026-04-02T01:00:55.229703Z","end":"2026-04-02T01:00:55.540138Z","steps":["trace[830991608] 'agreement among raft nodes before linearized reading' (duration: 310.355162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.540327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.229695Z","time spent":"310.526716ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T01:00:55.540446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.229514Z","time spent":"310.770271ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:55.626374Z","caller":"traceutil/trace.go:171","msg":"trace[1348926093] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"395.294983ms","start":"2026-04-02T01:00:55.231059Z","end":"2026-04-02T01:00:55.626354Z","steps":["trace[1348926093] 'process raft request' (duration: 395.242581ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:55.626382Z","caller":"traceutil/trace.go:171","msg":"trace[1287637658] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"396.010769ms","start":"2026-04-02T01:00:55.23034Z","end":"2026-04-02T01:00:55.626351Z","steps":["trace[1287637658] 'process raft request' (duration: 395.809654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.626494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.231027Z","time spent":"395.405006ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:55.626512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.230326Z","time spent":"396.118491ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T01:00:55.626651Z","caller":"traceutil/trace.go:171","msg":"trace[1237070035] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"395.55892ms","start":"2026-04-02T01:00:55.231053Z","end":"2026-04-02T01:00:55.626612Z","steps":["trace[1237070035] 'process raft request' (duration: 395.212342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.626708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.9321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.626733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.398995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.626758Z","caller":"traceutil/trace.go:171","msg":"trace[2075852016] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:810; }","duration":"397.014101ms","start":"2026-04-02T01:00:55.229732Z","end":"2026-04-02T01:00:55.626747Z","steps":["trace[2075852016] 'agreement among raft nodes before linearized reading' (duration: 396.915939ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:55.626762Z","caller":"traceutil/trace.go:171","msg":"trace[1663418833] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:810; }","duration":"343.453865ms","start":"2026-04-02T01:00:55.2833Z","end":"2026-04-02T01:00:55.626754Z","steps":["trace[1663418833] 'agreement among raft nodes before linearized reading' (duration: 343.392524ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.626783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.229721Z","time spent":"397.056382ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-04-02T01:00:55.626835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.231044Z","time spent":"395.689513ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:00:55.626928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.607754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.626962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.732102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.626981Z","caller":"traceutil/trace.go:171","msg":"trace[746407082] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:810; }","duration":"343.771312ms","start":"2026-04-02T01:00:55.283205Z","end":"2026-04-02T01:00:55.626976Z","steps":["trace[746407082] 'agreement among raft nodes before linearized reading' (duration: 343.740631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.626995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.283189Z","time spent":"343.801853ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-02T01:00:55.626985Z","caller":"traceutil/trace.go:171","msg":"trace[892029350] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:810; }","duration":"327.743737ms","start":"2026-04-02T01:00:55.299226Z","end":"2026-04-02T01:00:55.626969Z","steps":["trace[892029350] 'agreement among raft nodes before linearized reading' (duration: 327.595784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.348473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.627031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.29918Z","time spent":"327.840099ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-04-02T01:00:55.627046Z","caller":"traceutil/trace.go:171","msg":"trace[473045502] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:810; }","duration":"343.395814ms","start":"2026-04-02T01:00:55.283644Z","end":"2026-04-02T01:00:55.62704Z","steps":["trace[473045502] 'agreement among raft nodes before linearized reading' (duration: 343.364394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.283625Z","time spent":"343.445195ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-02T01:00:55.62711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.002981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.627142Z","caller":"traceutil/trace.go:171","msg":"trace[763542864] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:810; }","duration":"355.044872ms","start":"2026-04-02T01:00:55.272092Z","end":"2026-04-02T01:00:55.627137Z","steps":["trace[763542864] 'agreement among raft nodes before linearized reading' (duration: 355.004011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.272087Z","time spent":"355.064992ms","remote":"127.0.0.1:39192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-04-02T01:00:55.627254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.564433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.627276Z","caller":"traceutil/trace.go:171","msg":"trace[2046630983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:810; }","duration":"355.612974ms","start":"2026-04-02T01:00:55.271656Z","end":"2026-04-02T01:00:55.627269Z","steps":["trace[2046630983] 'agreement among raft nodes before linearized reading' (duration: 355.576314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.626785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.283286Z","time spent":"343.494147ms","remote":"127.0.0.1:39206","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T01:00:55.627296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.271645Z","time spent":"355.644995ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T01:00:55.627301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.602124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.62727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.456391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.627362Z","caller":"traceutil/trace.go:171","msg":"trace[1296702853] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:810; }","duration":"397.587354ms","start":"2026-04-02T01:00:55.229753Z","end":"2026-04-02T01:00:55.62734Z","steps":["trace[1296702853] 'agreement among raft nodes before linearized reading' (duration: 397.38658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.62727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.492622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.627401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.779397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.627419Z","caller":"traceutil/trace.go:171","msg":"trace[270881130] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:810; }","duration":"378.806898ms","start":"2026-04-02T01:00:55.248606Z","end":"2026-04-02T01:00:55.627413Z","steps":["trace[270881130] 'agreement among raft nodes before linearized reading' (duration: 378.779217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.229748Z","time spent":"397.651855ms","remote":"127.0.0.1:39182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-04-02T01:00:55.627441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.248603Z","time spent":"378.833588ms","remote":"127.0.0.1:39192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-04-02T01:00:55.627402Z","caller":"traceutil/trace.go:171","msg":"trace[1115015800] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:810; }","duration":"327.660475ms","start":"2026-04-02T01:00:55.299735Z","end":"2026-04-02T01:00:55.627395Z","steps":["trace[1115015800] 'agreement among raft nodes before linearized reading' (duration: 327.503042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.299722Z","time spent":"327.734988ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-04-02T01:00:55.62699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.790908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T01:00:55.62748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.88006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:00:55.627318Z","caller":"traceutil/trace.go:171","msg":"trace[1644152547] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:810; }","duration":"355.640324ms","start":"2026-04-02T01:00:55.271673Z","end":"2026-04-02T01:00:55.627314Z","steps":["trace[1644152547] 'agreement among raft nodes before linearized reading' (duration: 355.612474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.27166Z","time spent":"355.88076ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-02T01:00:55.627534Z","caller":"traceutil/trace.go:171","msg":"trace[1683904274] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:810; }","duration":"328.339751ms","start":"2026-04-02T01:00:55.29917Z","end":"2026-04-02T01:00:55.62751Z","steps":["trace[1683904274] 'agreement among raft nodes before linearized reading' (duration: 327.794839ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:00:55.627569Z","caller":"traceutil/trace.go:171","msg":"trace[787484353] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:810; }","duration":"378.983512ms","start":"2026-04-02T01:00:55.248575Z","end":"2026-04-02T01:00:55.627558Z","steps":["trace[787484353] 'agreement among raft nodes before linearized reading' (duration: 378.874949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.299155Z","time spent":"328.425752ms","remote":"127.0.0.1:39216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T01:00:55.627606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.248567Z","time spent":"379.028693ms","remote":"127.0.0.1:39192","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-02T01:00:55.627831Z","caller":"traceutil/trace.go:171","msg":"trace[90829798] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"396.715285ms","start":"2026-04-02T01:00:55.231099Z","end":"2026-04-02T01:00:55.627814Z","steps":["trace[90829798] 'process raft request' (duration: 395.224803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:00:55.627929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:00:55.231093Z","time spent":"396.777197ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:01:07.287033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717240895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T01:01:07.787963Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9823086685717240895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T01:01:07.92037Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.519294968s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T01:01:07.920805Z","caller":"traceutil/trace.go:171","msg":"trace[1422982375] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"1.134103795s","start":"2026-04-02T01:01:06.786671Z","end":"2026-04-02T01:01:07.920775Z","steps":["trace[1422982375] 'read index received' (duration: 1.133828268s)","trace[1422982375] 'applied index is now lower than readState.Index' (duration: 274.487µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T01:01:07.920899Z","caller":"traceutil/trace.go:171","msg":"trace[2042658454] transaction","detail":"{read_only:false; response_revision:847; number_of_response:1; }","duration":"1.519987057s","start":"2026-04-02T01:01:06.400902Z","end":"2026-04-02T01:01:07.920889Z","steps":["trace[2042658454] 'process raft request' (duration: 1.519700439s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:01:06.400865Z","time spent":"1.520067029s","remote":"127.0.0.1:54318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:01:07.9212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.474613ms","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-02T01:01:07.92132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.77744ms","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-02T01:01:07.921318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.86967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.200\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-02T01:01:07.921354Z","caller":"traceutil/trace.go:171","msg":"trace[1173830248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:847; }","duration":"335.815451ms","start":"2026-04-02T01:01:07.585529Z","end":"2026-04-02T01:01:07.921345Z","steps":["trace[1173830248] 'agreement among raft nodes before linearized reading' (duration: 335.660837ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:01:07.921288Z","caller":"traceutil/trace.go:171","msg":"trace[1600262528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:847; }","duration":"236.591986ms","start":"2026-04-02T01:01:07.684675Z","end":"2026-04-02T01:01:07.921267Z","steps":["trace[1600262528] 'agreement among raft nodes before linearized reading' (duration: 236.34046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:01:07.585468Z","time spent":"335.923445ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T01:01:07.9214Z","caller":"traceutil/trace.go:171","msg":"trace[393624319] range","detail":"{range_begin:/registry/masterleases/162.253.55.200; range_end:; response_count:1; response_revision:847; }","duration":"580.973322ms","start":"2026-04-02T01:01:07.340409Z","end":"2026-04-02T01:01:07.921382Z","steps":["trace[393624319] 'agreement among raft nodes before linearized reading' (duration: 580.839309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.554868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T01:01:07.921393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.67599ms","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-02T01:01:07.921443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:01:07.340394Z","time spent":"581.041004ms","remote":"127.0.0.1:54148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.200\" "} {"level":"info","ts":"2026-04-02T01:01:07.921472Z","caller":"traceutil/trace.go:171","msg":"trace[596639639] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:847; }","duration":"243.685451ms","start":"2026-04-02T01:01:07.677768Z","end":"2026-04-02T01:01:07.921454Z","steps":["trace[596639639] 'agreement among raft nodes before linearized reading' (duration: 243.467756ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:01:07.921478Z","caller":"traceutil/trace.go:171","msg":"trace[467393152] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:847; }","duration":"233.772282ms","start":"2026-04-02T01:01:07.687689Z","end":"2026-04-02T01:01:07.921462Z","steps":["trace[467393152] 'agreement among raft nodes before linearized reading' (duration: 233.494195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134932107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-02T01:01:07.921611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.732103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T01:01:07.921661Z","caller":"traceutil/trace.go:171","msg":"trace[1104666551] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:847; }","duration":"1.13504987s","start":"2026-04-02T01:01:06.786597Z","end":"2026-04-02T01:01:07.921646Z","steps":["trace[1104666551] 'agreement among raft nodes before linearized reading' (duration: 1.134895345s)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:01:07.921709Z","caller":"traceutil/trace.go:171","msg":"trace[1530891314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:847; }","duration":"757.468264ms","start":"2026-04-02T01:01:07.164218Z","end":"2026-04-02T01:01:07.921686Z","steps":["trace[1530891314] 'agreement among raft nodes before linearized reading' (duration: 756.714424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.625155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-02T01:01:07.921761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:01:06.786564Z","time spent":"1.135172563s","remote":"127.0.0.1:54506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-04-02T01:01:07.921834Z","caller":"traceutil/trace.go:171","msg":"trace[66722113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:847; }","duration":"230.254343ms","start":"2026-04-02T01:01:07.691569Z","end":"2026-04-02T01:01:07.921823Z","steps":["trace[66722113] 'agreement among raft nodes before linearized reading' (duration: 229.528713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:01:07.921777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:01:07.164197Z","time spent":"757.562406ms","remote":"127.0.0.1:54120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T01:01:21.81385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.496774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lf5z7\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-04-02T01:01:21.813914Z","caller":"traceutil/trace.go:171","msg":"trace[297088329] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-lf5z7; range_end:; response_count:1; response_revision:1013; }","duration":"251.611827ms","start":"2026-04-02T01:01:21.562288Z","end":"2026-04-02T01:01:21.813899Z","steps":["trace[297088329] 'range keys from in-memory index tree' (duration: 251.345989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:02:57.199628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.230059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T01:02:57.200147Z","caller":"traceutil/trace.go:171","msg":"trace[1940258878] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"671.651694ms","start":"2026-04-02T01:02:56.528468Z","end":"2026-04-02T01:02:57.20012Z","steps":["trace[1940258878] 'process raft request' (duration: 50.843941ms)","trace[1940258878] 'compare' (duration: 619.657516ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:02:57.20023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:02:56.528451Z","time spent":"671.737088ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:02:57.200423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.445242ms","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-02T01:02:57.200499Z","caller":"traceutil/trace.go:171","msg":"trace[536477499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1380; }","duration":"662.556576ms","start":"2026-04-02T01:02:56.537926Z","end":"2026-04-02T01:02:57.200483Z","steps":["trace[536477499] 'agreement among raft nodes before linearized reading' (duration: 662.346078ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T01:02:57.200079Z","caller":"traceutil/trace.go:171","msg":"trace[1504634287] linearizableReadLoop","detail":"{readStateIndex:1440; appliedIndex:1439; }","duration":"662.100938ms","start":"2026-04-02T01:02:56.537961Z","end":"2026-04-02T01:02:57.200062Z","steps":["trace[1504634287] 'read index received' (duration: 41.319116ms)","trace[1504634287] 'applied index is now lower than readState.Index' (duration: 620.780092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T01:02:57.200536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:02:56.537909Z","time spent":"662.618259ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T01:02:57.223896Z","caller":"traceutil/trace.go:171","msg":"trace[1629469224] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"615.617738ms","start":"2026-04-02T01:02:56.608262Z","end":"2026-04-02T01:02:57.223879Z","steps":["trace[1629469224] 'process raft request' (duration: 615.41955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:02:57.223996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:02:56.608239Z","time spent":"615.711042ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T01:02:57.223991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.954665ms","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-02T01:02:57.224065Z","caller":"traceutil/trace.go:171","msg":"trace[1593186180] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"683.060809ms","start":"2026-04-02T01:02:56.540986Z","end":"2026-04-02T01:02:57.224047Z","steps":["trace[1593186180] 'agreement among raft nodes before linearized reading' (duration: 682.866231ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T01:02:57.224111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T01:02:56.540976Z","time spent":"683.126802ms","remote":"127.0.0.1:54428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}