{"level":"warn","ts":"2026-04-21T03:25:22.694415Z","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-21T03:25:22.694523Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.70:2380","--initial-cluster=instance=https://199.204.45.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.70: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-21T03:25:22.694609Z","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-21T03:25:22.694624Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-21T03:25:22.694643Z","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-21T03:25:22.695849Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"]} {"level":"info","ts":"2026-04-21T03:25:22.696081Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.70: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-21T03:25:22.705096Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.04073ms"} {"level":"info","ts":"2026-04-21T03:25:22.719534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f8844a9a4263041d","cluster-id":"4749b7c221ae0648"} {"level":"info","ts":"2026-04-21T03:25:22.719622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=()"} {"level":"info","ts":"2026-04-21T03:25:22.719652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 0"} {"level":"info","ts":"2026-04-21T03:25:22.719684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f8844a9a4263041d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-21T03:25:22.7197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became follower at term 1"} {"level":"info","ts":"2026-04-21T03:25:22.719746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"warn","ts":"2026-04-21T03:25:22.7302Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-21T03:25:22.732064Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-21T03:25:22.733507Z","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-21T03:25:22.735677Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f8844a9a4263041d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-21T03:25:22.735997Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f8844a9a4263041d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-21T03:25:22.736718Z","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-21T03:25:22.736977Z","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-21T03:25:22.737022Z","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-21T03:25:22.737168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d switched to configuration voters=(17907520044731139101)"} {"level":"info","ts":"2026-04-21T03:25:22.737296Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","added-peer-id":"f8844a9a4263041d","added-peer-peer-urls":["https://199.204.45.70:2380"]} {"level":"info","ts":"2026-04-21T03:25:22.740295Z","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-21T03:25:22.740497Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f8844a9a4263041d","initial-advertise-peer-urls":["https://199.204.45.70:2380"],"listen-peer-urls":["https://199.204.45.70:2380"],"advertise-client-urls":["https://199.204.45.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-21T03:25:22.740542Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-21T03:25:22.740629Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-21T03:25:22.740642Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.70:2380"} {"level":"info","ts":"2026-04-21T03:25:23.420352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d is starting a new election at term 1"} {"level":"info","ts":"2026-04-21T03:25:23.420398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-21T03:25:23.420421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgPreVoteResp from f8844a9a4263041d at term 1"} {"level":"info","ts":"2026-04-21T03:25:23.420433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became candidate at term 2"} {"level":"info","ts":"2026-04-21T03:25:23.420438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d received MsgVoteResp from f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-21T03:25:23.420447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f8844a9a4263041d became leader at term 2"} {"level":"info","ts":"2026-04-21T03:25:23.420456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f8844a9a4263041d elected leader f8844a9a4263041d at term 2"} {"level":"info","ts":"2026-04-21T03:25:23.471554Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T03:25:23.494417Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f8844a9a4263041d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.70:2379]}","request-path":"/0/members/f8844a9a4263041d/attributes","cluster-id":"4749b7c221ae0648","publish-timeout":"7s"} {"level":"info","ts":"2026-04-21T03:25:23.494437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T03:25:23.494505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-21T03:25:23.494732Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-21T03:25:23.49476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-21T03:25:23.499383Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-21T03:25:23.499945Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.70:2379"} {"level":"info","ts":"2026-04-21T03:25:23.544316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4749b7c221ae0648","local-member-id":"f8844a9a4263041d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T03:25:23.544474Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-21T03:25:23.544558Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-04-21T03:25:23.806183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.090653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.806327Z","caller":"traceutil/trace.go:171","msg":"trace[262348067] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"104.287005ms","start":"2026-04-21T03:25:23.702012Z","end":"2026-04-21T03:25:23.806299Z","steps":["trace[262348067] 'count revisions from in-memory index tree' (duration: 103.945053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.806366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.73669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.806541Z","caller":"traceutil/trace.go:171","msg":"trace[1242488276] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"104.546677ms","start":"2026-04-21T03:25:23.701971Z","end":"2026-04-21T03:25:23.806518Z","steps":["trace[1242488276] 'range keys from in-memory index tree' (duration: 103.68418ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.806876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.252665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T03:25:23.806848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.132071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.806938Z","caller":"traceutil/trace.go:171","msg":"trace[982878748] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"129.329646ms","start":"2026-04-21T03:25:23.677588Z","end":"2026-04-21T03:25:23.806918Z","steps":["trace[982878748] 'count revisions from in-memory index tree' (duration: 129.210334ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:23.806946Z","caller":"traceutil/trace.go:171","msg":"trace[1501091781] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"162.269282ms","start":"2026-04-21T03:25:23.644658Z","end":"2026-04-21T03:25:23.806927Z","steps":["trace[1501091781] 'count revisions from in-memory index tree' (duration: 162.00522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.353268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T03:25:23.807051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.509386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807142Z","caller":"traceutil/trace.go:171","msg":"trace[2022156292] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"146.426678ms","start":"2026-04-21T03:25:23.660706Z","end":"2026-04-21T03:25:23.807133Z","steps":["trace[2022156292] 'range keys from in-memory index tree' (duration: 146.254567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.871218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807235Z","caller":"traceutil/trace.go:171","msg":"trace[117003571] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"129.714078ms","start":"2026-04-21T03:25:23.677503Z","end":"2026-04-21T03:25:23.807217Z","steps":["trace[117003571] 'range keys from in-memory index tree' (duration: 129.360765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.52801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T03:25:23.807328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.672097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807339Z","caller":"traceutil/trace.go:171","msg":"trace[1149388297] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"146.59759ms","start":"2026-04-21T03:25:23.660734Z","end":"2026-04-21T03:25:23.807331Z","steps":["trace[1149388297] 'count revisions from in-memory index tree' (duration: 146.410779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.23061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807368Z","caller":"traceutil/trace.go:171","msg":"trace[987942132] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"192.745738ms","start":"2026-04-21T03:25:23.614611Z","end":"2026-04-21T03:25:23.807356Z","steps":["trace[987942132] 'count revisions from in-memory index tree' (duration: 192.600786ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:23.807378Z","caller":"traceutil/trace.go:171","msg":"trace[345957099] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"119.293731ms","start":"2026-04-21T03:25:23.688073Z","end":"2026-04-21T03:25:23.807367Z","steps":["trace[345957099] 'count revisions from in-memory index tree' (duration: 119.19256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.846692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807566Z","caller":"traceutil/trace.go:171","msg":"trace[1697180826] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"177.950823ms","start":"2026-04-21T03:25:23.6296Z","end":"2026-04-21T03:25:23.807551Z","steps":["trace[1697180826] 'count revisions from in-memory index tree' (duration: 177.717821ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:23.807217Z","caller":"traceutil/trace.go:171","msg":"trace[450714183] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"192.96735ms","start":"2026-04-21T03:25:23.61423Z","end":"2026-04-21T03:25:23.807197Z","steps":["trace[450714183] 'range keys from in-memory index tree' (duration: 192.686617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:23.807607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.399312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T03:25:23.807646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.235489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-21T03:25:23.807655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.115344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-21T03:25:23.807681Z","caller":"traceutil/trace.go:171","msg":"trace[1636992972] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"119.520853ms","start":"2026-04-21T03:25:23.688149Z","end":"2026-04-21T03:25:23.807669Z","steps":["trace[1636992972] 'range keys from in-memory index tree' (duration: 119.292201ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:23.80769Z","caller":"traceutil/trace.go:171","msg":"trace[249730724] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"178.175215ms","start":"2026-04-21T03:25:23.629506Z","end":"2026-04-21T03:25:23.807681Z","steps":["trace[249730724] 'range keys from in-memory index tree' (duration: 178.051364ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:23.807696Z","caller":"traceutil/trace.go:171","msg":"trace[2077199100] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"163.300511ms","start":"2026-04-21T03:25:23.644377Z","end":"2026-04-21T03:25:23.807677Z","steps":["trace[2077199100] 'range keys from in-memory index tree' (duration: 163.177909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:47.117821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:46.579399Z","time spent":"538.418787ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T03:25:47.118226Z","caller":"traceutil/trace.go:171","msg":"trace[521401134] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:474; }","duration":"415.021012ms","start":"2026-04-21T03:25:46.703186Z","end":"2026-04-21T03:25:47.118207Z","steps":["trace[521401134] 'read index received' (duration: 415.016002ms)","trace[521401134] 'applied index is now lower than readState.Index' (duration: 3.92µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:25:47.11874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.535826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:25:47.118846Z","caller":"traceutil/trace.go:171","msg":"trace[569727540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"415.729266ms","start":"2026-04-21T03:25:46.703091Z","end":"2026-04-21T03:25:47.11882Z","steps":["trace[569727540] 'agreement among raft nodes before linearized reading' (duration: 415.530105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:47.118894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:46.703043Z","time spent":"415.843138ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T03:25:51.389297Z","caller":"traceutil/trace.go:171","msg":"trace[1731797027] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"241.504488ms","start":"2026-04-21T03:25:51.147766Z","end":"2026-04-21T03:25:51.389271Z","steps":["trace[1731797027] 'process raft request' (duration: 241.359007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:52.062316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.306492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:25:52.0625Z","caller":"traceutil/trace.go:171","msg":"trace[1343029372] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"913.839109ms","start":"2026-04-21T03:25:51.148616Z","end":"2026-04-21T03:25:52.062455Z","steps":["trace[1343029372] 'process raft request' (duration: 748.514629ms)","trace[1343029372] 'compare' (duration: 164.176681ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:25:52.062592Z","caller":"traceutil/trace.go:171","msg":"trace[604056819] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:484; }","duration":"737.530454ms","start":"2026-04-21T03:25:51.325036Z","end":"2026-04-21T03:25:52.062566Z","steps":["trace[604056819] 'read index received' (duration: 64.126886ms)","trace[604056819] 'applied index is now lower than readState.Index' (duration: 673.401358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:25:52.062714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:51.148604Z","time spent":"913.99134ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:25:52.166326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.255428ms","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-21T03:25:52.166328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.862575ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-21T03:25:52.166413Z","caller":"traceutil/trace.go:171","msg":"trace[1916354420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"841.414779ms","start":"2026-04-21T03:25:51.324977Z","end":"2026-04-21T03:25:52.166392Z","steps":["trace[1916354420] 'agreement among raft nodes before linearized reading' (duration: 737.714635ms)","trace[1916354420] 'range keys from in-memory index tree' (duration: 103.473072ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:25:52.166459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:51.324914Z","time spent":"841.53675ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T03:25:52.16647Z","caller":"traceutil/trace.go:171","msg":"trace[210400602] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"103.801654ms","start":"2026-04-21T03:25:52.062646Z","end":"2026-04-21T03:25:52.166448Z","steps":["trace[210400602] 'read index received' (duration: 43.21µs)","trace[210400602] 'applied index is now lower than readState.Index' (duration: 103.756764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:25:52.166601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.487138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"info","ts":"2026-04-21T03:25:52.166638Z","caller":"traceutil/trace.go:171","msg":"trace[423415107] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:469; }","duration":"829.548608ms","start":"2026-04-21T03:25:51.337077Z","end":"2026-04-21T03:25:52.166625Z","steps":["trace[423415107] 'agreement among raft nodes before linearized reading' (duration: 829.431497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:52.166678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:51.337069Z","time spent":"829.599069ms","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12700,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-04-21T03:25:52.166705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.418934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:25:52.166738Z","caller":"traceutil/trace.go:171","msg":"trace[1932906963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:469; }","duration":"464.499625ms","start":"2026-04-21T03:25:51.702228Z","end":"2026-04-21T03:25:52.166728Z","steps":["trace[1932906963] 'agreement among raft nodes before linearized reading' (duration: 464.436715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:52.166759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:51.702191Z","time spent":"464.564145ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:25:52.166883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.871982ms","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-21T03:25:52.167048Z","caller":"traceutil/trace.go:171","msg":"trace[151021457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"805.073473ms","start":"2026-04-21T03:25:51.361945Z","end":"2026-04-21T03:25:52.167018Z","steps":["trace[151021457] 'agreement among raft nodes before linearized reading' (duration: 804.732261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:52.167096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:51.361934Z","time spent":"805.154014ms","remote":"127.0.0.1:35490","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-21T03:25:54.17142Z","caller":"traceutil/trace.go:171","msg":"trace[1140342280] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"101.962796ms","start":"2026-04-21T03:25:54.069425Z","end":"2026-04-21T03:25:54.171388Z","steps":["trace[1140342280] 'process raft request' (duration: 101.766735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:56.423698Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827289,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T03:25:57.430416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.027087962s","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-21T03:25:57.430535Z","caller":"traceutil/trace.go:171","msg":"trace[665092440] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"2.027245494s","start":"2026-04-21T03:25:55.403253Z","end":"2026-04-21T03:25:57.430499Z","steps":["trace[665092440] 'range keys from in-memory index tree' (duration: 2.02689393s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.430603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:55.403231Z","time spent":"2.027356604s","remote":"127.0.0.1:35490","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-21T03:25:57.430641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.730268923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:25:57.430763Z","caller":"traceutil/trace.go:171","msg":"trace[938701245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"2.730439565s","start":"2026-04-21T03:25:54.700293Z","end":"2026-04-21T03:25:57.430732Z","steps":["trace[938701245] 'range keys from in-memory index tree' (duration: 2.730160053s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.430817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:54.700264Z","time spent":"2.730539296s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:25:57.430927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.450601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:25:57.431005Z","caller":"traceutil/trace.go:171","msg":"trace[1982948360] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"1.507386002s","start":"2026-04-21T03:25:55.923602Z","end":"2026-04-21T03:25:57.430988Z","steps":["trace[1982948360] 'read index received' (duration: 636.82457ms)","trace[1982948360] 'applied index is now lower than readState.Index' (duration: 870.559952ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:25:57.431067Z","caller":"traceutil/trace.go:171","msg":"trace[709110695] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.825330148s","start":"2026-04-21T03:25:55.605708Z","end":"2026-04-21T03:25:57.431038Z","steps":["trace[709110695] 'process raft request' (duration: 954.693955ms)","trace[709110695] 'compare' (duration: 870.212989ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:25:57.431157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:55.605674Z","time spent":"1.825436919s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:25:57.4312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.512657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-21T03:25:57.431211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.992589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T03:25:57.43122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507610264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:25:57.431257Z","caller":"traceutil/trace.go:171","msg":"trace[1188653214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"730.083681ms","start":"2026-04-21T03:25:56.701163Z","end":"2026-04-21T03:25:57.431247Z","steps":["trace[1188653214] 'agreement among raft nodes before linearized reading' (duration: 730.00129ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:57.43126Z","caller":"traceutil/trace.go:171","msg":"trace[1050693752] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:510; }","duration":"852.636867ms","start":"2026-04-21T03:25:56.578604Z","end":"2026-04-21T03:25:57.431241Z","steps":["trace[1050693752] 'agreement among raft nodes before linearized reading' (duration: 852.539526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.431261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255540904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-21T03:25:57.431278Z","caller":"traceutil/trace.go:171","msg":"trace[1977467569] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:510; }","duration":"1.507672724s","start":"2026-04-21T03:25:55.923589Z","end":"2026-04-21T03:25:57.431262Z","steps":["trace[1977467569] 'agreement among raft nodes before linearized reading' (duration: 1.507488543s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.431287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:56.701136Z","time spent":"730.144451ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:25:57.431297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:56.57857Z","time spent":"852.719038ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"warn","ts":"2026-04-21T03:25:57.431327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:55.923518Z","time spent":"1.507800045s","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-04-21T03:25:57.431333Z","caller":"traceutil/trace.go:171","msg":"trace[639914640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"1.255651265s","start":"2026-04-21T03:25:56.175659Z","end":"2026-04-21T03:25:57.43131Z","steps":["trace[639914640] 'agreement among raft nodes before linearized reading' (duration: 1.255473364s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.431343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162220307s","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-21T03:25:57.431384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:56.175596Z","time spent":"1.255775636s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T03:25:57.431396Z","caller":"traceutil/trace.go:171","msg":"trace[1147828014] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.162316587s","start":"2026-04-21T03:25:56.269064Z","end":"2026-04-21T03:25:57.431381Z","steps":["trace[1147828014] 'agreement among raft nodes before linearized reading' (duration: 1.162115696s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.431479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:56.269039Z","time spent":"1.162431009s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T03:25:57.43155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16267225s","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-21T03:25:57.431598Z","caller":"traceutil/trace.go:171","msg":"trace[1941969320] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.16275876s","start":"2026-04-21T03:25:56.268825Z","end":"2026-04-21T03:25:57.431583Z","steps":["trace[1941969320] 'agreement among raft nodes before linearized reading' (duration: 1.16267971s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:57.431639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:56.268801Z","time spent":"1.162827871s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-21T03:25:57.941337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T03:25:58.319458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.718784ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-04-21T03:25:58.319658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.434213Z","time spent":"885.440324ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-21T03:25:58.382163Z","caller":"traceutil/trace.go:171","msg":"trace[1764022418] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"942.683502ms","start":"2026-04-21T03:25:57.439449Z","end":"2026-04-21T03:25:58.382133Z","steps":["trace[1764022418] 'process raft request' (duration: 942.611841ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:25:58.382198Z","caller":"traceutil/trace.go:171","msg":"trace[1087422709] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"942.928163ms","start":"2026-04-21T03:25:57.439232Z","end":"2026-04-21T03:25:58.38216Z","steps":["trace[1087422709] 'process raft request' (duration: 942.600669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:58.382355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.439442Z","time spent":"942.813402ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:25:58.382393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.439205Z","time spent":"943.066543ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:25:58.382502Z","caller":"traceutil/trace.go:171","msg":"trace[996594480] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"943.134584ms","start":"2026-04-21T03:25:57.439356Z","end":"2026-04-21T03:25:58.382491Z","steps":["trace[996594480] 'process raft request' (duration: 942.659781ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:58.382542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.43935Z","time spent":"943.172424ms","remote":"127.0.0.1:35306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T03:25:58.382555Z","caller":"traceutil/trace.go:171","msg":"trace[1562103557] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:528; }","duration":"941.600142ms","start":"2026-04-21T03:25:57.440922Z","end":"2026-04-21T03:25:58.382522Z","steps":["trace[1562103557] 'read index received' (duration: 656.821168ms)","trace[1562103557] 'applied index is now lower than readState.Index' (duration: 284.778054ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:25:58.382616Z","caller":"traceutil/trace.go:171","msg":"trace[1109818771] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"943.108184ms","start":"2026-04-21T03:25:57.43948Z","end":"2026-04-21T03:25:58.382588Z","steps":["trace[1109818771] 'process raft request' (duration: 942.61403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:58.382749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.439469Z","time spent":"943.206514ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:25:58.382716Z","caller":"traceutil/trace.go:171","msg":"trace[1488715837] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"943.000793ms","start":"2026-04-21T03:25:57.439672Z","end":"2026-04-21T03:25:58.382673Z","steps":["trace[1488715837] 'process raft request' (duration: 942.718611ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:58.382743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.800424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T03:25:58.382954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.439516Z","time spent":"943.296805ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:25:58.382974Z","caller":"traceutil/trace.go:171","msg":"trace[1450147892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:515; }","duration":"942.088726ms","start":"2026-04-21T03:25:57.440874Z","end":"2026-04-21T03:25:58.382962Z","steps":["trace[1450147892] 'agreement among raft nodes before linearized reading' (duration: 941.801284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:25:58.383007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.440861Z","time spent":"942.135937ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:25:59.827141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075739301s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-21T03:26:00.170709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487338073s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:00.171025Z","caller":"traceutil/trace.go:171","msg":"trace[1642227848] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.4197924s","start":"2026-04-21T03:25:58.751214Z","end":"2026-04-21T03:26:00.171006Z","steps":["trace[1642227848] 'process raft request' (duration: 1.419611699s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:00.171116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:58.751162Z","time spent":"1.419910762s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:00.171202Z","caller":"traceutil/trace.go:171","msg":"trace[968986551] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"1.78849551s","start":"2026-04-21T03:25:58.382637Z","end":"2026-04-21T03:26:00.171133Z","steps":["trace[968986551] 'read index received' (duration: 300.636343ms)","trace[968986551] 'applied index is now lower than readState.Index' (duration: 1.487856397s)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:00.171399Z","caller":"traceutil/trace.go:171","msg":"trace[620358267] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.85012376s","start":"2026-04-21T03:25:58.321254Z","end":"2026-04-21T03:26:00.171377Z","steps":["trace[620358267] 'process raft request' (duration: 362.028421ms)","trace[620358267] 'compare' (duration: 1.487066071s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:00.171507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.786337534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:00.171552Z","caller":"traceutil/trace.go:171","msg":"trace[1859698260] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"1.786420644s","start":"2026-04-21T03:25:58.38512Z","end":"2026-04-21T03:26:00.171541Z","steps":["trace[1859698260] 'agreement among raft nodes before linearized reading' (duration: 1.786322323s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:00.171595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:58.385101Z","time spent":"1.786482164s","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:26:00.171692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.72841794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:00.171798Z","caller":"traceutil/trace.go:171","msg":"trace[481059453] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:517; }","duration":"2.728637782s","start":"2026-04-21T03:25:57.443132Z","end":"2026-04-21T03:26:00.171769Z","steps":["trace[481059453] 'agreement among raft nodes before linearized reading' (duration: 2.72837728s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:00.171876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:57.443121Z","time spent":"2.728734173s","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-04-21T03:26:00.171827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:25:58.32122Z","time spent":"1.850261371s","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:04.32021Z","caller":"traceutil/trace.go:171","msg":"trace[1484034589] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"281.710608ms","start":"2026-04-21T03:26:04.038469Z","end":"2026-04-21T03:26:04.32018Z","steps":["trace[1484034589] 'process raft request' (duration: 281.086784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.320537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.065206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.320668Z","caller":"traceutil/trace.go:171","msg":"trace[2081522859] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"260.745584ms","start":"2026-04-21T03:26:04.059394Z","end":"2026-04-21T03:26:04.320139Z","steps":["trace[2081522859] 'read index received' (duration: 259.862898ms)","trace[2081522859] 'applied index is now lower than readState.Index' (duration: 881.796µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:04.320798Z","caller":"traceutil/trace.go:171","msg":"trace[633542724] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"261.444259ms","start":"2026-04-21T03:26:04.05932Z","end":"2026-04-21T03:26:04.320764Z","steps":["trace[633542724] 'agreement among raft nodes before linearized reading' (duration: 260.933276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.213891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321164Z","caller":"traceutil/trace.go:171","msg":"trace[916721805] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:605; }","duration":"214.251531ms","start":"2026-04-21T03:26:04.106899Z","end":"2026-04-21T03:26:04.32115Z","steps":["trace[916721805] 'agreement among raft nodes before linearized reading' (duration: 214.205201ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.969353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321219Z","caller":"traceutil/trace.go:171","msg":"trace[1718505764] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:605; }","duration":"186.096883ms","start":"2026-04-21T03:26:04.1351Z","end":"2026-04-21T03:26:04.321197Z","steps":["trace[1718505764] 'agreement among raft nodes before linearized reading' (duration: 185.908342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.527383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321391Z","caller":"traceutil/trace.go:171","msg":"trace[581125756] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:605; }","duration":"214.589664ms","start":"2026-04-21T03:26:04.106792Z","end":"2026-04-21T03:26:04.321381Z","steps":["trace[581125756] 'agreement among raft nodes before linearized reading' (duration: 214.536903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.665275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321542Z","caller":"traceutil/trace.go:171","msg":"trace[1469207203] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"233.713815ms","start":"2026-04-21T03:26:04.087818Z","end":"2026-04-21T03:26:04.321532Z","steps":["trace[1469207203] 'agreement among raft nodes before linearized reading' (duration: 233.667234ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.570801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321756Z","caller":"traceutil/trace.go:171","msg":"trace[1392497903] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"262.357446ms","start":"2026-04-21T03:26:04.059377Z","end":"2026-04-21T03:26:04.321734Z","steps":["trace[1392497903] 'agreement among raft nodes before linearized reading' (duration: 261.580021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.321087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.887484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.321968Z","caller":"traceutil/trace.go:171","msg":"trace[2035218129] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:605; }","duration":"212.77982ms","start":"2026-04-21T03:26:04.109174Z","end":"2026-04-21T03:26:04.321954Z","steps":["trace[2035218129] 'agreement among raft nodes before linearized reading' (duration: 211.889624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.32183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.120625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T03:26:04.321701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.484026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.322055Z","caller":"traceutil/trace.go:171","msg":"trace[1165495232] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"235.337936ms","start":"2026-04-21T03:26:04.086697Z","end":"2026-04-21T03:26:04.322035Z","steps":["trace[1165495232] 'agreement among raft nodes before linearized reading' (duration: 235.102135ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:04.322091Z","caller":"traceutil/trace.go:171","msg":"trace[1549455179] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:605; }","duration":"186.874279ms","start":"2026-04-21T03:26:04.135203Z","end":"2026-04-21T03:26:04.322077Z","steps":["trace[1549455179] 'agreement among raft nodes before linearized reading' (duration: 186.471116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.32186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.197805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.322225Z","caller":"traceutil/trace.go:171","msg":"trace[1038958879] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:605; }","duration":"235.589048ms","start":"2026-04-21T03:26:04.086626Z","end":"2026-04-21T03:26:04.322215Z","steps":["trace[1038958879] 'agreement among raft nodes before linearized reading' (duration: 235.221535ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:04.322522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.454956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:04.322629Z","caller":"traceutil/trace.go:171","msg":"trace[1402212997] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:605; }","duration":"186.724558ms","start":"2026-04-21T03:26:04.135881Z","end":"2026-04-21T03:26:04.322605Z","steps":["trace[1402212997] 'agreement among raft nodes before linearized reading' (duration: 185.797462ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:04.510622Z","caller":"traceutil/trace.go:171","msg":"trace[1511520848] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"133.714136ms","start":"2026-04-21T03:26:04.376878Z","end":"2026-04-21T03:26:04.510592Z","steps":["trace[1511520848] 'process raft request' (duration: 62.42043ms)","trace[1511520848] 'compare' (duration: 70.399579ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:05.391593Z","caller":"traceutil/trace.go:171","msg":"trace[374818466] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"414.536644ms","start":"2026-04-21T03:26:04.977023Z","end":"2026-04-21T03:26:05.39156Z","steps":["trace[374818466] 'process raft request' (duration: 409.796649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:05.391767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:04.976997Z","time spent":"414.678855ms","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:05.841239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.093316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:05.841346Z","caller":"traceutil/trace.go:171","msg":"trace[981901594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"112.267047ms","start":"2026-04-21T03:26:05.729053Z","end":"2026-04-21T03:26:05.84132Z","steps":["trace[981901594] 'range keys from in-memory index tree' (duration: 111.948205ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.180945Z","caller":"traceutil/trace.go:171","msg":"trace[1087264327] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"212.699345ms","start":"2026-04-21T03:26:05.968188Z","end":"2026-04-21T03:26:06.180887Z","steps":["trace[1087264327] 'process raft request' (duration: 211.555056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.708996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.288632ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:06.709289Z","caller":"traceutil/trace.go:171","msg":"trace[220097851] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:637; }","duration":"180.82626ms","start":"2026-04-21T03:26:06.528442Z","end":"2026-04-21T03:26:06.709268Z","steps":["trace[220097851] 'read index received' (duration: 62.921µs)","trace[220097851] 'applied index is now lower than readState.Index' (duration: 180.761259ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:06.709394Z","caller":"traceutil/trace.go:171","msg":"trace[644783725] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"478.277309ms","start":"2026-04-21T03:26:06.230992Z","end":"2026-04-21T03:26:06.70927Z","steps":["trace[644783725] 'process raft request' (duration: 212.594444ms)","trace[644783725] 'compare' (duration: 264.97809ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:06.709569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:06.230959Z","time spent":"478.52905ms","remote":"127.0.0.1:35550","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T03:26:06.709701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.574478ms","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-21T03:26:06.709826Z","caller":"traceutil/trace.go:171","msg":"trace[1297291436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"180.729209ms","start":"2026-04-21T03:26:06.52907Z","end":"2026-04-21T03:26:06.709799Z","steps":["trace[1297291436] 'agreement among raft nodes before linearized reading' (duration: 180.445137ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.70978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.157475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T03:26:06.709974Z","caller":"traceutil/trace.go:171","msg":"trace[1817709455] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:614; }","duration":"131.426217ms","start":"2026-04-21T03:26:06.578533Z","end":"2026-04-21T03:26:06.70996Z","steps":["trace[1817709455] 'agreement among raft nodes before linearized reading' (duration: 131.102174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.709879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.808944ms","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-21T03:26:06.71013Z","caller":"traceutil/trace.go:171","msg":"trace[76187423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"168.092147ms","start":"2026-04-21T03:26:06.541994Z","end":"2026-04-21T03:26:06.710087Z","steps":["trace[76187423] 'agreement among raft nodes before linearized reading' (duration: 167.580763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.709981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.458361ms","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-21T03:26:06.710195Z","caller":"traceutil/trace.go:171","msg":"trace[1908622732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"167.696312ms","start":"2026-04-21T03:26:06.542484Z","end":"2026-04-21T03:26:06.710181Z","steps":["trace[1908622732] 'agreement among raft nodes before linearized reading' (duration: 167.062908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.710278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.780576ms","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-21T03:26:06.710437Z","caller":"traceutil/trace.go:171","msg":"trace[1411617594] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"182.204301ms","start":"2026-04-21T03:26:06.528206Z","end":"2026-04-21T03:26:06.71041Z","steps":["trace[1411617594] 'agreement among raft nodes before linearized reading' (duration: 181.254443ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.902176Z","caller":"traceutil/trace.go:171","msg":"trace[1863271323] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"187.343328ms","start":"2026-04-21T03:26:06.714803Z","end":"2026-04-21T03:26:06.902147Z","steps":["trace[1863271323] 'process raft request' (duration: 177.869859ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.902246Z","caller":"traceutil/trace.go:171","msg":"trace[1990527231] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"187.028056ms","start":"2026-04-21T03:26:06.715182Z","end":"2026-04-21T03:26:06.90221Z","steps":["trace[1990527231] 'process raft request' (duration: 186.838285ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.902493Z","caller":"traceutil/trace.go:171","msg":"trace[620635209] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"187.222437ms","start":"2026-04-21T03:26:06.715259Z","end":"2026-04-21T03:26:06.902482Z","steps":["trace[620635209] 'process raft request' (duration: 186.896205ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.902558Z","caller":"traceutil/trace.go:171","msg":"trace[544991292] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"146.597979ms","start":"2026-04-21T03:26:06.755926Z","end":"2026-04-21T03:26:06.902524Z","steps":["trace[544991292] 'process raft request' (duration: 146.547208ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:06.902616Z","caller":"traceutil/trace.go:171","msg":"trace[1020152626] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:638; }","duration":"181.074402ms","start":"2026-04-21T03:26:06.721507Z","end":"2026-04-21T03:26:06.902582Z","steps":["trace[1020152626] 'read index received' (duration: 32.855781ms)","trace[1020152626] 'applied index is now lower than readState.Index' (duration: 148.215461ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:06.902617Z","caller":"traceutil/trace.go:171","msg":"trace[1445228654] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"187.284858ms","start":"2026-04-21T03:26:06.715287Z","end":"2026-04-21T03:26:06.902572Z","steps":["trace[1445228654] 'process raft request' (duration: 187.092186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:06.90288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.343405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:06.902923Z","caller":"traceutil/trace.go:171","msg":"trace[1345352868] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:619; }","duration":"181.435425ms","start":"2026-04-21T03:26:06.72147Z","end":"2026-04-21T03:26:06.902906Z","steps":["trace[1345352868] 'agreement among raft nodes before linearized reading' (duration: 181.216794ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:07.423655Z","caller":"traceutil/trace.go:171","msg":"trace[1796422663] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"114.721702ms","start":"2026-04-21T03:26:07.308905Z","end":"2026-04-21T03:26:07.423627Z","steps":["trace[1796422663] 'process raft request' (duration: 114.136768ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:07.653647Z","caller":"traceutil/trace.go:171","msg":"trace[2055019529] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"172.850189ms","start":"2026-04-21T03:26:07.480769Z","end":"2026-04-21T03:26:07.653619Z","steps":["trace[2055019529] 'process raft request' (duration: 172.669588ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:07.693941Z","caller":"traceutil/trace.go:171","msg":"trace[1101613936] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"112.038663ms","start":"2026-04-21T03:26:07.581876Z","end":"2026-04-21T03:26:07.693915Z","steps":["trace[1101613936] 'process raft request' (duration: 111.762631ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:08.257392Z","caller":"traceutil/trace.go:171","msg":"trace[1320021813] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"153.689868ms","start":"2026-04-21T03:26:08.103685Z","end":"2026-04-21T03:26:08.257375Z","steps":["trace[1320021813] 'process raft request' (duration: 148.847602ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:09.067721Z","caller":"traceutil/trace.go:171","msg":"trace[256554942] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"147.556302ms","start":"2026-04-21T03:26:08.920143Z","end":"2026-04-21T03:26:09.0677Z","steps":["trace[256554942] 'process raft request' (duration: 105.440704ms)","trace[256554942] 'compare' (duration: 41.875466ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:09.067905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.626684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-21T03:26:09.06791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.105552ms","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-21T03:26:09.068012Z","caller":"traceutil/trace.go:171","msg":"trace[289069810] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:629; }","duration":"150.750045ms","start":"2026-04-21T03:26:08.917236Z","end":"2026-04-21T03:26:09.067986Z","steps":["trace[289069810] 'agreement among raft nodes before linearized reading' (duration: 150.588124ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:09.0682Z","caller":"traceutil/trace.go:171","msg":"trace[1968130639] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"153.256303ms","start":"2026-04-21T03:26:08.914763Z","end":"2026-04-21T03:26:09.06802Z","steps":["trace[1968130639] 'agreement among raft nodes before linearized reading' (duration: 152.973021ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:09.826391Z","caller":"traceutil/trace.go:171","msg":"trace[2081679919] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"122.418266ms","start":"2026-04-21T03:26:09.703948Z","end":"2026-04-21T03:26:09.826366Z","steps":["trace[2081679919] 'read index received' (duration: 122.300635ms)","trace[2081679919] 'applied index is now lower than readState.Index' (duration: 116.711µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:09.82645Z","caller":"traceutil/trace.go:171","msg":"trace[1402258704] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"263.332389ms","start":"2026-04-21T03:26:09.563086Z","end":"2026-04-21T03:26:09.826419Z","steps":["trace[1402258704] 'process raft request' (duration: 263.128737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:09.826561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.587128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:09.826809Z","caller":"traceutil/trace.go:171","msg":"trace[998563033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"122.90141ms","start":"2026-04-21T03:26:09.703891Z","end":"2026-04-21T03:26:09.826793Z","steps":["trace[998563033] 'agreement among raft nodes before linearized reading' (duration: 122.576588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:10.891415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T03:26:11.392097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827562,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T03:26:11.734158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525983454s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:11.734288Z","caller":"traceutil/trace.go:171","msg":"trace[1322771488] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.875256481s","start":"2026-04-21T03:26:09.859007Z","end":"2026-04-21T03:26:11.734263Z","steps":["trace[1322771488] 'process raft request' (duration: 349.090285ms)","trace[1322771488] 'compare' (duration: 1.525836303s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:11.734369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:09.858983Z","time spent":"1.875349621s","remote":"127.0.0.1:35306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-21T03:26:11.777232Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.569032009s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T03:26:11.777795Z","caller":"traceutil/trace.go:171","msg":"trace[609891069] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.917937502s","start":"2026-04-21T03:26:09.85983Z","end":"2026-04-21T03:26:11.777768Z","steps":["trace[609891069] 'process raft request' (duration: 1.91769566s)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:11.777784Z","caller":"traceutil/trace.go:171","msg":"trace[1366384839] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.916983635s","start":"2026-04-21T03:26:09.860777Z","end":"2026-04-21T03:26:11.777761Z","steps":["trace[1366384839] 'process raft request' (duration: 1.916913874s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.778014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:09.859814Z","time spent":"1.918079473s","remote":"127.0.0.1:35468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:11.778045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:09.860766Z","time spent":"1.917158026s","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:11.777726Z","caller":"traceutil/trace.go:171","msg":"trace[1618270584] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.918089662s","start":"2026-04-21T03:26:09.8596Z","end":"2026-04-21T03:26:11.777689Z","steps":["trace[1618270584] 'process raft request' (duration: 1.9178126s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.778606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:09.859582Z","time spent":"1.918972499s","remote":"127.0.0.1:35306","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-21T03:26:11.812593Z","caller":"traceutil/trace.go:171","msg":"trace[1032346249] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:663; }","duration":"1.42160759s","start":"2026-04-21T03:26:10.390914Z","end":"2026-04-21T03:26:11.812521Z","steps":["trace[1032346249] 'read index received' (duration: 1.386651805s)","trace[1032346249] 'applied index is now lower than readState.Index' (duration: 34.954625ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:11.812674Z","caller":"traceutil/trace.go:171","msg":"trace[2074198264] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.505804506s","start":"2026-04-21T03:26:10.306827Z","end":"2026-04-21T03:26:11.812631Z","steps":["trace[2074198264] 'process raft request' (duration: 1.505567364s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.81282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:10.306796Z","time spent":"1.505943348s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:11.812881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.421957414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j9rfs\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-21T03:26:11.812884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113528367s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:11.812948Z","caller":"traceutil/trace.go:171","msg":"trace[1819604345] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j9rfs; range_end:; response_count:1; response_revision:641; }","duration":"1.422049374s","start":"2026-04-21T03:26:10.390858Z","end":"2026-04-21T03:26:11.812908Z","steps":["trace[1819604345] 'agreement among raft nodes before linearized reading' (duration: 1.421839532s)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:11.812982Z","caller":"traceutil/trace.go:171","msg":"trace[1295452872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"1.113680078s","start":"2026-04-21T03:26:10.699279Z","end":"2026-04-21T03:26:11.812959Z","steps":["trace[1295452872] 'agreement among raft nodes before linearized reading' (duration: 1.113513117s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.812994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:10.390837Z","time spent":"1.422144934s","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-j9rfs\" "} {"level":"warn","ts":"2026-04-21T03:26:11.813011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.768069ms","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-21T03:26:11.813013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.851222ms","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-21T03:26:11.813034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:10.699254Z","time spent":"1.1137641s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T03:26:11.813056Z","caller":"traceutil/trace.go:171","msg":"trace[469041003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"741.80999ms","start":"2026-04-21T03:26:11.07123Z","end":"2026-04-21T03:26:11.81304Z","steps":["trace[469041003] 'agreement among raft nodes before linearized reading' (duration: 741.627719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.813053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.352348ms","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-21T03:26:11.81309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.071187Z","time spent":"741.89536ms","remote":"127.0.0.1:35490","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-21T03:26:11.813084Z","caller":"traceutil/trace.go:171","msg":"trace[553901644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"736.955294ms","start":"2026-04-21T03:26:11.076107Z","end":"2026-04-21T03:26:11.813063Z","steps":["trace[553901644] 'agreement among raft nodes before linearized reading' (duration: 736.736272ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:11.813095Z","caller":"traceutil/trace.go:171","msg":"trace[566805581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"822.438489ms","start":"2026-04-21T03:26:10.990648Z","end":"2026-04-21T03:26:11.813086Z","steps":["trace[566805581] 'agreement among raft nodes before linearized reading' (duration: 822.327668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.813123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:10.990622Z","time spent":"822.495959ms","remote":"127.0.0.1:35490","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-21T03:26:11.813129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.076093Z","time spent":"737.027374ms","remote":"127.0.0.1:35490","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-21T03:26:11.813534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.064631ms","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-21T03:26:11.813572Z","caller":"traceutil/trace.go:171","msg":"trace[1991737458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"742.146681ms","start":"2026-04-21T03:26:11.071417Z","end":"2026-04-21T03:26:11.813563Z","steps":["trace[1991737458] 'agreement among raft nodes before linearized reading' (duration: 742.024001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:11.813595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.071404Z","time spent":"742.185252ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-21T03:26:13.821968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000549477s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-21T03:26:13.822115Z","caller":"traceutil/trace.go:171","msg":"trace[1847588067] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000761158s","start":"2026-04-21T03:26:11.82133Z","end":"2026-04-21T03:26:13.822091Z","steps":["trace[1847588067] 'agreement among raft nodes before linearized reading' (duration: 2.000576747s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:13.822177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.821313Z","time spent":"2.00084978s","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:26:14.066995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.228454167s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:14.067116Z","caller":"traceutil/trace.go:171","msg":"trace[39850297] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.250484528s","start":"2026-04-21T03:26:11.81661Z","end":"2026-04-21T03:26:14.067095Z","steps":["trace[39850297] 'process raft request' (duration: 21.747409ms)","trace[39850297] 'compare' (duration: 2.227416779s)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:14.067183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.816591Z","time spent":"2.250557719s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:14.740961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.902544292s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T03:26:14.782569Z","caller":"traceutil/trace.go:171","msg":"trace[329488302] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"2.961162709s","start":"2026-04-21T03:26:11.821382Z","end":"2026-04-21T03:26:14.782545Z","steps":["trace[329488302] 'read index received' (duration: 16.985394ms)","trace[329488302] 'applied index is now lower than readState.Index' (duration: 2.944176345s)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:14.782672Z","caller":"traceutil/trace.go:171","msg":"trace[1484338994] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.965775563s","start":"2026-04-21T03:26:11.816884Z","end":"2026-04-21T03:26:14.78266Z","steps":["trace[1484338994] 'process raft request' (duration: 2.924301881s)","trace[1484338994] 'compare' (duration: 41.103509ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:14.782768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.816864Z","time spent":"2.965834583s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:14.782757Z","caller":"traceutil/trace.go:171","msg":"trace[1434239676] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.964607794s","start":"2026-04-21T03:26:11.818101Z","end":"2026-04-21T03:26:14.782708Z","steps":["trace[1434239676] 'process raft request' (duration: 2.964354913s)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:14.782741Z","caller":"traceutil/trace.go:171","msg":"trace[1777372015] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.964911886s","start":"2026-04-21T03:26:11.817802Z","end":"2026-04-21T03:26:14.782714Z","steps":["trace[1777372015] 'process raft request' (duration: 2.964610344s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:14.782928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.818064Z","time spent":"2.964754405s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:14.782963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.817786Z","time spent":"2.965056067s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:14.783064Z","caller":"traceutil/trace.go:171","msg":"trace[1347738191] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.956773457s","start":"2026-04-21T03:26:11.826278Z","end":"2026-04-21T03:26:14.783052Z","steps":["trace[1347738191] 'process raft request' (duration: 2.956217623s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:14.783133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:11.826265Z","time spent":"2.956834877s","remote":"127.0.0.1:35406","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:14.787467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.065435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:14.787552Z","caller":"traceutil/trace.go:171","msg":"trace[419679608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"965.183786ms","start":"2026-04-21T03:26:13.822347Z","end":"2026-04-21T03:26:14.787531Z","steps":["trace[419679608] 'agreement among raft nodes before linearized reading' (duration: 965.059795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:14.787598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:13.822313Z","time spent":"965.274736ms","remote":"127.0.0.1:35210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:26:16.968303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.241633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:16.968421Z","caller":"traceutil/trace.go:171","msg":"trace[639277012] linearizableReadLoop","detail":"{readStateIndex:692; appliedIndex:691; }","duration":"389.081025ms","start":"2026-04-21T03:26:16.579326Z","end":"2026-04-21T03:26:16.968407Z","steps":["trace[639277012] 'read index received' (duration: 272.60025ms)","trace[639277012] 'applied index is now lower than readState.Index' (duration: 116.479685ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:16.968482Z","caller":"traceutil/trace.go:171","msg":"trace[1280526985] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"424.27597ms","start":"2026-04-21T03:26:16.544198Z","end":"2026-04-21T03:26:16.968474Z","steps":["trace[1280526985] 'process raft request' (duration: 307.785695ms)","trace[1280526985] 'compare' (duration: 116.134073ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:16.968569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:16.544181Z","time spent":"424.32777ms","remote":"127.0.0.1:35270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:16.968827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.488557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-21T03:26:16.968883Z","caller":"traceutil/trace.go:171","msg":"trace[1631658777] range","detail":"{range_begin:/registry/masterleases/199.204.45.70; range_end:; response_count:1; response_revision:666; }","duration":"389.597089ms","start":"2026-04-21T03:26:16.579275Z","end":"2026-04-21T03:26:16.968872Z","steps":["trace[1631658777] 'agreement among raft nodes before linearized reading' (duration: 389.341016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:16.968949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:16.579226Z","time spent":"389.71634ms","remote":"127.0.0.1:35250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.70\" "} {"level":"info","ts":"2026-04-21T03:26:16.973429Z","caller":"traceutil/trace.go:171","msg":"trace[739005627] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"261.541568ms","start":"2026-04-21T03:26:16.711855Z","end":"2026-04-21T03:26:16.973397Z","steps":["trace[739005627] 'process raft request' (duration: 261.356217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:16.977575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.95861ms","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-21T03:26:16.977634Z","caller":"traceutil/trace.go:171","msg":"trace[701605410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"190.10623ms","start":"2026-04-21T03:26:16.787513Z","end":"2026-04-21T03:26:16.977619Z","steps":["trace[701605410] 'agreement among raft nodes before linearized reading' (duration: 189.902199ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:16.977618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.446173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-21T03:26:16.977709Z","caller":"traceutil/trace.go:171","msg":"trace[417780567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"190.609254ms","start":"2026-04-21T03:26:16.787081Z","end":"2026-04-21T03:26:16.97769Z","steps":["trace[417780567] 'agreement among raft nodes before linearized reading' (duration: 190.351852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:16.97794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.735185ms","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-21T03:26:16.978023Z","caller":"traceutil/trace.go:171","msg":"trace[431708263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"190.874176ms","start":"2026-04-21T03:26:16.787134Z","end":"2026-04-21T03:26:16.978008Z","steps":["trace[431708263] 'agreement among raft nodes before linearized reading' (duration: 190.609974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:16.977787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.002269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:16.978186Z","caller":"traceutil/trace.go:171","msg":"trace[2065738598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"274.490783ms","start":"2026-04-21T03:26:16.703678Z","end":"2026-04-21T03:26:16.978169Z","steps":["trace[2065738598] 'agreement among raft nodes before linearized reading' (duration: 273.729638ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:25.135588Z","caller":"traceutil/trace.go:171","msg":"trace[1599648727] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"109.191496ms","start":"2026-04-21T03:26:25.026373Z","end":"2026-04-21T03:26:25.135565Z","steps":["trace[1599648727] 'process raft request' (duration: 109.032684ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:25.227082Z","caller":"traceutil/trace.go:171","msg":"trace[177150794] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:788; }","duration":"189.0632ms","start":"2026-04-21T03:26:25.037986Z","end":"2026-04-21T03:26:25.227049Z","steps":["trace[177150794] 'read index received' (duration: 97.471572ms)","trace[177150794] 'applied index is now lower than readState.Index' (duration: 91.590248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:25.227479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.471474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T03:26:25.22761Z","caller":"traceutil/trace.go:171","msg":"trace[505537726] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:762; }","duration":"189.611685ms","start":"2026-04-21T03:26:25.037961Z","end":"2026-04-21T03:26:25.227572Z","steps":["trace[505537726] 'agreement among raft nodes before linearized reading' (duration: 189.443843ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:25.227769Z","caller":"traceutil/trace.go:171","msg":"trace[1835143061] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"199.310664ms","start":"2026-04-21T03:26:25.028437Z","end":"2026-04-21T03:26:25.227747Z","steps":["trace[1835143061] 'process raft request' (duration: 198.523807ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:25.227727Z","caller":"traceutil/trace.go:171","msg":"trace[2020544627] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"199.954659ms","start":"2026-04-21T03:26:25.027679Z","end":"2026-04-21T03:26:25.227634Z","steps":["trace[2020544627] 'process raft request' (duration: 157.17875ms)","trace[2020544627] 'compare' (duration: 41.997803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:28.629243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.187669ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:28.629513Z","caller":"traceutil/trace.go:171","msg":"trace[1566633946] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"235.526381ms","start":"2026-04-21T03:26:28.393967Z","end":"2026-04-21T03:26:28.629494Z","steps":["trace[1566633946] 'read index received' (duration: 59.97236ms)","trace[1566633946] 'applied index is now lower than readState.Index' (duration: 175.552431ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:28.629592Z","caller":"traceutil/trace.go:171","msg":"trace[2028646283] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"271.099186ms","start":"2026-04-21T03:26:28.358446Z","end":"2026-04-21T03:26:28.629545Z","steps":["trace[2028646283] 'process raft request' (duration: 95.524345ms)","trace[2028646283] 'compare' (duration: 175.062368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:28.629643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.650382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:28.629678Z","caller":"traceutil/trace.go:171","msg":"trace[1973504945] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:787; }","duration":"235.704862ms","start":"2026-04-21T03:26:28.393962Z","end":"2026-04-21T03:26:28.629667Z","steps":["trace[1973504945] 'agreement among raft nodes before linearized reading' (duration: 235.628922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.202844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827854,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T03:26:29.600996Z","caller":"traceutil/trace.go:171","msg":"trace[980113850] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"899.01903ms","start":"2026-04-21T03:26:28.701956Z","end":"2026-04-21T03:26:29.600975Z","steps":["trace[980113850] 'read index received' (duration: 898.993139ms)","trace[980113850] 'applied index is now lower than readState.Index' (duration: 24.731µs)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:29.601125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.15282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:29.601119Z","caller":"traceutil/trace.go:171","msg":"trace[166127929] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"966.809286ms","start":"2026-04-21T03:26:28.634283Z","end":"2026-04-21T03:26:29.601092Z","steps":["trace[166127929] 'process raft request' (duration: 966.560745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.601284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:28.634234Z","time spent":"966.946647ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:29.601156Z","caller":"traceutil/trace.go:171","msg":"trace[1379147979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"899.223951ms","start":"2026-04-21T03:26:28.701923Z","end":"2026-04-21T03:26:29.601147Z","steps":["trace[1379147979] 'agreement among raft nodes before linearized reading' (duration: 899.16269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.601477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:28.701905Z","time spent":"899.465182ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-21T03:26:29.613327Z","caller":"traceutil/trace.go:171","msg":"trace[570017682] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"268.380865ms","start":"2026-04-21T03:26:29.344932Z","end":"2026-04-21T03:26:29.613312Z","steps":["trace[570017682] 'process raft request' (duration: 268.148273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.613391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.810585ms","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-21T03:26:29.61344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.455987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-21T03:26:29.613494Z","caller":"traceutil/trace.go:171","msg":"trace[2521501] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:789; }","duration":"448.583137ms","start":"2026-04-21T03:26:29.164898Z","end":"2026-04-21T03:26:29.613481Z","steps":["trace[2521501] 'agreement among raft nodes before linearized reading' (duration: 448.452246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.613543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:29.164874Z","time spent":"448.659147ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-21T03:26:29.613495Z","caller":"traceutil/trace.go:171","msg":"trace[848396605] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:789; }","duration":"368.969856ms","start":"2026-04-21T03:26:29.244496Z","end":"2026-04-21T03:26:29.613466Z","steps":["trace[848396605] 'agreement among raft nodes before linearized reading' (duration: 368.741914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.613584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.347276ms","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-21T03:26:29.613647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:29.244475Z","time spent":"369.114837ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-21T03:26:29.613737Z","caller":"traceutil/trace.go:171","msg":"trace[634895023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:789; }","duration":"370.700758ms","start":"2026-04-21T03:26:29.242994Z","end":"2026-04-21T03:26:29.613694Z","steps":["trace[634895023] 'agreement among raft nodes before linearized reading' (duration: 370.249315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:29.613818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:29.242963Z","time spent":"370.815029ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-21T03:26:33.204635Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431827896,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-21T03:26:33.56552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365151946s","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-21T03:26:33.565608Z","caller":"traceutil/trace.go:171","msg":"trace[1157482096] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"1.365288587s","start":"2026-04-21T03:26:32.200301Z","end":"2026-04-21T03:26:33.565589Z","steps":["trace[1157482096] 'range keys from in-memory index tree' (duration: 1.364982896s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:33.565816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:32.200279Z","time spent":"1.365362548s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-21T03:26:33.576308Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.252802163s","expected-duration":"1s"} {"level":"info","ts":"2026-04-21T03:26:33.57714Z","caller":"traceutil/trace.go:171","msg":"trace[233112688] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"1.253478237s","start":"2026-04-21T03:26:32.323361Z","end":"2026-04-21T03:26:33.576839Z","steps":["trace[233112688] 'process raft request' (duration: 1.253286426s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:33.577374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:32.323325Z","time spent":"1.25390355s","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:26:33.591241Z","caller":"traceutil/trace.go:171","msg":"trace[551859728] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:837; }","duration":"887.645009ms","start":"2026-04-21T03:26:32.703578Z","end":"2026-04-21T03:26:33.591223Z","steps":["trace[551859728] 'read index received' (duration: 872.865424ms)","trace[551859728] 'applied index is now lower than readState.Index' (duration: 14.778585ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:33.591297Z","caller":"traceutil/trace.go:171","msg":"trace[1448862876] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.069849004s","start":"2026-04-21T03:26:32.521422Z","end":"2026-04-21T03:26:33.591271Z","steps":["trace[1448862876] 'process raft request' (duration: 1.069664572s)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:33.591363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.77175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:26:33.591404Z","caller":"traceutil/trace.go:171","msg":"trace[2094648356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"887.888721ms","start":"2026-04-21T03:26:32.703503Z","end":"2026-04-21T03:26:33.591392Z","steps":["trace[2094648356] 'agreement among raft nodes before linearized reading' (duration: 887.790191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:33.591446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:32.703473Z","time spent":"887.962662ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:26:33.591493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:26:32.52139Z","time spent":"1.070003934s","remote":"127.0.0.1:35468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:26:47.985673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.614699ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:26:47.985797Z","caller":"traceutil/trace.go:171","msg":"trace[574700565] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"259.943215ms","start":"2026-04-21T03:26:47.725836Z","end":"2026-04-21T03:26:47.985779Z","steps":["trace[574700565] 'read index received' (duration: 88.044205ms)","trace[574700565] 'applied index is now lower than readState.Index' (duration: 171.89733ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:26:47.986093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.045126ms","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-21T03:26:47.986201Z","caller":"traceutil/trace.go:171","msg":"trace[1068164034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:968; }","duration":"260.176827ms","start":"2026-04-21T03:26:47.725998Z","end":"2026-04-21T03:26:47.986174Z","steps":["trace[1068164034] 'agreement among raft nodes before linearized reading' (duration: 259.952746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:47.98648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.63909ms","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-21T03:26:47.986518Z","caller":"traceutil/trace.go:171","msg":"trace[457918166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:968; }","duration":"260.6974ms","start":"2026-04-21T03:26:47.72581Z","end":"2026-04-21T03:26:47.986508Z","steps":["trace[457918166] 'agreement among raft nodes before linearized reading' (duration: 260.6007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:26:47.987175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.607085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:1 size:1464"} {"level":"info","ts":"2026-04-21T03:26:47.987236Z","caller":"traceutil/trace.go:171","msg":"trace[1027028901] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:1; response_revision:968; }","duration":"241.712475ms","start":"2026-04-21T03:26:47.745507Z","end":"2026-04-21T03:26:47.987219Z","steps":["trace[1027028901] 'agreement among raft nodes before linearized reading' (duration: 241.461823ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:26:47.991047Z","caller":"traceutil/trace.go:171","msg":"trace[83320115] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"261.284185ms","start":"2026-04-21T03:26:47.724594Z","end":"2026-04-21T03:26:47.985878Z","steps":["trace[83320115] 'process raft request' (duration: 89.369384ms)","trace[83320115] 'compare' (duration: 171.453978ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:58.959704Z","caller":"traceutil/trace.go:171","msg":"trace[1470092115] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"142.099735ms","start":"2026-04-21T03:26:58.817574Z","end":"2026-04-21T03:26:58.959674Z","steps":["trace[1470092115] 'process raft request' (duration: 121.167818ms)","trace[1470092115] 'compare' (duration: 20.776686ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:26:59.965926Z","caller":"traceutil/trace.go:171","msg":"trace[606332624] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"181.549343ms","start":"2026-04-21T03:26:59.784358Z","end":"2026-04-21T03:26:59.965907Z","steps":["trace[606332624] 'process raft request' (duration: 180.523506ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:27:55.163975Z","caller":"traceutil/trace.go:171","msg":"trace[1642559197] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"111.879204ms","start":"2026-04-21T03:27:55.052052Z","end":"2026-04-21T03:27:55.163931Z","steps":["trace[1642559197] 'process raft request' (duration: 111.739383ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:27:55.259528Z","caller":"traceutil/trace.go:171","msg":"trace[1552611151] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"147.958928ms","start":"2026-04-21T03:27:55.111536Z","end":"2026-04-21T03:27:55.259495Z","steps":["trace[1552611151] 'process raft request' (duration: 135.806622ms)","trace[1552611151] 'compare' (duration: 11.968954ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:08.643916Z","caller":"traceutil/trace.go:171","msg":"trace[731541964] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"119.580218ms","start":"2026-04-21T03:28:08.524271Z","end":"2026-04-21T03:28:08.643851Z","steps":["trace[731541964] 'process raft request' (duration: 119.246505ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:13.069041Z","caller":"traceutil/trace.go:171","msg":"trace[112975954] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"116.96253ms","start":"2026-04-21T03:28:12.95205Z","end":"2026-04-21T03:28:13.069013Z","steps":["trace[112975954] 'process raft request' (duration: 64.054739ms)","trace[112975954] 'compare' (duration: 52.72951ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:13.647535Z","caller":"traceutil/trace.go:171","msg":"trace[2026479562] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"129.505018ms","start":"2026-04-21T03:28:13.518007Z","end":"2026-04-21T03:28:13.647512Z","steps":["trace[2026479562] 'process raft request' (duration: 129.356367ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:13.948597Z","caller":"traceutil/trace.go:171","msg":"trace[267803738] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"166.669957ms","start":"2026-04-21T03:28:13.781896Z","end":"2026-04-21T03:28:13.948566Z","steps":["trace[267803738] 'process raft request' (duration: 160.461414ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:13.954475Z","caller":"traceutil/trace.go:171","msg":"trace[1228747375] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1327; }","duration":"166.421976ms","start":"2026-04-21T03:28:13.788026Z","end":"2026-04-21T03:28:13.954448Z","steps":["trace[1228747375] 'read index received' (duration: 154.343681ms)","trace[1228747375] 'applied index is now lower than readState.Index' (duration: 12.077095ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:13.954499Z","caller":"traceutil/trace.go:171","msg":"trace[1565732174] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"167.233071ms","start":"2026-04-21T03:28:13.787244Z","end":"2026-04-21T03:28:13.954477Z","steps":["trace[1565732174] 'process raft request' (duration: 167.07616ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:13.954622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.578187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:28:13.954693Z","caller":"traceutil/trace.go:171","msg":"trace[1866144809] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:1274; }","duration":"166.660447ms","start":"2026-04-21T03:28:13.788018Z","end":"2026-04-21T03:28:13.954679Z","steps":["trace[1866144809] 'agreement among raft nodes before linearized reading' (duration: 166.527876ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:14.209281Z","caller":"traceutil/trace.go:171","msg":"trace[1520891593] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"178.820903ms","start":"2026-04-21T03:28:14.030437Z","end":"2026-04-21T03:28:14.209258Z","steps":["trace[1520891593] 'read index received' (duration: 178.41703ms)","trace[1520891593] 'applied index is now lower than readState.Index' (duration: 402.893µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:14.209366Z","caller":"traceutil/trace.go:171","msg":"trace[387633940] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"183.207613ms","start":"2026-04-21T03:28:14.026114Z","end":"2026-04-21T03:28:14.209321Z","steps":["trace[387633940] 'process raft request' (duration: 182.856811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:14.209416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.959334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c7b969a-eb3e-4a39-8393-c45880159f83\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-21T03:28:14.209456Z","caller":"traceutil/trace.go:171","msg":"trace[1991468716] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c7b969a-eb3e-4a39-8393-c45880159f83; range_end:; response_count:1; response_revision:1276; }","duration":"179.037864ms","start":"2026-04-21T03:28:14.030407Z","end":"2026-04-21T03:28:14.209445Z","steps":["trace[1991468716] 'agreement among raft nodes before linearized reading' (duration: 178.943674ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:14.40088Z","caller":"traceutil/trace.go:171","msg":"trace[235715132] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"187.00091ms","start":"2026-04-21T03:28:14.213857Z","end":"2026-04-21T03:28:14.400858Z","steps":["trace[235715132] 'read index received' (duration: 186.806959ms)","trace[235715132] 'applied index is now lower than readState.Index' (duration: 192.821µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:14.400984Z","caller":"traceutil/trace.go:171","msg":"trace[1363895711] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"187.172762ms","start":"2026-04-21T03:28:14.213777Z","end":"2026-04-21T03:28:14.400949Z","steps":["trace[1363895711] 'process raft request' (duration: 186.86109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:14.401121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.240692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6c7b969a-eb3e-4a39-8393-c45880159f83\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-21T03:28:14.401169Z","caller":"traceutil/trace.go:171","msg":"trace[56448069] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6c7b969a-eb3e-4a39-8393-c45880159f83; range_end:; response_count:1; response_revision:1277; }","duration":"187.322112ms","start":"2026-04-21T03:28:14.213831Z","end":"2026-04-21T03:28:14.401154Z","steps":["trace[56448069] 'agreement among raft nodes before linearized reading' (duration: 187.152521ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:14.905706Z","caller":"traceutil/trace.go:171","msg":"trace[287610128] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"160.783577ms","start":"2026-04-21T03:28:14.744892Z","end":"2026-04-21T03:28:14.905675Z","steps":["trace[287610128] 'process raft request' (duration: 111.651093ms)","trace[287610128] 'compare' (duration: 48.657551ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:28:14.905986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.029195ms","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-21T03:28:14.906071Z","caller":"traceutil/trace.go:171","msg":"trace[1442110191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1279; }","duration":"152.156226ms","start":"2026-04-21T03:28:14.753903Z","end":"2026-04-21T03:28:14.906059Z","steps":["trace[1442110191] 'agreement among raft nodes before linearized reading' (duration: 151.833944ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:14.905701Z","caller":"traceutil/trace.go:171","msg":"trace[1739689541] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1333; }","duration":"151.405141ms","start":"2026-04-21T03:28:14.753947Z","end":"2026-04-21T03:28:14.905352Z","steps":["trace[1739689541] 'read index received' (duration: 102.553798ms)","trace[1739689541] 'applied index is now lower than readState.Index' (duration: 48.850133ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:28:14.906813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.48869ms","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-21T03:28:14.906894Z","caller":"traceutil/trace.go:171","msg":"trace[519589480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1279; }","duration":"125.61085ms","start":"2026-04-21T03:28:14.781271Z","end":"2026-04-21T03:28:14.906882Z","steps":["trace[519589480] 'agreement among raft nodes before linearized reading' (duration: 125.371149ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:15.280895Z","caller":"traceutil/trace.go:171","msg":"trace[2084978786] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"148.716822ms","start":"2026-04-21T03:28:15.132141Z","end":"2026-04-21T03:28:15.280857Z","steps":["trace[2084978786] 'process raft request' (duration: 148.43879ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:15.963247Z","caller":"traceutil/trace.go:171","msg":"trace[1292535046] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"250.402255ms","start":"2026-04-21T03:28:15.712805Z","end":"2026-04-21T03:28:15.963207Z","steps":["trace[1292535046] 'process raft request' (duration: 250.170013ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:17.549354Z","caller":"traceutil/trace.go:171","msg":"trace[1825957116] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"118.973674ms","start":"2026-04-21T03:28:17.430346Z","end":"2026-04-21T03:28:17.54932Z","steps":["trace[1825957116] 'process raft request' (duration: 92.253276ms)","trace[1825957116] 'compare' (duration: 26.600236ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:23.074472Z","caller":"traceutil/trace.go:171","msg":"trace[1211699554] linearizableReadLoop","detail":"{readStateIndex:1386; appliedIndex:1385; }","duration":"105.205227ms","start":"2026-04-21T03:28:22.969236Z","end":"2026-04-21T03:28:23.074441Z","steps":["trace[1211699554] 'read index received' (duration: 104.946275ms)","trace[1211699554] 'applied index is now lower than readState.Index' (duration: 257.182µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:23.074489Z","caller":"traceutil/trace.go:171","msg":"trace[2127270659] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"260.522485ms","start":"2026-04-21T03:28:22.813933Z","end":"2026-04-21T03:28:23.074456Z","steps":["trace[2127270659] 'process raft request' (duration: 260.327744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:23.075046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.781502ms","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-21T03:28:23.075194Z","caller":"traceutil/trace.go:171","msg":"trace[1758666702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"106.055323ms","start":"2026-04-21T03:28:22.969113Z","end":"2026-04-21T03:28:23.075168Z","steps":["trace[1758666702] 'agreement among raft nodes before linearized reading' (duration: 105.448189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:23.354881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.897328ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-21T03:28:23.355014Z","caller":"traceutil/trace.go:171","msg":"trace[192264544] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"276.073674ms","start":"2026-04-21T03:28:23.078919Z","end":"2026-04-21T03:28:23.354993Z","steps":["trace[192264544] 'process raft request' (duration: 74.972795ms)","trace[192264544] 'compare' (duration: 200.723807ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:23.358383Z","caller":"traceutil/trace.go:171","msg":"trace[1200215348] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"278.859623ms","start":"2026-04-21T03:28:23.07949Z","end":"2026-04-21T03:28:23.358349Z","steps":["trace[1200215348] 'process raft request' (duration: 278.645451ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:23.358553Z","caller":"traceutil/trace.go:171","msg":"trace[691183463] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"278.795493ms","start":"2026-04-21T03:28:23.079724Z","end":"2026-04-21T03:28:23.358519Z","steps":["trace[691183463] 'process raft request' (duration: 278.575881ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:31.203113Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":296566521431829224,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-21T03:28:31.207474Z","caller":"traceutil/trace.go:171","msg":"trace[1404749917] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"634.377184ms","start":"2026-04-21T03:28:30.573079Z","end":"2026-04-21T03:28:31.207456Z","steps":["trace[1404749917] 'process raft request' (duration: 634.275693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:31.207602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:28:30.573059Z","time spent":"634.489374ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-21T03:28:31.215299Z","caller":"traceutil/trace.go:171","msg":"trace[916147973] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"513.238314ms","start":"2026-04-21T03:28:30.702038Z","end":"2026-04-21T03:28:31.215276Z","steps":["trace[916147973] 'read index received' (duration: 505.46693ms)","trace[916147973] 'applied index is now lower than readState.Index' (duration: 7.770214ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:31.215408Z","caller":"traceutil/trace.go:171","msg":"trace[597877321] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"458.359071ms","start":"2026-04-21T03:28:30.757027Z","end":"2026-04-21T03:28:31.215386Z","steps":["trace[597877321] 'process raft request' (duration: 458.098529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:31.215524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:28:30.757Z","time spent":"458.456982ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:28:31.215446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.397486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-21T03:28:31.215579Z","caller":"traceutil/trace.go:171","msg":"trace[1436445774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"513.570937ms","start":"2026-04-21T03:28:30.701992Z","end":"2026-04-21T03:28:31.215563Z","steps":["trace[1436445774] 'agreement among raft nodes before linearized reading' (duration: 513.406806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:31.215692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:28:30.701973Z","time spent":"513.705148ms","remote":"127.0.0.1:35218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-21T03:28:31.216069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.794252ms","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-21T03:28:31.216129Z","caller":"traceutil/trace.go:171","msg":"trace[2145976492] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"108.895283ms","start":"2026-04-21T03:28:31.107224Z","end":"2026-04-21T03:28:31.216119Z","steps":["trace[2145976492] 'agreement among raft nodes before linearized reading' (duration: 108.273089ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:41.459841Z","caller":"traceutil/trace.go:171","msg":"trace[1267556352] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"100.227212ms","start":"2026-04-21T03:28:41.359594Z","end":"2026-04-21T03:28:41.459821Z","steps":["trace[1267556352] 'read index received' (duration: 100.083891ms)","trace[1267556352] 'applied index is now lower than readState.Index' (duration: 141.921µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:41.459893Z","caller":"traceutil/trace.go:171","msg":"trace[1604044205] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"120.399323ms","start":"2026-04-21T03:28:41.339461Z","end":"2026-04-21T03:28:41.45986Z","steps":["trace[1604044205] 'process raft request' (duration: 120.229962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:41.46Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.389123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"info","ts":"2026-04-21T03:28:41.460043Z","caller":"traceutil/trace.go:171","msg":"trace[356440378] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1398; }","duration":"100.467903ms","start":"2026-04-21T03:28:41.359565Z","end":"2026-04-21T03:28:41.460033Z","steps":["trace[356440378] 'agreement among raft nodes before linearized reading' (duration: 100.379073ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:41.65333Z","caller":"traceutil/trace.go:171","msg":"trace[1013012275] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1462; }","duration":"118.45876ms","start":"2026-04-21T03:28:41.534845Z","end":"2026-04-21T03:28:41.653303Z","steps":["trace[1013012275] 'read index received' (duration: 9.888479ms)","trace[1013012275] 'applied index is now lower than readState.Index' (duration: 108.569221ms)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:41.653398Z","caller":"traceutil/trace.go:171","msg":"trace[1903240563] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"136.760138ms","start":"2026-04-21T03:28:41.5166Z","end":"2026-04-21T03:28:41.65336Z","steps":["trace[1903240563] 'process raft request' (duration: 69.757109ms)","trace[1903240563] 'compare' (duration: 66.833518ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:28:41.653512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.189027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/cluster-admin\" ","response":"range_response_count:1 size:679"} {"level":"warn","ts":"2026-04-21T03:28:41.653531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.653281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:828"} {"level":"info","ts":"2026-04-21T03:28:41.653605Z","caller":"traceutil/trace.go:171","msg":"trace[697616825] range","detail":"{range_begin:/registry/clusterrolebindings/cluster-admin; range_end:; response_count:1; response_revision:1402; }","duration":"115.310768ms","start":"2026-04-21T03:28:41.538281Z","end":"2026-04-21T03:28:41.653592Z","steps":["trace[697616825] 'agreement among raft nodes before linearized reading' (duration: 115.159687ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:41.65364Z","caller":"traceutil/trace.go:171","msg":"trace[1016710482] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1402; }","duration":"118.782272ms","start":"2026-04-21T03:28:41.534834Z","end":"2026-04-21T03:28:41.653616Z","steps":["trace[1016710482] 'agreement among raft nodes before linearized reading' (duration: 118.563361ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:42.051306Z","caller":"traceutil/trace.go:171","msg":"trace[1007898346] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"295.068226ms","start":"2026-04-21T03:28:41.756207Z","end":"2026-04-21T03:28:42.051275Z","steps":["trace[1007898346] 'read index received' (duration: 294.938195ms)","trace[1007898346] 'applied index is now lower than readState.Index' (duration: 129.151µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:42.051314Z","caller":"traceutil/trace.go:171","msg":"trace[887751121] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"310.685996ms","start":"2026-04-21T03:28:41.740612Z","end":"2026-04-21T03:28:42.051298Z","steps":["trace[887751121] 'process raft request' (duration: 310.543585ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:42.051499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:28:41.740582Z","time spent":"310.823447ms","remote":"127.0.0.1:35490","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-21T03:28:42.051525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.316137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:1 size:1102"} {"level":"info","ts":"2026-04-21T03:28:42.05159Z","caller":"traceutil/trace.go:171","msg":"trace[707983932] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:1; response_revision:1403; }","duration":"295.373708ms","start":"2026-04-21T03:28:41.756174Z","end":"2026-04-21T03:28:42.051548Z","steps":["trace[707983932] 'agreement among raft nodes before linearized reading' (duration: 295.206127ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:42.153464Z","caller":"traceutil/trace.go:171","msg":"trace[1548881027] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"102.061195ms","start":"2026-04-21T03:28:42.051362Z","end":"2026-04-21T03:28:42.153423Z","steps":["trace[1548881027] 'read index received' (duration: 6.444905ms)","trace[1548881027] 'applied index is now lower than readState.Index' (duration: 95.61397ms)"],"step_count":2} {"level":"warn","ts":"2026-04-21T03:28:42.153577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.783537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" ","response":"range_response_count:1 size:700"} {"level":"warn","ts":"2026-04-21T03:28:42.153498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.065414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T03:28:42.153646Z","caller":"traceutil/trace.go:171","msg":"trace[1006590087] range","detail":"{range_begin:/registry/clusterroles/system:controller:resourcequota-controller; range_end:; response_count:1; response_revision:1403; }","duration":"370.880188ms","start":"2026-04-21T03:28:41.782741Z","end":"2026-04-21T03:28:42.153621Z","steps":["trace[1006590087] 'agreement among raft nodes before linearized reading' (duration: 370.755627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:42.153697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-21T03:28:41.782718Z","time spent":"370.969808ms","remote":"127.0.0.1:35556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":723,"request content":"key:\"/registry/clusterroles/system:controller:resourcequota-controller\" "} {"level":"info","ts":"2026-04-21T03:28:42.153701Z","caller":"traceutil/trace.go:171","msg":"trace[1999284350] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1403; }","duration":"176.314065ms","start":"2026-04-21T03:28:41.977363Z","end":"2026-04-21T03:28:42.153677Z","steps":["trace[1999284350] 'agreement among raft nodes before linearized reading' (duration: 176.061853ms)"],"step_count":1} {"level":"info","ts":"2026-04-21T03:28:44.201128Z","caller":"traceutil/trace.go:171","msg":"trace[1204578385] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1471; }","duration":"109.580888ms","start":"2026-04-21T03:28:44.091523Z","end":"2026-04-21T03:28:44.201104Z","steps":["trace[1204578385] 'read index received' (duration: 109.372426ms)","trace[1204578385] 'applied index is now lower than readState.Index' (duration: 207.602µs)"],"step_count":2} {"level":"info","ts":"2026-04-21T03:28:44.201215Z","caller":"traceutil/trace.go:171","msg":"trace[455998962] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"143.639307ms","start":"2026-04-21T03:28:44.057509Z","end":"2026-04-21T03:28:44.201148Z","steps":["trace[455998962] 'process raft request' (duration: 143.440624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-21T03:28:44.201415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.82344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-21T03:28:44.202326Z","caller":"traceutil/trace.go:171","msg":"trace[765296938] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1409; }","duration":"110.829156ms","start":"2026-04-21T03:28:44.091469Z","end":"2026-04-21T03:28:44.202298Z","steps":["trace[765296938] 'agreement among raft nodes before linearized reading' (duration: 109.784589ms)"],"step_count":1}