{"level":"warn","ts":"2026-04-02T22:22:05.142066Z","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-02T22:22:05.142157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-02T22:22:05.142221Z","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-02T22:22:05.142229Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-02T22:22:05.142248Z","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-02T22:22:05.142883Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-04-02T22:22:05.142948Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-02T22:22:05.148928Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.783268ms"} {"level":"info","ts":"2026-04-02T22:22:05.156235Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-04-02T22:22:05.156351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T22:22:05.156406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-04-02T22:22:05.156443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T22:22:05.156478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-04-02T22:22:05.156562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-04-02T22:22:05.161638Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T22:22:05.162754Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T22:22:05.164456Z","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-02T22:22:05.166464Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T22:22:05.170311Z","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-02T22:22:05.170492Z","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-02T22:22:05.170509Z","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-02T22:22:05.170456Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T22:22:05.171816Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-04-02T22:22:05.171953Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-04-02T22:22:05.172444Z","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-02T22:22:05.172597Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-02T22:22:05.17264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-04-02T22:22:05.172774Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T22:22:05.17281Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T22:22:05.857142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T22:22:05.857218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T22:22:05.857242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-04-02T22:22:05.857266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-04-02T22:22:05.857277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-02T22:22:05.857294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-04-02T22:22:05.857307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-04-02T22:22:05.858814Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:22:05.859172Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T22:22:05.859433Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:22:05.860032Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T22:22:05.860102Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:22:05.860218Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:22:05.860265Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T22:22:05.860367Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T22:22:05.860418Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T22:22:05.861989Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T22:22:05.871673Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-04-02T22:22:07.408013Z","caller":"traceutil/trace.go:171","msg":"trace[604944892] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"144.546651ms","start":"2026-04-02T22:22:07.263367Z","end":"2026-04-02T22:22:07.407914Z","steps":["trace[604944892] 'process raft request' (duration: 116.752231ms)","trace[604944892] 'compare' (duration: 27.525518ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:07.408159Z","caller":"traceutil/trace.go:171","msg":"trace[1745487802] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"143.446962ms","start":"2026-04-02T22:22:07.264687Z","end":"2026-04-02T22:22:07.408134Z","steps":["trace[1745487802] 'process raft request' (duration: 143.244543ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.40819Z","caller":"traceutil/trace.go:171","msg":"trace[1599639749] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"143.596929ms","start":"2026-04-02T22:22:07.264563Z","end":"2026-04-02T22:22:07.40816Z","steps":["trace[1599639749] 'process raft request' (duration: 143.313397ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.40801Z","caller":"traceutil/trace.go:171","msg":"trace[978061687] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:7; }","duration":"143.61474ms","start":"2026-04-02T22:22:07.264367Z","end":"2026-04-02T22:22:07.407982Z","steps":["trace[978061687] 'read index received' (duration: 10.498927ms)","trace[978061687] 'applied index is now lower than readState.Index' (duration: 133.114583ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:07.408064Z","caller":"traceutil/trace.go:171","msg":"trace[1251143500] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"143.433872ms","start":"2026-04-02T22:22:07.264552Z","end":"2026-04-02T22:22:07.407986Z","steps":["trace[1251143500] 'process raft request' (duration: 143.240773ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.408172Z","caller":"traceutil/trace.go:171","msg":"trace[782548199] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"143.480274ms","start":"2026-04-02T22:22:07.264626Z","end":"2026-04-02T22:22:07.408106Z","steps":["trace[782548199] 'process raft request' (duration: 143.282775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:07.408817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.392984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:22:07.40958Z","caller":"traceutil/trace.go:171","msg":"trace[377460034] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"145.205129ms","start":"2026-04-02T22:22:07.264344Z","end":"2026-04-02T22:22:07.409549Z","steps":["trace[377460034] 'agreement among raft nodes before linearized reading' (duration: 143.761716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:07.423977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.529358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:22:07.42403Z","caller":"traceutil/trace.go:171","msg":"trace[1210023600] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"115.620762ms","start":"2026-04-02T22:22:07.308394Z","end":"2026-04-02T22:22:07.424015Z","steps":["trace[1210023600] 'agreement among raft nodes before linearized reading' (duration: 115.485176ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.42404Z","caller":"traceutil/trace.go:171","msg":"trace[225276120] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"147.834253ms","start":"2026-04-02T22:22:07.276192Z","end":"2026-04-02T22:22:07.424026Z","steps":["trace[225276120] 'process raft request' (duration: 147.484088ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.424217Z","caller":"traceutil/trace.go:171","msg":"trace[1480242674] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"144.974969ms","start":"2026-04-02T22:22:07.27923Z","end":"2026-04-02T22:22:07.424205Z","steps":["trace[1480242674] 'process raft request' (duration: 144.577232ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:07.424222Z","caller":"traceutil/trace.go:171","msg":"trace[1279387191] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"139.831955ms","start":"2026-04-02T22:22:07.284381Z","end":"2026-04-02T22:22:07.424213Z","steps":["trace[1279387191] 'process raft request' (duration: 139.454909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:07.424318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.600446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T22:22:07.424343Z","caller":"traceutil/trace.go:171","msg":"trace[1736704361] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"108.665379ms","start":"2026-04-02T22:22:07.315671Z","end":"2026-04-02T22:22:07.424336Z","steps":["trace[1736704361] 'agreement among raft nodes before linearized reading' (duration: 108.608976ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:15.28947Z","caller":"traceutil/trace.go:171","msg":"trace[1238545884] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"166.756079ms","start":"2026-04-02T22:22:15.122667Z","end":"2026-04-02T22:22:15.289423Z","steps":["trace[1238545884] 'process raft request' (duration: 166.53911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:15.289667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.056253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-02T22:22:15.289761Z","caller":"traceutil/trace.go:171","msg":"trace[244805550] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:340; }","duration":"128.195878ms","start":"2026-04-02T22:22:15.16155Z","end":"2026-04-02T22:22:15.289746Z","steps":["trace[244805550] 'agreement among raft nodes before linearized reading' (duration: 127.950549ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:15.289389Z","caller":"traceutil/trace.go:171","msg":"trace[482917380] linearizableReadLoop","detail":"{readStateIndex:352; appliedIndex:351; }","duration":"127.772421ms","start":"2026-04-02T22:22:15.16159Z","end":"2026-04-02T22:22:15.289363Z","steps":["trace[482917380] 'read index received' (duration: 127.555003ms)","trace[482917380] 'applied index is now lower than readState.Index' (duration: 216.528µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:15.440083Z","caller":"traceutil/trace.go:171","msg":"trace[1985363815] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"129.350364ms","start":"2026-04-02T22:22:15.310705Z","end":"2026-04-02T22:22:15.440056Z","steps":["trace[1985363815] 'read index received' (duration: 105.150804ms)","trace[1985363815] 'applied index is now lower than readState.Index' (duration: 24.19842ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:15.440207Z","caller":"traceutil/trace.go:171","msg":"trace[558839185] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"142.825689ms","start":"2026-04-02T22:22:15.297347Z","end":"2026-04-02T22:22:15.440173Z","steps":["trace[558839185] 'process raft request' (duration: 118.583047ms)","trace[558839185] 'compare' (duration: 24.012803ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:15.440416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.698528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T22:22:15.440463Z","caller":"traceutil/trace.go:171","msg":"trace[736023265] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:341; }","duration":"129.76008ms","start":"2026-04-02T22:22:15.31069Z","end":"2026-04-02T22:22:15.44045Z","steps":["trace[736023265] 'agreement among raft nodes before linearized reading' (duration: 129.51164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:15.7502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.86532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:15.750391Z","caller":"traceutil/trace.go:171","msg":"trace[2103037833] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:357; }","duration":"188.582415ms","start":"2026-04-02T22:22:15.561789Z","end":"2026-04-02T22:22:15.750371Z","steps":["trace[2103037833] 'read index received' (duration: 10.871791ms)","trace[2103037833] 'applied index is now lower than readState.Index' (duration: 177.709434ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:15.750469Z","caller":"traceutil/trace.go:171","msg":"trace[1812574979] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"219.912929ms","start":"2026-04-02T22:22:15.5305Z","end":"2026-04-02T22:22:15.750413Z","steps":["trace[1812574979] 'process raft request' (duration: 42.175654ms)","trace[1812574979] 'compare' (duration: 176.744375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:15.750524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.733111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-02T22:22:15.750572Z","caller":"traceutil/trace.go:171","msg":"trace[1271680117] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:347; }","duration":"188.822205ms","start":"2026-04-02T22:22:15.56174Z","end":"2026-04-02T22:22:15.750562Z","steps":["trace[1271680117] 'agreement among raft nodes before linearized reading' (duration: 188.732491ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:15.750518Z","caller":"traceutil/trace.go:171","msg":"trace[510745344] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"218.361257ms","start":"2026-04-02T22:22:15.532098Z","end":"2026-04-02T22:22:15.750459Z","steps":["trace[510745344] 'process raft request' (duration: 218.212021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:15.750708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.954965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-02T22:22:15.750765Z","caller":"traceutil/trace.go:171","msg":"trace[1036717012] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:347; }","duration":"139.045869ms","start":"2026-04-02T22:22:15.611699Z","end":"2026-04-02T22:22:15.750745Z","steps":["trace[1036717012] 'agreement among raft nodes before linearized reading' (duration: 138.950485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.225697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.266559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:16.225881Z","caller":"traceutil/trace.go:171","msg":"trace[69625293] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"423.166358ms","start":"2026-04-02T22:22:15.802693Z","end":"2026-04-02T22:22:16.22586Z","steps":["trace[69625293] 'process raft request' (duration: 314.649249ms)","trace[69625293] 'compare' (duration: 107.997059ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:16.225816Z","caller":"traceutil/trace.go:171","msg":"trace[160100763] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:362; }","duration":"414.737524ms","start":"2026-04-02T22:22:15.811053Z","end":"2026-04-02T22:22:16.225791Z","steps":["trace[160100763] 'read index received' (duration: 306.273908ms)","trace[160100763] 'applied index is now lower than readState.Index' (duration: 108.461996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:16.226099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.032676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-02T22:22:16.226157Z","caller":"traceutil/trace.go:171","msg":"trace[1944667778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:351; }","duration":"415.108879ms","start":"2026-04-02T22:22:15.811031Z","end":"2026-04-02T22:22:16.22614Z","steps":["trace[1944667778] 'agreement among raft nodes before linearized reading' (duration: 414.902501ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.226204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:15.811022Z","time spent":"415.169831ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-04-02T22:22:16.225998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:15.802667Z","time spent":"423.282774ms","remote":"127.0.0.1:36676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:16.226229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.196418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-02T22:22:16.226674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.36735ms","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-02T22:22:16.226752Z","caller":"traceutil/trace.go:171","msg":"trace[15890291] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:351; }","duration":"364.720468ms","start":"2026-04-02T22:22:15.862005Z","end":"2026-04-02T22:22:16.226725Z","steps":["trace[15890291] 'agreement among raft nodes before linearized reading' (duration: 364.141265ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.22678Z","caller":"traceutil/trace.go:171","msg":"trace[248245678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:351; }","duration":"281.482884ms","start":"2026-04-02T22:22:15.945268Z","end":"2026-04-02T22:22:16.226751Z","steps":["trace[248245678] 'agreement among raft nodes before linearized reading' (duration: 280.903941ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.226846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:15.861935Z","time spent":"364.889575ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-04-02T22:22:16.227385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.884623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-02T22:22:16.227593Z","caller":"traceutil/trace.go:171","msg":"trace[136458651] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:351; }","duration":"215.03731ms","start":"2026-04-02T22:22:16.012417Z","end":"2026-04-02T22:22:16.227454Z","steps":["trace[136458651] 'agreement among raft nodes before linearized reading' (duration: 213.748129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.227715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.302827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T22:22:16.227768Z","caller":"traceutil/trace.go:171","msg":"trace[1321890223] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:351; }","duration":"315.39975ms","start":"2026-04-02T22:22:15.912345Z","end":"2026-04-02T22:22:16.227745Z","steps":["trace[1321890223] 'agreement among raft nodes before linearized reading' (duration: 313.85571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.22795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:15.912319Z","time spent":"315.485403ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-02T22:22:16.228711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.322872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T22:22:16.228804Z","caller":"traceutil/trace.go:171","msg":"trace[238841988] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:351; }","duration":"267.655185ms","start":"2026-04-02T22:22:15.961109Z","end":"2026-04-02T22:22:16.228765Z","steps":["trace[238841988] 'agreement among raft nodes before linearized reading' (duration: 265.174898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.234204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.072603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-02T22:22:16.234283Z","caller":"traceutil/trace.go:171","msg":"trace[1995047684] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:351; }","duration":"172.193448ms","start":"2026-04-02T22:22:16.062064Z","end":"2026-04-02T22:22:16.234258Z","steps":["trace[1995047684] 'agreement among raft nodes before linearized reading' (duration: 166.693722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.687186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.48028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:16.687453Z","caller":"traceutil/trace.go:171","msg":"trace[909702204] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"452.56694ms","start":"2026-04-02T22:22:16.234787Z","end":"2026-04-02T22:22:16.687354Z","steps":["trace[909702204] 'process raft request' (duration: 149.824901ms)","trace[909702204] 'compare' (duration: 302.348514ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:16.687503Z","caller":"traceutil/trace.go:171","msg":"trace[1614200014] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"449.844443ms","start":"2026-04-02T22:22:16.237626Z","end":"2026-04-02T22:22:16.68747Z","steps":["trace[1614200014] 'process raft request' (duration: 449.738139ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.68758Z","caller":"traceutil/trace.go:171","msg":"trace[435112768] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"447.670398ms","start":"2026-04-02T22:22:16.239901Z","end":"2026-04-02T22:22:16.687571Z","steps":["trace[435112768] 'process raft request' (duration: 447.544873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.687642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.237611Z","time spent":"449.968168ms","remote":"127.0.0.1:36476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:16.687644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.239891Z","time spent":"447.72553ms","remote":"127.0.0.1:36598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:22:16.687648Z","caller":"traceutil/trace.go:171","msg":"trace[665966809] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"449.890725ms","start":"2026-04-02T22:22:16.237731Z","end":"2026-04-02T22:22:16.687621Z","steps":["trace[665966809] 'process raft request' (duration: 449.671386ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.687493Z","caller":"traceutil/trace.go:171","msg":"trace[129486618] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"451.632134ms","start":"2026-04-02T22:22:16.235831Z","end":"2026-04-02T22:22:16.687463Z","steps":["trace[129486618] 'process raft request' (duration: 451.503128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.687766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.235824Z","time spent":"451.903504ms","remote":"127.0.0.1:36498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:16.687803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.234769Z","time spent":"452.764207ms","remote":"127.0.0.1:36878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:16.687778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.237724Z","time spent":"449.978658ms","remote":"127.0.0.1:36662","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-02T22:22:16.687521Z","caller":"traceutil/trace.go:171","msg":"trace[2130053168] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"451.948965ms","start":"2026-04-02T22:22:16.235552Z","end":"2026-04-02T22:22:16.687501Z","steps":["trace[2130053168] 'process raft request' (duration: 451.730736ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.687541Z","caller":"traceutil/trace.go:171","msg":"trace[195306007] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"446.797713ms","start":"2026-04-02T22:22:16.240727Z","end":"2026-04-02T22:22:16.687525Z","steps":["trace[195306007] 'process raft request' (duration: 446.751961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.687979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.235545Z","time spent":"452.403894ms","remote":"127.0.0.1:36554","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:16.68805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:16.24072Z","time spent":"447.276272ms","remote":"127.0.0.1:36876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:22:16.891077Z","caller":"traceutil/trace.go:171","msg":"trace[1910801434] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"106.916186ms","start":"2026-04-02T22:22:16.784124Z","end":"2026-04-02T22:22:16.89104Z","steps":["trace[1910801434] 'read index received' (duration: 69.31548ms)","trace[1910801434] 'applied index is now lower than readState.Index' (duration: 37.599556ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:16.891215Z","caller":"traceutil/trace.go:171","msg":"trace[1240619429] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"146.50315ms","start":"2026-04-02T22:22:16.744679Z","end":"2026-04-02T22:22:16.891182Z","steps":["trace[1240619429] 'process raft request' (duration: 108.760568ms)","trace[1240619429] 'compare' (duration: 37.484691ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:16.891852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.612725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:22:16.89196Z","caller":"traceutil/trace.go:171","msg":"trace[1949388002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:364; }","duration":"158.755461ms","start":"2026-04-02T22:22:16.733146Z","end":"2026-04-02T22:22:16.891902Z","steps":["trace[1949388002] 'agreement among raft nodes before linearized reading' (duration: 158.083814ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.89203Z","caller":"traceutil/trace.go:171","msg":"trace[1182567905] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"103.605686ms","start":"2026-04-02T22:22:16.788403Z","end":"2026-04-02T22:22:16.892008Z","steps":["trace[1182567905] 'process raft request' (duration: 103.387527ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.892177Z","caller":"traceutil/trace.go:171","msg":"trace[559691639] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"103.295384ms","start":"2026-04-02T22:22:16.788857Z","end":"2026-04-02T22:22:16.892152Z","steps":["trace[559691639] 'process raft request' (duration: 103.121637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.892206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.954669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-02T22:22:16.892327Z","caller":"traceutil/trace.go:171","msg":"trace[2002285507] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:368; }","duration":"104.108516ms","start":"2026-04-02T22:22:16.788202Z","end":"2026-04-02T22:22:16.892311Z","steps":["trace[2002285507] 'agreement among raft nodes before linearized reading' (duration: 103.94621ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.892321Z","caller":"traceutil/trace.go:171","msg":"trace[1703031180] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"101.219582ms","start":"2026-04-02T22:22:16.791074Z","end":"2026-04-02T22:22:16.892293Z","steps":["trace[1703031180] 'process raft request' (duration: 100.981593ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:16.892522Z","caller":"traceutil/trace.go:171","msg":"trace[731298864] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"103.782943ms","start":"2026-04-02T22:22:16.788722Z","end":"2026-04-02T22:22:16.892505Z","steps":["trace[731298864] 'process raft request' (duration: 103.141297ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:16.892492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.096848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-02T22:22:16.892722Z","caller":"traceutil/trace.go:171","msg":"trace[142841134] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:368; }","duration":"101.336967ms","start":"2026-04-02T22:22:16.791367Z","end":"2026-04-02T22:22:16.892704Z","steps":["trace[142841134] 'agreement among raft nodes before linearized reading' (duration: 101.035816ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:23.648597Z","caller":"traceutil/trace.go:171","msg":"trace[726830086] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"145.034906ms","start":"2026-04-02T22:22:23.503536Z","end":"2026-04-02T22:22:23.648571Z","steps":["trace[726830086] 'process raft request' (duration: 144.899431ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:23.924445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.139412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4367"} {"level":"info","ts":"2026-04-02T22:22:23.924533Z","caller":"traceutil/trace.go:171","msg":"trace[556973408] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"207.306619ms","start":"2026-04-02T22:22:23.717205Z","end":"2026-04-02T22:22:23.924511Z","steps":["trace[556973408] 'range keys from in-memory index tree' (duration: 206.995198ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:24.084255Z","caller":"traceutil/trace.go:171","msg":"trace[443151124] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"143.775861ms","start":"2026-04-02T22:22:23.940451Z","end":"2026-04-02T22:22:24.084227Z","steps":["trace[443151124] 'process raft request' (duration: 143.633656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:31.511015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516364771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:22:32.012117Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516364771,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:22:32.313672Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.523934775s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:22:32.314255Z","caller":"traceutil/trace.go:171","msg":"trace[1090726848] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.524589278s","start":"2026-04-02T22:22:30.78964Z","end":"2026-04-02T22:22:32.314229Z","steps":["trace[1090726848] 'process raft request' (duration: 1.524375561s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:32.314581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:30.789614Z","time spent":"1.524841816s","remote":"127.0.0.1:36662","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-02T22:22:32.576445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.914206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:32.576536Z","caller":"traceutil/trace.go:171","msg":"trace[26272845] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:477; }","duration":"1.565733313s","start":"2026-04-02T22:22:31.010788Z","end":"2026-04-02T22:22:32.576521Z","steps":["trace[26272845] 'read index received' (duration: 1.303207481s)","trace[26272845] 'applied index is now lower than readState.Index' (duration: 262.524942ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:32.576609Z","caller":"traceutil/trace.go:171","msg":"trace[351476350] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"1.115658108s","start":"2026-04-02T22:22:31.460943Z","end":"2026-04-02T22:22:32.576601Z","steps":["trace[351476350] 'process raft request' (duration: 942.510665ms)","trace[351476350] 'compare' (duration: 172.717628ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:32.576648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:31.460918Z","time spent":"1.115709019s","remote":"127.0.0.1:36662","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-02T22:22:32.576741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.960329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:32.576754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.561413219s","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-02T22:22:32.576806Z","caller":"traceutil/trace.go:171","msg":"trace[843094511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:462; }","duration":"194.061253ms","start":"2026-04-02T22:22:32.382727Z","end":"2026-04-02T22:22:32.576788Z","steps":["trace[843094511] 'agreement among raft nodes before linearized reading' (duration: 193.943469ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:32.576823Z","caller":"traceutil/trace.go:171","msg":"trace[530670612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"1.561512642s","start":"2026-04-02T22:22:31.01529Z","end":"2026-04-02T22:22:32.576802Z","steps":["trace[530670612] 'agreement among raft nodes before linearized reading' (duration: 1.561387688s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:32.57685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.212151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T22:22:32.576891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566111185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:22:32.576924Z","caller":"traceutil/trace.go:171","msg":"trace[487765880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:462; }","duration":"1.566177237s","start":"2026-04-02T22:22:31.010739Z","end":"2026-04-02T22:22:32.576916Z","steps":["trace[487765880] 'agreement among raft nodes before linearized reading' (duration: 1.566109795s)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:32.576934Z","caller":"traceutil/trace.go:171","msg":"trace[1932884648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:462; }","duration":"899.350946ms","start":"2026-04-02T22:22:31.677563Z","end":"2026-04-02T22:22:32.576914Z","steps":["trace[1932884648] 'agreement among raft nodes before linearized reading' (duration: 899.191091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:32.576946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:31.010718Z","time spent":"1.566223809s","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:22:32.576868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:31.015272Z","time spent":"1.561585944s","remote":"127.0.0.1:36662","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-02T22:22:32.576967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:31.677542Z","time spent":"899.418419ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:22:34.818631Z","caller":"traceutil/trace.go:171","msg":"trace[108110727] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"190.458015ms","start":"2026-04-02T22:22:34.628148Z","end":"2026-04-02T22:22:34.818606Z","steps":["trace[108110727] 'process raft request' (duration: 190.31791ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:34.819016Z","caller":"traceutil/trace.go:171","msg":"trace[9106309] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:484; }","duration":"147.788646ms","start":"2026-04-02T22:22:34.671205Z","end":"2026-04-02T22:22:34.818994Z","steps":["trace[9106309] 'read index received' (duration: 147.780296ms)","trace[9106309] 'applied index is now lower than readState.Index' (duration: 7.11µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:34.819187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.961152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:22:34.819241Z","caller":"traceutil/trace.go:171","msg":"trace[1199231016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:467; }","duration":"148.060185ms","start":"2026-04-02T22:22:34.671167Z","end":"2026-04-02T22:22:34.819228Z","steps":["trace[1199231016] 'agreement among raft nodes before linearized reading' (duration: 147.92358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:34.82289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.64601ms","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-02T22:22:34.822941Z","caller":"traceutil/trace.go:171","msg":"trace[1739930225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"151.735873ms","start":"2026-04-02T22:22:34.671192Z","end":"2026-04-02T22:22:34.822928Z","steps":["trace[1739930225] 'agreement among raft nodes before linearized reading' (duration: 151.654281ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:37.320431Z","caller":"traceutil/trace.go:171","msg":"trace[1231539108] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"227.234792ms","start":"2026-04-02T22:22:37.09317Z","end":"2026-04-02T22:22:37.320405Z","steps":["trace[1231539108] 'process raft request' (duration: 226.905771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:39.771934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516364873,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:22:39.993704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.237544ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:39.994015Z","caller":"traceutil/trace.go:171","msg":"trace[1497156933] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"883.824928ms","start":"2026-04-02T22:22:39.110128Z","end":"2026-04-02T22:22:39.993953Z","steps":["trace[1497156933] 'process raft request' (duration: 762.225463ms)","trace[1497156933] 'compare' (duration: 121.076399ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:39.994201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.11011Z","time spent":"884.006163ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:22:39.994315Z","caller":"traceutil/trace.go:171","msg":"trace[646860709] transaction","detail":"{read_only:false; number_of_response:0; response_revision:531; }","duration":"879.143744ms","start":"2026-04-02T22:22:39.115137Z","end":"2026-04-02T22:22:39.994281Z","steps":["trace[646860709] 'process raft request' (duration: 878.752702ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:39.994268Z","caller":"traceutil/trace.go:171","msg":"trace[2137600739] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"880.977859ms","start":"2026-04-02T22:22:39.113257Z","end":"2026-04-02T22:22:39.994235Z","steps":["trace[2137600739] 'process raft request' (duration: 880.558926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:39.994489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.115125Z","time spent":"879.279598ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:39.994564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.113237Z","time spent":"881.253758ms","remote":"127.0.0.1:36928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T22:22:39.99472Z","caller":"traceutil/trace.go:171","msg":"trace[1671911090] transaction","detail":"{read_only:false; number_of_response:1; response_revision:532; }","duration":"877.123562ms","start":"2026-04-02T22:22:39.117575Z","end":"2026-04-02T22:22:39.994698Z","steps":["trace[1671911090] 'process raft request' (duration: 876.672608ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:39.994789Z","caller":"traceutil/trace.go:171","msg":"trace[2031420643] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"879.34961ms","start":"2026-04-02T22:22:39.115427Z","end":"2026-04-02T22:22:39.994776Z","steps":["trace[2031420643] 'process raft request' (duration: 878.641638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:39.994863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.115414Z","time spent":"879.401381ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:39.994876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.117557Z","time spent":"877.248695ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:39.995184Z","caller":"traceutil/trace.go:171","msg":"trace[414482405] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"874.845761ms","start":"2026-04-02T22:22:39.120301Z","end":"2026-04-02T22:22:39.995146Z","steps":["trace[414482405] 'process raft request' (duration: 874.13737ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:39.995304Z","caller":"traceutil/trace.go:171","msg":"trace[971047530] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"874.958614ms","start":"2026-04-02T22:22:39.120321Z","end":"2026-04-02T22:22:39.995279Z","steps":["trace[971047530] 'process raft request' (duration: 874.689516ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:39.995336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.120289Z","time spent":"874.971835ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:39.995408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.120307Z","time spent":"875.039208ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:39.995757Z","caller":"traceutil/trace.go:171","msg":"trace[916737248] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"873.80491ms","start":"2026-04-02T22:22:39.12194Z","end":"2026-04-02T22:22:39.995744Z","steps":["trace[916737248] 'process raft request' (duration: 873.296724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:39.995831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.121933Z","time spent":"873.859861ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7326,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:39.999925Z","caller":"traceutil/trace.go:171","msg":"trace[1029968930] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"873.733238ms","start":"2026-04-02T22:22:39.12617Z","end":"2026-04-02T22:22:39.999904Z","steps":["trace[1029968930] 'process raft request' (duration: 869.753725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:40.000056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.126156Z","time spent":"873.846981ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:40.003031Z","caller":"traceutil/trace.go:171","msg":"trace[280475219] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"867.219837ms","start":"2026-04-02T22:22:39.135792Z","end":"2026-04-02T22:22:40.003012Z","steps":["trace[280475219] 'process raft request' (duration: 864.448272ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:40.003132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.135779Z","time spent":"867.29548ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:40.00314Z","caller":"traceutil/trace.go:171","msg":"trace[1867019912] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:548; }","duration":"731.966053ms","start":"2026-04-02T22:22:39.270998Z","end":"2026-04-02T22:22:40.002964Z","steps":["trace[1867019912] 'read index received' (duration: 601.446373ms)","trace[1867019912] 'applied index is now lower than readState.Index' (duration: 130.51832ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:40.003383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.382066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-02T22:22:40.003463Z","caller":"traceutil/trace.go:171","msg":"trace[99249086] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:538; }","duration":"732.482129ms","start":"2026-04-02T22:22:39.270947Z","end":"2026-04-02T22:22:40.003429Z","steps":["trace[99249086] 'agreement among raft nodes before linearized reading' (duration: 732.068566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:40.003468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.951661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:40.003481Z","caller":"traceutil/trace.go:171","msg":"trace[240829252] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"795.894667ms","start":"2026-04-02T22:22:39.207563Z","end":"2026-04-02T22:22:40.003457Z","steps":["trace[240829252] 'process raft request' (duration: 795.273668ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:40.003505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.270925Z","time spent":"732.569651ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"info","ts":"2026-04-02T22:22:40.003533Z","caller":"traceutil/trace.go:171","msg":"trace[1268642572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"619.020373ms","start":"2026-04-02T22:22:39.384486Z","end":"2026-04-02T22:22:40.003506Z","steps":["trace[1268642572] 'agreement among raft nodes before linearized reading' (duration: 618.89588ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:40.003538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.207535Z","time spent":"795.97922ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:40.0036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:39.384465Z","time spent":"619.102846ms","remote":"127.0.0.1:36448","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-02T22:22:40.308559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.740277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T22:22:40.308672Z","caller":"traceutil/trace.go:171","msg":"trace[10991668] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"230.560262ms","start":"2026-04-02T22:22:40.078091Z","end":"2026-04-02T22:22:40.308651Z","steps":["trace[10991668] 'process raft request' (duration: 47.626969ms)","trace[10991668] 'compare' (duration: 182.590622ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:40.883118Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516364913,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:22:41.039163Z","caller":"traceutil/trace.go:171","msg":"trace[761262281] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"954.123395ms","start":"2026-04-02T22:22:40.084995Z","end":"2026-04-02T22:22:41.039118Z","steps":["trace[761262281] 'process raft request' (duration: 881.686542ms)","trace[761262281] 'compare' (duration: 72.119804ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:41.03925Z","caller":"traceutil/trace.go:171","msg":"trace[766222788] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"942.80468ms","start":"2026-04-02T22:22:40.09642Z","end":"2026-04-02T22:22:41.039225Z","steps":["trace[766222788] 'process raft request' (duration: 942.662626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.039334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.084981Z","time spent":"954.265669ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:41.039412Z","caller":"traceutil/trace.go:171","msg":"trace[1701287195] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"949.254396ms","start":"2026-04-02T22:22:40.090139Z","end":"2026-04-02T22:22:41.039394Z","steps":["trace[1701287195] 'process raft request' (duration: 948.760002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.039615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.090087Z","time spent":"949.441693ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:41.040138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.096395Z","time spent":"942.919244ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:41.040833Z","caller":"traceutil/trace.go:171","msg":"trace[595310847] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:567; }","duration":"658.304688ms","start":"2026-04-02T22:22:40.382507Z","end":"2026-04-02T22:22:41.040812Z","steps":["trace[595310847] 'read index received' (duration: 584.255507ms)","trace[595310847] 'applied index is now lower than readState.Index' (duration: 74.047871ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:41.041029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.521385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:41.041091Z","caller":"traceutil/trace.go:171","msg":"trace[347545977] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"658.608968ms","start":"2026-04-02T22:22:40.382458Z","end":"2026-04-02T22:22:41.041067Z","steps":["trace[347545977] 'agreement among raft nodes before linearized reading' (duration: 658.500624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.041154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.382431Z","time spent":"658.711811ms","remote":"127.0.0.1:36444","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-02T22:22:41.041164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.321172ms","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-02T22:22:41.041223Z","caller":"traceutil/trace.go:171","msg":"trace[845497331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"618.428636ms","start":"2026-04-02T22:22:40.422778Z","end":"2026-04-02T22:22:41.041206Z","steps":["trace[845497331] 'agreement among raft nodes before linearized reading' (duration: 618.254771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.041265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.422753Z","time spent":"618.502067ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T22:22:41.041294Z","caller":"traceutil/trace.go:171","msg":"trace[2022138623] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"714.958011ms","start":"2026-04-02T22:22:40.326285Z","end":"2026-04-02T22:22:41.041243Z","steps":["trace[2022138623] 'process raft request' (duration: 714.400204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.041601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:40.326263Z","time spent":"715.120206ms","remote":"127.0.0.1:36464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:41.041626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.920627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T22:22:41.04168Z","caller":"traceutil/trace.go:171","msg":"trace[1569474572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"149.00728ms","start":"2026-04-02T22:22:40.89266Z","end":"2026-04-02T22:22:41.041667Z","steps":["trace[1569474572] 'agreement among raft nodes before linearized reading' (duration: 148.798093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.041721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.364712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:22:41.041761Z","caller":"traceutil/trace.go:171","msg":"trace[183816289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"128.415544ms","start":"2026-04-02T22:22:40.913332Z","end":"2026-04-02T22:22:41.041747Z","steps":["trace[183816289] 'agreement among raft nodes before linearized reading' (duration: 128.332762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:41.04163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.013985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:22:41.041978Z","caller":"traceutil/trace.go:171","msg":"trace[1059911069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"136.382986ms","start":"2026-04-02T22:22:40.905581Z","end":"2026-04-02T22:22:41.041964Z","steps":["trace[1059911069] 'agreement among raft nodes before linearized reading' (duration: 135.85549ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:41.361008Z","caller":"traceutil/trace.go:171","msg":"trace[1582196788] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"234.607393ms","start":"2026-04-02T22:22:41.126374Z","end":"2026-04-02T22:22:41.360981Z","steps":["trace[1582196788] 'process raft request' (duration: 139.326534ms)","trace[1582196788] 'compare' (duration: 95.159786ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:41.361803Z","caller":"traceutil/trace.go:171","msg":"trace[534913174] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"230.970214ms","start":"2026-04-02T22:22:41.130809Z","end":"2026-04-02T22:22:41.361779Z","steps":["trace[534913174] 'process raft request' (duration: 230.805249ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:41.361988Z","caller":"traceutil/trace.go:171","msg":"trace[471622844] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"231.263014ms","start":"2026-04-02T22:22:41.130692Z","end":"2026-04-02T22:22:41.361955Z","steps":["trace[471622844] 'process raft request' (duration: 230.639315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:46.76229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516365091,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:22:46.821136Z","caller":"traceutil/trace.go:171","msg":"trace[865824644] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"692.9318ms","start":"2026-04-02T22:22:46.12817Z","end":"2026-04-02T22:22:46.821102Z","steps":["trace[865824644] 'process raft request' (duration: 692.176348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:46.821299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.128152Z","time spent":"693.064154ms","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:47.150509Z","caller":"traceutil/trace.go:171","msg":"trace[1773711607] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:640; }","duration":"889.026693ms","start":"2026-04-02T22:22:46.261433Z","end":"2026-04-02T22:22:47.15046Z","steps":["trace[1773711607] 'read index received' (duration: 558.947208ms)","trace[1773711607] 'applied index is now lower than readState.Index' (duration: 330.078545ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:47.150591Z","caller":"traceutil/trace.go:171","msg":"trace[1223761653] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"777.018091ms","start":"2026-04-02T22:22:46.37354Z","end":"2026-04-02T22:22:47.150558Z","steps":["trace[1223761653] 'process raft request' (duration: 770.99019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.150676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.102975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.150706Z","caller":"traceutil/trace.go:171","msg":"trace[131600855] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:619; }","duration":"889.190788ms","start":"2026-04-02T22:22:46.261504Z","end":"2026-04-02T22:22:47.150695Z","steps":["trace[131600855] 'agreement among raft nodes before linearized reading' (duration: 889.112065ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.150726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.373525Z","time spent":"777.125654ms","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:47.150768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.066175ms","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":"warn","ts":"2026-04-02T22:22:47.150744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.037803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.150787Z","caller":"traceutil/trace.go:171","msg":"trace[47271968] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:619; }","duration":"836.104425ms","start":"2026-04-02T22:22:46.314677Z","end":"2026-04-02T22:22:47.150781Z","steps":["trace[47271968] 'agreement among raft nodes before linearized reading' (duration: 836.074924ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.150809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.314667Z","time spent":"836.137457ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"info","ts":"2026-04-02T22:22:47.150823Z","caller":"traceutil/trace.go:171","msg":"trace[956408865] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"821.123786ms","start":"2026-04-02T22:22:46.329673Z","end":"2026-04-02T22:22:47.150797Z","steps":["trace[956408865] 'agreement among raft nodes before linearized reading' (duration: 821.004352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.150735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.261483Z","time spent":"889.24485ms","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T22:22:47.150888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.329667Z","time spent":"821.204658ms","remote":"127.0.0.1:50564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T22:22:47.151002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.872132ms","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-02T22:22:47.151022Z","caller":"traceutil/trace.go:171","msg":"trace[1852206814] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:619; }","duration":"840.907643ms","start":"2026-04-02T22:22:46.310108Z","end":"2026-04-02T22:22:47.151016Z","steps":["trace[1852206814] 'agreement among raft nodes before linearized reading' (duration: 840.872872ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.310103Z","time spent":"840.936583ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.151086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.553958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.037367ms","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-02T22:22:47.151156Z","caller":"traceutil/trace.go:171","msg":"trace[1570124740] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:619; }","duration":"841.083068ms","start":"2026-04-02T22:22:46.310067Z","end":"2026-04-02T22:22:47.15115Z","steps":["trace[1570124740] 'agreement among raft nodes before linearized reading' (duration: 841.048377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.310057Z","time spent":"841.115059ms","remote":"127.0.0.1:50560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T22:22:47.151158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.412623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.151233Z","caller":"traceutil/trace.go:171","msg":"trace[772692692] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:619; }","duration":"754.524316ms","start":"2026-04-02T22:22:46.396682Z","end":"2026-04-02T22:22:47.151206Z","steps":["trace[772692692] 'agreement among raft nodes before linearized reading' (duration: 754.423453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.597396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.396655Z","time spent":"754.625788ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.151309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.360468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.151335Z","caller":"traceutil/trace.go:171","msg":"trace[2128977650] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:619; }","duration":"861.650337ms","start":"2026-04-02T22:22:46.289668Z","end":"2026-04-02T22:22:47.151318Z","steps":["trace[2128977650] 'agreement among raft nodes before linearized reading' (duration: 861.572135ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.289643Z","time spent":"861.713659ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 "} {"level":"info","ts":"2026-04-02T22:22:47.151369Z","caller":"traceutil/trace.go:171","msg":"trace[1554336850] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:619; }","duration":"802.419499ms","start":"2026-04-02T22:22:46.34893Z","end":"2026-04-02T22:22:47.15135Z","steps":["trace[1554336850] 'agreement among raft nodes before linearized reading' (duration: 802.343347ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.944477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.15144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.73552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.151477Z","caller":"traceutil/trace.go:171","msg":"trace[1306594539] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:619; }","duration":"861.783332ms","start":"2026-04-02T22:22:46.289686Z","end":"2026-04-02T22:22:47.151469Z","steps":["trace[1306594539] 'agreement among raft nodes before linearized reading' (duration: 861.73128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.689139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.289683Z","time spent":"861.814092ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":89,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 "} {"level":"warn","ts":"2026-04-02T22:22:47.151418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.348919Z","time spent":"802.483211ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-04-02T22:22:47.151543Z","caller":"traceutil/trace.go:171","msg":"trace[900477210] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:619; }","duration":"861.808212ms","start":"2026-04-02T22:22:46.289704Z","end":"2026-04-02T22:22:47.151512Z","steps":["trace[900477210] 'agreement among raft nodes before linearized reading' (duration: 861.630637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.59584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.158265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.289701Z","time spent":"861.883954ms","remote":"127.0.0.1:50554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:22:47.151535Z","caller":"traceutil/trace.go:171","msg":"trace[936318391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:619; }","duration":"763.076141ms","start":"2026-04-02T22:22:46.388387Z","end":"2026-04-02T22:22:47.151463Z","steps":["trace[936318391] 'agreement among raft nodes before linearized reading' (duration: 762.911167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.9773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.103443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.151706Z","caller":"traceutil/trace.go:171","msg":"trace[1021740882] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:619; }","duration":"755.169834ms","start":"2026-04-02T22:22:46.396529Z","end":"2026-04-02T22:22:47.151699Z","steps":["trace[1021740882] 'agreement among raft nodes before linearized reading' (duration: 755.138813ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.498495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.396498Z","time spent":"755.227886ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-02T22:22:47.151745Z","caller":"traceutil/trace.go:171","msg":"trace[1740830388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:619; }","duration":"739.547476ms","start":"2026-04-02T22:22:46.412185Z","end":"2026-04-02T22:22:47.151733Z","steps":["trace[1740830388] 'agreement among raft nodes before linearized reading' (duration: 739.495605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.412176Z","time spent":"739.590887ms","remote":"127.0.0.1:50606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.151784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.876467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.150739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.746524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.151776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.388357Z","time spent":"763.323238ms","remote":"127.0.0.1:36448","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-02T22:22:47.151238Z","caller":"traceutil/trace.go:171","msg":"trace[352437157] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:619; }","duration":"889.775525ms","start":"2026-04-02T22:22:46.261409Z","end":"2026-04-02T22:22:47.151185Z","steps":["trace[352437157] 'agreement among raft nodes before linearized reading' (duration: 889.443815ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:47.151578Z","caller":"traceutil/trace.go:171","msg":"trace[1627036863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"820.621291ms","start":"2026-04-02T22:22:46.330951Z","end":"2026-04-02T22:22:47.151573Z","steps":["trace[1627036863] 'agreement among raft nodes before linearized reading' (duration: 820.59121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.261396Z","time spent":"890.509435ms","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.151958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.330947Z","time spent":"821.003441ms","remote":"127.0.0.1:50564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-04-02T22:22:47.151853Z","caller":"traceutil/trace.go:171","msg":"trace[1765088529] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:619; }","duration":"784.917947ms","start":"2026-04-02T22:22:46.366921Z","end":"2026-04-02T22:22:47.151839Z","steps":["trace[1765088529] 'agreement among raft nodes before linearized reading' (duration: 783.731563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.152047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.366885Z","time spent":"785.143094ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-04-02T22:22:47.1517Z","caller":"traceutil/trace.go:171","msg":"trace[1894532406] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:619; }","duration":"739.609618ms","start":"2026-04-02T22:22:46.412078Z","end":"2026-04-02T22:22:47.151687Z","steps":["trace[1894532406] 'agreement among raft nodes before linearized reading' (duration: 738.98027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.151242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.574788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.152842Z","caller":"traceutil/trace.go:171","msg":"trace[83305787] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:619; }","duration":"786.140012ms","start":"2026-04-02T22:22:46.366638Z","end":"2026-04-02T22:22:47.152778Z","steps":["trace[83305787] 'agreement among raft nodes before linearized reading' (duration: 784.556537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.153179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.366632Z","time spent":"786.339307ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.151608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.318695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:22:47.153781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.412034Z","time spent":"740.090181ms","remote":"127.0.0.1:50606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-04-02T22:22:47.154913Z","caller":"traceutil/trace.go:171","msg":"trace[743208726] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:619; }","duration":"806.632319ms","start":"2026-04-02T22:22:46.348265Z","end":"2026-04-02T22:22:47.154897Z","steps":["trace[743208726] 'agreement among raft nodes before linearized reading' (duration: 803.325655ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.155006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.348254Z","time spent":"806.702971ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:22:47.151617Z","caller":"traceutil/trace.go:171","msg":"trace[225591748] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:619; }","duration":"784.240947ms","start":"2026-04-02T22:22:46.36736Z","end":"2026-04-02T22:22:47.151601Z","steps":["trace[225591748] 'agreement among raft nodes before linearized reading' (duration: 784.143935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.155212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.367354Z","time spent":"787.81571ms","remote":"127.0.0.1:50584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-04-02T22:22:47.151638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.392137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.155437Z","caller":"traceutil/trace.go:171","msg":"trace[734382437] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:619; }","duration":"807.167994ms","start":"2026-04-02T22:22:46.34823Z","end":"2026-04-02T22:22:47.155398Z","steps":["trace[734382437] 'agreement among raft nodes before linearized reading' (duration: 803.390747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.155512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.348225Z","time spent":"807.247577ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-04-02T22:22:47.151656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.185096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:47.155777Z","caller":"traceutil/trace.go:171","msg":"trace[1522625411] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:619; }","duration":"743.330094ms","start":"2026-04-02T22:22:46.412409Z","end":"2026-04-02T22:22:47.155739Z","steps":["trace[1522625411] 'agreement among raft nodes before linearized reading' (duration: 739.167356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.155849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.412382Z","time spent":"743.429136ms","remote":"127.0.0.1:50598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-04-02T22:22:47.15184Z","caller":"traceutil/trace.go:171","msg":"trace[2013056815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:619; }","duration":"821.941409ms","start":"2026-04-02T22:22:46.329882Z","end":"2026-04-02T22:22:47.151823Z","steps":["trace[2013056815] 'agreement among raft nodes before linearized reading' (duration: 821.856076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:47.15604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:46.329878Z","time spent":"826.148918ms","remote":"127.0.0.1:50564","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-02T22:22:47.885763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516365095,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T22:22:47.996668Z","caller":"traceutil/trace.go:171","msg":"trace[1331566419] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"839.261237ms","start":"2026-04-02T22:22:47.157367Z","end":"2026-04-02T22:22:47.996628Z","steps":["trace[1331566419] 'process raft request' (duration: 768.921809ms)","trace[1331566419] 'compare' (duration: 70.054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:47.996874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.15735Z","time spent":"839.400681ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:48.059528Z","caller":"traceutil/trace.go:171","msg":"trace[2120770201] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"901.85241ms","start":"2026-04-02T22:22:47.157649Z","end":"2026-04-02T22:22:48.059502Z","steps":["trace[2120770201] 'process raft request' (duration: 901.700126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:48.059692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.157637Z","time spent":"901.962543ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:48.059738Z","caller":"traceutil/trace.go:171","msg":"trace[1479792157] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"901.970983ms","start":"2026-04-02T22:22:47.157742Z","end":"2026-04-02T22:22:48.059713Z","steps":["trace[1479792157] 'process raft request' (duration: 901.716276ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:48.059895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.15773Z","time spent":"902.078886ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:48.060137Z","caller":"traceutil/trace.go:171","msg":"trace[1939661394] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:642; }","duration":"674.994286ms","start":"2026-04-02T22:22:47.385117Z","end":"2026-04-02T22:22:48.060112Z","steps":["trace[1939661394] 'read index received' (duration: 541.259843ms)","trace[1939661394] 'applied index is now lower than readState.Index' (duration: 133.733313ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:48.060187Z","caller":"traceutil/trace.go:171","msg":"trace[1212300043] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"848.499524ms","start":"2026-04-02T22:22:47.211664Z","end":"2026-04-02T22:22:48.060163Z","steps":["trace[1212300043] 'process raft request' (duration: 848.35042ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:48.060232Z","caller":"traceutil/trace.go:171","msg":"trace[162381701] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"902.442756ms","start":"2026-04-02T22:22:47.157754Z","end":"2026-04-02T22:22:48.060197Z","steps":["trace[162381701] 'process raft request' (duration: 901.87425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:48.060301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.211628Z","time spent":"848.608638ms","remote":"127.0.0.1:36764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T22:22:48.060358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.206192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:48.060423Z","caller":"traceutil/trace.go:171","msg":"trace[1125489599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"675.376516ms","start":"2026-04-02T22:22:47.385036Z","end":"2026-04-02T22:22:48.060412Z","steps":["trace[1125489599] 'agreement among raft nodes before linearized reading' (duration: 675.169541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:48.060506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.385014Z","time spent":"675.48166ms","remote":"127.0.0.1:36444","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-02T22:22:48.061054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:47.157745Z","time spent":"902.528689ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:49.621791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.1288ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:49.622007Z","caller":"traceutil/trace.go:171","msg":"trace[801464701] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:624; }","duration":"867.353957ms","start":"2026-04-02T22:22:48.754621Z","end":"2026-04-02T22:22:49.621975Z","steps":["trace[801464701] 'range keys from in-memory index tree' (duration: 867.101069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:49.63273Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217350969s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:22:49.633151Z","caller":"traceutil/trace.go:171","msg":"trace[967926829] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.217861904s","start":"2026-04-02T22:22:48.415263Z","end":"2026-04-02T22:22:49.633125Z","steps":["trace[967926829] 'process raft request' (duration: 1.217680529s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:49.633285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:48.415231Z","time spent":"1.217970397s","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:22:50.587054Z","caller":"traceutil/trace.go:171","msg":"trace[438796156] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:647; }","duration":"1.315350124s","start":"2026-04-02T22:22:49.271678Z","end":"2026-04-02T22:22:50.587028Z","steps":["trace[438796156] 'read index received' (duration: 361.294149ms)","trace[438796156] 'applied index is now lower than readState.Index' (duration: 954.054864ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:22:50.587132Z","caller":"traceutil/trace.go:171","msg":"trace[1237122614] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.120546023s","start":"2026-04-02T22:22:48.466543Z","end":"2026-04-02T22:22:50.587089Z","steps":["trace[1237122614] 'process raft request' (duration: 2.119375851s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.587265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201368927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:22:50.587309Z","caller":"traceutil/trace.go:171","msg":"trace[923770173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.201498431s","start":"2026-04-02T22:22:49.385799Z","end":"2026-04-02T22:22:50.587298Z","steps":["trace[923770173] 'agreement among raft nodes before linearized reading' (duration: 1.201411929s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.587324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:48.466509Z","time spent":"2.120703538s","remote":"127.0.0.1:36488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:22:50.587344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:49.385776Z","time spent":"1.201560232s","remote":"127.0.0.1:36444","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-02T22:22:50.587451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315765195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-02T22:22:50.587663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.366809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T22:22:50.587675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.554819ms","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-02T22:22:50.587683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.384923ms","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-02T22:22:50.587701Z","caller":"traceutil/trace.go:171","msg":"trace[1328671408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"523.455301ms","start":"2026-04-02T22:22:50.064236Z","end":"2026-04-02T22:22:50.587691Z","steps":["trace[1328671408] 'agreement among raft nodes before linearized reading' (duration: 523.302437ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:50.587711Z","caller":"traceutil/trace.go:171","msg":"trace[468267210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"584.450575ms","start":"2026-04-02T22:22:50.003252Z","end":"2026-04-02T22:22:50.587703Z","steps":["trace[468267210] 'agreement among raft nodes before linearized reading' (duration: 584.354843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.58773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:50.064213Z","time spent":"523.510273ms","remote":"127.0.0.1:36662","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-02T22:22:50.587738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:50.003223Z","time spent":"584.509407ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T22:22:50.587706Z","caller":"traceutil/trace.go:171","msg":"trace[264626354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"521.60087ms","start":"2026-04-02T22:22:50.066096Z","end":"2026-04-02T22:22:50.587697Z","steps":["trace[264626354] 'agreement among raft nodes before linearized reading' (duration: 521.508537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.587818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:50.066085Z","time spent":"521.725603ms","remote":"127.0.0.1:36662","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-02T22:22:50.587502Z","caller":"traceutil/trace.go:171","msg":"trace[781037099] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:626; }","duration":"1.315851877s","start":"2026-04-02T22:22:49.271635Z","end":"2026-04-02T22:22:50.587487Z","steps":["trace[781037099] 'agreement among raft nodes before linearized reading' (duration: 1.315502268s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.587957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:49.27159Z","time spent":"1.316361561s","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-04-02T22:22:50.588057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.901268ms","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-02T22:22:50.588243Z","caller":"traceutil/trace.go:171","msg":"trace[1565682071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"522.222507ms","start":"2026-04-02T22:22:50.065991Z","end":"2026-04-02T22:22:50.588213Z","steps":["trace[1565682071] 'agreement among raft nodes before linearized reading' (duration: 521.480737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:22:50.588408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:22:50.06598Z","time spent":"522.407762ms","remote":"127.0.0.1:36662","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-02T22:22:55.359792Z","caller":"traceutil/trace.go:171","msg":"trace[971671912] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"184.575426ms","start":"2026-04-02T22:22:55.175186Z","end":"2026-04-02T22:22:55.359762Z","steps":["trace[971671912] 'process raft request' (duration: 175.178588ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:55.45775Z","caller":"traceutil/trace.go:171","msg":"trace[1309349417] linearizableReadLoop","detail":"{readStateIndex:727; appliedIndex:725; }","duration":"188.667864ms","start":"2026-04-02T22:22:55.26903Z","end":"2026-04-02T22:22:55.457698Z","steps":["trace[1309349417] 'read index received' (duration: 81.341994ms)","trace[1309349417] 'applied index is now lower than readState.Index' (duration: 107.32367ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:22:55.458139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.083734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-02T22:22:55.458261Z","caller":"traceutil/trace.go:171","msg":"trace[1797996789] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"189.228868ms","start":"2026-04-02T22:22:55.268982Z","end":"2026-04-02T22:22:55.45821Z","steps":["trace[1797996789] 'agreement among raft nodes before linearized reading' (duration: 188.828468ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:22:55.806504Z","caller":"traceutil/trace.go:171","msg":"trace[1660271339] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"112.698041ms","start":"2026-04-02T22:22:55.693773Z","end":"2026-04-02T22:22:55.806472Z","steps":["trace[1660271339] 'process raft request' (duration: 112.538206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:06.766486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516365424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:23:07.266638Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232310427516365424,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T22:23:07.424162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.194254624s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T22:23:07.424621Z","caller":"traceutil/trace.go:171","msg":"trace[385526344] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"1.158396915s","start":"2026-04-02T22:23:06.266194Z","end":"2026-04-02T22:23:07.42459Z","steps":["trace[385526344] 'read index received' (duration: 1.158159819s)","trace[385526344] 'applied index is now lower than readState.Index' (duration: 236.016µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:23:07.424756Z","caller":"traceutil/trace.go:171","msg":"trace[1038496329] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"1.19492671s","start":"2026-04-02T22:23:06.22978Z","end":"2026-04-02T22:23:07.424707Z","steps":["trace[1038496329] 'process raft request' (duration: 1.194643523s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.68197ms","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-02T22:23:07.424798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.158573209s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:23:07.425216Z","caller":"traceutil/trace.go:171","msg":"trace[2130521229] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:792; }","duration":"719.877275ms","start":"2026-04-02T22:23:06.705313Z","end":"2026-04-02T22:23:07.42519Z","steps":["trace[2130521229] 'agreement among raft nodes before linearized reading' (duration: 719.575178ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.335991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-02T22:23:07.424842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039683493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:23:07.425239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.809193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:23:07.425294Z","caller":"traceutil/trace.go:171","msg":"trace[15809120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:792; }","duration":"1.040173594s","start":"2026-04-02T22:23:06.385109Z","end":"2026-04-02T22:23:07.425283Z","steps":["trace[15809120] 'agreement among raft nodes before linearized reading' (duration: 1.039651322s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.705281Z","time spent":"719.983657ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T22:23:07.425301Z","caller":"traceutil/trace.go:171","msg":"trace[719318396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:792; }","duration":"691.484404ms","start":"2026-04-02T22:23:06.733793Z","end":"2026-04-02T22:23:07.425277Z","steps":["trace[719318396] 'agreement among raft nodes before linearized reading' (duration: 691.158797ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:23:07.425335Z","caller":"traceutil/trace.go:171","msg":"trace[171364772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"719.968527ms","start":"2026-04-02T22:23:06.705343Z","end":"2026-04-02T22:23:07.425312Z","steps":["trace[171364772] 'agreement among raft nodes before linearized reading' (duration: 719.6678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.424973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.681167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:23:07.425239Z","caller":"traceutil/trace.go:171","msg":"trace[663544143] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:792; }","duration":"1.159043121s","start":"2026-04-02T22:23:06.266167Z","end":"2026-04-02T22:23:07.42521Z","steps":["trace[663544143] 'agreement among raft nodes before linearized reading' (duration: 1.158559559s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.705303Z","time spent":"720.07247ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:23:07.425051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.270422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T22:23:07.425399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.266157Z","time spent":"1.159229605s","remote":"127.0.0.1:36552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-04-02T22:23:07.425429Z","caller":"traceutil/trace.go:171","msg":"trace[212869382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:792; }","duration":"727.696322ms","start":"2026-04-02T22:23:06.697722Z","end":"2026-04-02T22:23:07.425419Z","steps":["trace[212869382] 'agreement among raft nodes before linearized reading' (duration: 727.154009ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.858327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:23:07.425391Z","caller":"traceutil/trace.go:171","msg":"trace[1517127322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"719.120867ms","start":"2026-04-02T22:23:06.706258Z","end":"2026-04-02T22:23:07.425379Z","steps":["trace[1517127322] 'agreement among raft nodes before linearized reading' (duration: 718.589064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.706248Z","time spent":"719.26558ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T22:23:07.425528Z","caller":"traceutil/trace.go:171","msg":"trace[1045921375] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:792; }","duration":"179.43673ms","start":"2026-04-02T22:23:07.246059Z","end":"2026-04-02T22:23:07.425496Z","steps":["trace[1045921375] 'agreement among raft nodes before linearized reading' (duration: 178.812656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:23:07.425335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.385047Z","time spent":"1.040277996s","remote":"127.0.0.1:36448","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-02T22:23:07.425352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.73378Z","time spent":"691.563197ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:23:07.425461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.697659Z","time spent":"727.793504ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T22:23:07.426183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:23:06.22976Z","time spent":"1.195393741s","remote":"127.0.0.1:36574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:18.090285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.824141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T22:24:18.090402Z","caller":"traceutil/trace.go:171","msg":"trace[1497996029] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1158; }","duration":"139.980363ms","start":"2026-04-02T22:24:17.950397Z","end":"2026-04-02T22:24:18.090377Z","steps":["trace[1497996029] 'range keys from in-memory index tree' (duration: 139.629158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:18.090476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.639878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:24:18.090592Z","caller":"traceutil/trace.go:171","msg":"trace[632017548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"197.842342ms","start":"2026-04-02T22:24:17.892727Z","end":"2026-04-02T22:24:18.090569Z","steps":["trace[632017548] 'range keys from in-memory index tree' (duration: 197.436475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:18.090638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.558659ms","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-02T22:24:18.090688Z","caller":"traceutil/trace.go:171","msg":"trace[874776000] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1158; }","duration":"158.65686ms","start":"2026-04-02T22:24:17.932018Z","end":"2026-04-02T22:24:18.090675Z","steps":["trace[874776000] 'range keys from in-memory index tree' (duration: 158.385586ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:34.57349Z","caller":"traceutil/trace.go:171","msg":"trace[1654330692] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"324.630473ms","start":"2026-04-02T22:24:34.248826Z","end":"2026-04-02T22:24:34.573457Z","steps":["trace[1654330692] 'process raft request' (duration: 296.59643ms)","trace[1654330692] 'compare' (duration: 27.860931ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:34.573524Z","caller":"traceutil/trace.go:171","msg":"trace[804107476] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"322.745276ms","start":"2026-04-02T22:24:34.250723Z","end":"2026-04-02T22:24:34.573468Z","steps":["trace[804107476] 'read index received' (duration: 294.634542ms)","trace[804107476] 'applied index is now lower than readState.Index' (duration: 28.105874ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:34.573669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:34.248812Z","time spent":"324.758046ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:24:34.575949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.0597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-04-02T22:24:34.575972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.241192ms","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-02T22:24:34.576023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.28564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:34.576037Z","caller":"traceutil/trace.go:171","msg":"trace[2075702188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1213; }","duration":"323.193253ms","start":"2026-04-02T22:24:34.252823Z","end":"2026-04-02T22:24:34.576017Z","steps":["trace[2075702188] 'agreement among raft nodes before linearized reading' (duration: 322.950699ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:34.576052Z","caller":"traceutil/trace.go:171","msg":"trace[1086273833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1213; }","duration":"325.377414ms","start":"2026-04-02T22:24:34.250653Z","end":"2026-04-02T22:24:34.576031Z","steps":["trace[1086273833] 'agreement among raft nodes before linearized reading' (duration: 322.99811ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:34.576085Z","caller":"traceutil/trace.go:171","msg":"trace[1081900433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1213; }","duration":"187.394841ms","start":"2026-04-02T22:24:34.388672Z","end":"2026-04-02T22:24:34.576067Z","steps":["trace[1081900433] 'agreement among raft nodes before linearized reading' (duration: 187.27221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:34.576221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:34.25281Z","time spent":"323.399916ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:34.57611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:24:34.250629Z","time spent":"325.468016ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T22:24:34.575972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.307883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/eg\" ","response":"range_response_count:1 size:1086"} {"level":"info","ts":"2026-04-02T22:24:34.576366Z","caller":"traceutil/trace.go:171","msg":"trace[116779575] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/eg; range_end:; response_count:1; response_revision:1213; }","duration":"183.726518ms","start":"2026-04-02T22:24:34.392619Z","end":"2026-04-02T22:24:34.576346Z","steps":["trace[116779575] 'agreement among raft nodes before linearized reading' (duration: 183.228921ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:51.951764Z","caller":"traceutil/trace.go:171","msg":"trace[1040822227] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"126.653525ms","start":"2026-04-02T22:24:51.825083Z","end":"2026-04-02T22:24:51.951736Z","steps":["trace[1040822227] 'read index received' (duration: 126.474122ms)","trace[1040822227] 'applied index is now lower than readState.Index' (duration: 178.393µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:24:51.951829Z","caller":"traceutil/trace.go:171","msg":"trace[1782703601] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"139.191905ms","start":"2026-04-02T22:24:51.812597Z","end":"2026-04-02T22:24:51.951789Z","steps":["trace[1782703601] 'process raft request' (duration: 138.976382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:24:51.95192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.809167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:24:51.95196Z","caller":"traceutil/trace.go:171","msg":"trace[1982608613] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1302; }","duration":"126.907678ms","start":"2026-04-02T22:24:51.82504Z","end":"2026-04-02T22:24:51.951948Z","steps":["trace[1982608613] 'agreement among raft nodes before linearized reading' (duration: 126.816507ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:54.644812Z","caller":"traceutil/trace.go:171","msg":"trace[165938592] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"170.239159ms","start":"2026-04-02T22:24:54.47455Z","end":"2026-04-02T22:24:54.644789Z","steps":["trace[165938592] 'process raft request' (duration: 170.034917ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:24:54.644797Z","caller":"traceutil/trace.go:171","msg":"trace[1535092995] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"106.734172ms","start":"2026-04-02T22:24:54.538028Z","end":"2026-04-02T22:24:54.644762Z","steps":["trace[1535092995] 'read index received' (duration: 106.459478ms)","trace[1535092995] 'applied index is now lower than readState.Index' (duration: 273.214µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:24:54.645067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.022146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2474"} {"level":"info","ts":"2026-04-02T22:24:54.645137Z","caller":"traceutil/trace.go:171","msg":"trace[255943053] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1313; }","duration":"107.159648ms","start":"2026-04-02T22:24:54.53796Z","end":"2026-04-02T22:24:54.645119Z","steps":["trace[255943053] 'agreement among raft nodes before linearized reading' (duration: 106.901744ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:00.705531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.737958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T22:25:00.705562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.292693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:25:00.705613Z","caller":"traceutil/trace.go:171","msg":"trace[1346333816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"318.859059ms","start":"2026-04-02T22:25:00.386734Z","end":"2026-04-02T22:25:00.705593Z","steps":["trace[1346333816] 'range keys from in-memory index tree' (duration: 318.666387ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:00.70565Z","caller":"traceutil/trace.go:171","msg":"trace[1156581135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"276.410555ms","start":"2026-04-02T22:25:00.429217Z","end":"2026-04-02T22:25:00.705628Z","steps":["trace[1156581135] 'range keys from in-memory index tree' (duration: 276.118061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:00.705656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:00.386716Z","time spent":"318.93131ms","remote":"127.0.0.1:36444","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-02T22:25:00.8325Z","caller":"traceutil/trace.go:171","msg":"trace[1910098308] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"123.186718ms","start":"2026-04-02T22:25:00.709285Z","end":"2026-04-02T22:25:00.832472Z","steps":["trace[1910098308] 'process raft request' (duration: 122.937065ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:00.990061Z","caller":"traceutil/trace.go:171","msg":"trace[848972832] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"152.823082ms","start":"2026-04-02T22:25:00.837204Z","end":"2026-04-02T22:25:00.990027Z","steps":["trace[848972832] 'process raft request' (duration: 70.001911ms)","trace[848972832] 'compare' (duration: 82.554627ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:25:20.625224Z","caller":"traceutil/trace.go:171","msg":"trace[189624740] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"217.160163ms","start":"2026-04-02T22:25:20.408038Z","end":"2026-04-02T22:25:20.625199Z","steps":["trace[189624740] 'read index received' (duration: 216.91988ms)","trace[189624740] 'applied index is now lower than readState.Index' (duration: 239.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:25:20.625309Z","caller":"traceutil/trace.go:171","msg":"trace[583417201] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"237.190668ms","start":"2026-04-02T22:25:20.388103Z","end":"2026-04-02T22:25:20.625294Z","steps":["trace[583417201] 'process raft request' (duration: 236.937455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:20.625449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.978253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-04-02T22:25:20.625434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.367335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:25:20.625492Z","caller":"traceutil/trace.go:171","msg":"trace[269356955] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1406; }","duration":"207.061424ms","start":"2026-04-02T22:25:20.418421Z","end":"2026-04-02T22:25:20.625482Z","steps":["trace[269356955] 'agreement among raft nodes before linearized reading' (duration: 206.914852ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:20.625538Z","caller":"traceutil/trace.go:171","msg":"trace[248118453] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1406; }","duration":"217.498877ms","start":"2026-04-02T22:25:20.40801Z","end":"2026-04-02T22:25:20.625509Z","steps":["trace[248118453] 'agreement among raft nodes before linearized reading' (duration: 217.318034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:20.625458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.453696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T22:25:20.625881Z","caller":"traceutil/trace.go:171","msg":"trace[1280004562] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1406; }","duration":"136.919372ms","start":"2026-04-02T22:25:20.488952Z","end":"2026-04-02T22:25:20.625871Z","steps":["trace[1280004562] 'agreement among raft nodes before linearized reading' (duration: 136.466956ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.289134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.973838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T22:25:21.289216Z","caller":"traceutil/trace.go:171","msg":"trace[2120779732] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1406; }","duration":"321.106499ms","start":"2026-04-02T22:25:20.96809Z","end":"2026-04-02T22:25:21.289197Z","steps":["trace[2120779732] 'range keys from in-memory index tree' (duration: 320.793305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.289259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:20.968056Z","time spent":"321.19444ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T22:25:21.289288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.480612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 ","response":"range_response_count:3 size:3333"} {"level":"info","ts":"2026-04-02T22:25:21.289376Z","caller":"traceutil/trace.go:171","msg":"trace[1429064292] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/; range_end:/registry/roles/envoy-gateway-system0; response_count:3; response_revision:1406; }","duration":"515.604083ms","start":"2026-04-02T22:25:20.773751Z","end":"2026-04-02T22:25:21.289355Z","steps":["trace[1429064292] 'range keys from in-memory index tree' (duration: 515.3243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.289428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:20.773741Z","time spent":"515.676375ms","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":3,"response size":3356,"request content":"key:\"/registry/roles/envoy-gateway-system/\" range_end:\"/registry/roles/envoy-gateway-system0\" limit:500 "} {"level":"warn","ts":"2026-04-02T22:25:21.289551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.599512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T22:25:21.289633Z","caller":"traceutil/trace.go:171","msg":"trace[123583481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1406; }","duration":"224.717983ms","start":"2026-04-02T22:25:21.064897Z","end":"2026-04-02T22:25:21.289615Z","steps":["trace[123583481] 'range keys from in-memory index tree' (duration: 224.370189ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.289907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.867187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:25:21.289981Z","caller":"traceutil/trace.go:171","msg":"trace[334047531] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1406; }","duration":"592.964478ms","start":"2026-04-02T22:25:20.696999Z","end":"2026-04-02T22:25:21.289964Z","steps":["trace[334047531] 'range keys from in-memory index tree' (duration: 592.804895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.290052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:20.69699Z","time spent":"593.045499ms","remote":"127.0.0.1:36732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 "} {"level":"warn","ts":"2026-04-02T22:25:21.290212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.333473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-04-02T22:25:21.290279Z","caller":"traceutil/trace.go:171","msg":"trace[994172449] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1406; }","duration":"531.461835ms","start":"2026-04-02T22:25:20.758799Z","end":"2026-04-02T22:25:21.290261Z","steps":["trace[994172449] 'range keys from in-memory index tree' (duration: 530.442742ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.290343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.202278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-02T22:25:21.29035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:20.75878Z","time spent":"531.539966ms","remote":"127.0.0.1:36746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":1134,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" "} {"level":"info","ts":"2026-04-02T22:25:21.290383Z","caller":"traceutil/trace.go:171","msg":"trace[307672200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1407; }","duration":"143.256589ms","start":"2026-04-02T22:25:21.147118Z","end":"2026-04-02T22:25:21.290375Z","steps":["trace[307672200] 'agreement among raft nodes before linearized reading' (duration: 143.130517ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:21.290222Z","caller":"traceutil/trace.go:171","msg":"trace[838148123] linearizableReadLoop","detail":"{readStateIndex:1466; appliedIndex:1465; }","duration":"143.074017ms","start":"2026-04-02T22:25:21.147135Z","end":"2026-04-02T22:25:21.290209Z","steps":["trace[838148123] 'read index received' (duration: 141.913662ms)","trace[838148123] 'applied index is now lower than readState.Index' (duration: 1.159385ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T22:25:21.29043Z","caller":"traceutil/trace.go:171","msg":"trace[2140910067] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"166.449093ms","start":"2026-04-02T22:25:21.123886Z","end":"2026-04-02T22:25:21.290335Z","steps":["trace[2140910067] 'process raft request' (duration: 165.212058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.289315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.060336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-04-02T22:25:21.290872Z","caller":"traceutil/trace.go:171","msg":"trace[938636403] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1406; }","duration":"534.752867ms","start":"2026-04-02T22:25:20.75609Z","end":"2026-04-02T22:25:21.290843Z","steps":["trace[938636403] 'range keys from in-memory index tree' (duration: 532.934494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.291524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:20.756069Z","time spent":"535.418095ms","remote":"127.0.0.1:36746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":1082,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-04-02T22:25:21.614508Z","caller":"traceutil/trace.go:171","msg":"trace[327627054] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"316.535458ms","start":"2026-04-02T22:25:21.297944Z","end":"2026-04-02T22:25:21.614479Z","steps":["trace[327627054] 'process raft request' (duration: 306.203697ms)","trace[327627054] 'compare' (duration: 10.140679ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:25:21.614655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:21.297923Z","time spent":"316.656ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:25:21.614744Z","caller":"traceutil/trace.go:171","msg":"trace[269380898] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"315.1128ms","start":"2026-04-02T22:25:21.299605Z","end":"2026-04-02T22:25:21.614718Z","steps":["trace[269380898] 'process raft request' (duration: 314.819217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.614888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:21.299599Z","time spent":"315.214012ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T22:25:21.615035Z","caller":"traceutil/trace.go:171","msg":"trace[931376267] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1467; }","duration":"241.647297ms","start":"2026-04-02T22:25:21.373377Z","end":"2026-04-02T22:25:21.615025Z","steps":["trace[931376267] 'read index received' (duration: 230.702528ms)","trace[931376267] 'applied index is now lower than readState.Index' (duration: 10.943789ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T22:25:21.615191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.805119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:25:21.615187Z","caller":"traceutil/trace.go:171","msg":"trace[1701955349] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"315.477056ms","start":"2026-04-02T22:25:21.299639Z","end":"2026-04-02T22:25:21.615116Z","steps":["trace[1701955349] 'process raft request' (duration: 314.982959ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.615356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T22:25:21.299635Z","time spent":"315.628987ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T22:25:21.615383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.16127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T22:25:21.615457Z","caller":"traceutil/trace.go:171","msg":"trace[130861215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"233.248911ms","start":"2026-04-02T22:25:21.382188Z","end":"2026-04-02T22:25:21.615437Z","steps":["trace[130861215] 'agreement among raft nodes before linearized reading' (duration: 233.135769ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:21.615222Z","caller":"traceutil/trace.go:171","msg":"trace[1107569241] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1411; }","duration":"241.867331ms","start":"2026-04-02T22:25:21.373346Z","end":"2026-04-02T22:25:21.615213Z","steps":["trace[1107569241] 'agreement among raft nodes before linearized reading' (duration: 241.761309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.615814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.470464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"warn","ts":"2026-04-02T22:25:21.615822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.31871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:1 size:1059"} {"level":"info","ts":"2026-04-02T22:25:21.615853Z","caller":"traceutil/trace.go:171","msg":"trace[1253341160] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:1411; }","duration":"237.521186ms","start":"2026-04-02T22:25:21.378321Z","end":"2026-04-02T22:25:21.615842Z","steps":["trace[1253341160] 'agreement among raft nodes before linearized reading' (duration: 237.391864ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T22:25:21.615868Z","caller":"traceutil/trace.go:171","msg":"trace[2062699372] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:1; response_revision:1411; }","duration":"236.365871ms","start":"2026-04-02T22:25:21.379488Z","end":"2026-04-02T22:25:21.615854Z","steps":["trace[2062699372] 'agreement among raft nodes before linearized reading' (duration: 236.212219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T22:25:21.616082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.755964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" limit:500 ","response":"range_response_count:5 size:1050264"} {"level":"info","ts":"2026-04-02T22:25:21.616128Z","caller":"traceutil/trace.go:171","msg":"trace[1959732332] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1411; }","duration":"239.833144ms","start":"2026-04-02T22:25:21.376281Z","end":"2026-04-02T22:25:21.616114Z","steps":["trace[1959732332] 'agreement among raft nodes before linearized reading' (duration: 238.967123ms)"],"step_count":1}