{"level":"warn","ts":"2026-07-06T22:16:14.695485Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T22:16:14.695601Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.216: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.216:2380","--initial-cluster=instance=https://199.204.45.216:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.216:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.216: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-07-06T22:16:14.695716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T22:16:14.695732Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-06T22:16:14.695752Z","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-07-06T22:16:14.696723Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"]} {"level":"info","ts":"2026-07-06T22:16:14.696853Z","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.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.216: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-07-06T22:16:14.702498Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.161531ms"} {"level":"info","ts":"2026-07-06T22:16:14.714058Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4e98569549cc430c","cluster-id":"65d70b4d1d4a8bf9"} {"level":"info","ts":"2026-07-06T22:16:14.714183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T22:16:14.714245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 0"} {"level":"info","ts":"2026-07-06T22:16:14.714274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4e98569549cc430c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T22:16:14.714319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became follower at term 1"} {"level":"info","ts":"2026-07-06T22:16:14.714408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"warn","ts":"2026-07-06T22:16:14.727246Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T22:16:14.728744Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T22:16:14.73144Z","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-07-06T22:16:14.73449Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4e98569549cc430c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T22:16:14.73467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4e98569549cc430c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T22:16:14.73464Z","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-07-06T22:16:14.734731Z","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-07-06T22:16:14.734752Z","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-07-06T22:16:14.738396Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c switched to configuration voters=(5663371730606637836)"} {"level":"info","ts":"2026-07-06T22:16:14.738512Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","added-peer-id":"4e98569549cc430c","added-peer-peer-urls":["https://199.204.45.216:2380"]} {"level":"info","ts":"2026-07-06T22:16:14.73927Z","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-07-06T22:16:14.739369Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-06T22:16:14.739392Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.216:2380"} {"level":"info","ts":"2026-07-06T22:16:14.739414Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4e98569549cc430c","initial-advertise-peer-urls":["https://199.204.45.216:2380"],"listen-peer-urls":["https://199.204.45.216:2380"],"advertise-client-urls":["https://199.204.45.216:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.216:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T22:16:14.739433Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T22:16:15.714949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T22:16:15.715041Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T22:16:15.715097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgPreVoteResp from 4e98569549cc430c at term 1"} {"level":"info","ts":"2026-07-06T22:16:15.71512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became candidate at term 2"} {"level":"info","ts":"2026-07-06T22:16:15.715132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c received MsgVoteResp from 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-06T22:16:15.715155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4e98569549cc430c became leader at term 2"} {"level":"info","ts":"2026-07-06T22:16:15.71517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4e98569549cc430c elected leader 4e98569549cc430c at term 2"} {"level":"info","ts":"2026-07-06T22:16:15.716393Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:16:15.717218Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4e98569549cc430c","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.216:2379]}","request-path":"/0/members/4e98569549cc430c/attributes","cluster-id":"65d70b4d1d4a8bf9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T22:16:15.717451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T22:16:15.717537Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T22:16:15.717719Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T22:16:15.717747Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T22:16:15.717935Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65d70b4d1d4a8bf9","local-member-id":"4e98569549cc430c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:16:15.71807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:16:15.718138Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T22:16:15.72239Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.216:2379"} {"level":"info","ts":"2026-07-06T22:16:15.723174Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-06T22:16:17.174098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.303263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-06T22:16:17.174209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.472098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-06T22:16:17.174153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.973855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T22:16:17.174219Z","caller":"traceutil/trace.go:171","msg":"trace[1137420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:4; }","duration":"139.479456ms","start":"2026-07-06T22:16:17.034721Z","end":"2026-07-06T22:16:17.174201Z","steps":["trace[1137420] 'range keys from in-memory index tree' (duration: 139.228451ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.174288Z","caller":"traceutil/trace.go:171","msg":"trace[1862540265] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:4; }","duration":"139.114298ms","start":"2026-07-06T22:16:17.035167Z","end":"2026-07-06T22:16:17.174281Z","steps":["trace[1862540265] 'range keys from in-memory index tree' (duration: 138.921684ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.17458Z","caller":"traceutil/trace.go:171","msg":"trace[1838579579] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:0; response_revision:4; }","duration":"107.654683ms","start":"2026-07-06T22:16:17.066579Z","end":"2026-07-06T22:16:17.174233Z","steps":["trace[1838579579] 'range keys from in-memory index tree' (duration: 107.416627ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.174882Z","caller":"traceutil/trace.go:171","msg":"trace[1180764671] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:7; }","duration":"107.905379ms","start":"2026-07-06T22:16:17.066962Z","end":"2026-07-06T22:16:17.174867Z","steps":["trace[1180764671] 'read index received' (duration: 107.876119ms)","trace[1180764671] 'applied index is now lower than readState.Index' (duration: 28.19µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:17.174953Z","caller":"traceutil/trace.go:171","msg":"trace[1364890132] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"108.201247ms","start":"2026-07-06T22:16:17.066743Z","end":"2026-07-06T22:16:17.174945Z","steps":["trace[1364890132] 'process raft request' (duration: 107.981772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:17.175114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.131555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T22:16:17.175246Z","caller":"traceutil/trace.go:171","msg":"trace[1272462502] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:5; }","duration":"108.263538ms","start":"2026-07-06T22:16:17.066961Z","end":"2026-07-06T22:16:17.175224Z","steps":["trace[1272462502] 'agreement among raft nodes before linearized reading' (duration: 108.036792ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.17774Z","caller":"traceutil/trace.go:171","msg":"trace[2047586556] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"106.851443ms","start":"2026-07-06T22:16:17.070866Z","end":"2026-07-06T22:16:17.177717Z","steps":["trace[2047586556] 'process raft request' (duration: 106.803142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:17.178625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.491264ms","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-07-06T22:16:17.178685Z","caller":"traceutil/trace.go:171","msg":"trace[818538174] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"108.589197ms","start":"2026-07-06T22:16:17.070082Z","end":"2026-07-06T22:16:17.178672Z","steps":["trace[818538174] 'agreement among raft nodes before linearized reading' (duration: 108.499705ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:17.178901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.276335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T22:16:17.178954Z","caller":"traceutil/trace.go:171","msg":"trace[1176293508] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"109.337757ms","start":"2026-07-06T22:16:17.069604Z","end":"2026-07-06T22:16:17.178942Z","steps":["trace[1176293508] 'agreement among raft nodes before linearized reading' (duration: 109.266635ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.179218Z","caller":"traceutil/trace.go:171","msg":"trace[831061411] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"110.247729ms","start":"2026-07-06T22:16:17.068959Z","end":"2026-07-06T22:16:17.179207Z","steps":["trace[831061411] 'process raft request' (duration: 108.391151ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.179366Z","caller":"traceutil/trace.go:171","msg":"trace[49228572] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"109.456378ms","start":"2026-07-06T22:16:17.069884Z","end":"2026-07-06T22:16:17.17934Z","steps":["trace[49228572] 'process raft request' (duration: 107.633532ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.179587Z","caller":"traceutil/trace.go:171","msg":"trace[402838629] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"109.003948ms","start":"2026-07-06T22:16:17.070559Z","end":"2026-07-06T22:16:17.179563Z","steps":["trace[402838629] 'process raft request' (duration: 107.068968ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.179735Z","caller":"traceutil/trace.go:171","msg":"trace[1292074951] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"109.219564ms","start":"2026-07-06T22:16:17.070502Z","end":"2026-07-06T22:16:17.179721Z","steps":["trace[1292074951] 'process raft request' (duration: 107.096799ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.179232Z","caller":"traceutil/trace.go:171","msg":"trace[1297340491] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"109.334326ms","start":"2026-07-06T22:16:17.069866Z","end":"2026-07-06T22:16:17.179201Z","steps":["trace[1297340491] 'process raft request' (duration: 107.590712ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.321556Z","caller":"traceutil/trace.go:171","msg":"trace[412895780] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"136.643884ms","start":"2026-07-06T22:16:17.184875Z","end":"2026-07-06T22:16:17.321519Z","steps":["trace[412895780] 'process raft request' (duration: 113.081672ms)","trace[412895780] 'compare' (duration: 23.271475ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:17.32166Z","caller":"traceutil/trace.go:171","msg":"trace[1754760037] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"134.848168ms","start":"2026-07-06T22:16:17.186781Z","end":"2026-07-06T22:16:17.321629Z","steps":["trace[1754760037] 'process raft request' (duration: 134.663484ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.321924Z","caller":"traceutil/trace.go:171","msg":"trace[1763502815] linearizableReadLoop","detail":"{readStateIndex:24; appliedIndex:15; }","duration":"106.828202ms","start":"2026-07-06T22:16:17.214996Z","end":"2026-07-06T22:16:17.321825Z","steps":["trace[1763502815] 'read index received' (duration: 5.264824ms)","trace[1763502815] 'applied index is now lower than readState.Index' (duration: 101.561388ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:17.322083Z","caller":"traceutil/trace.go:171","msg":"trace[1889251031] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"134.338336ms","start":"2026-07-06T22:16:17.187737Z","end":"2026-07-06T22:16:17.322076Z","steps":["trace[1889251031] 'process raft request' (duration: 133.812882ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322131Z","caller":"traceutil/trace.go:171","msg":"trace[600734134] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"134.284685ms","start":"2026-07-06T22:16:17.18783Z","end":"2026-07-06T22:16:17.322115Z","steps":["trace[600734134] 'process raft request' (duration: 133.809282ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322284Z","caller":"traceutil/trace.go:171","msg":"trace[1509056363] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"132.093088ms","start":"2026-07-06T22:16:17.190184Z","end":"2026-07-06T22:16:17.322277Z","steps":["trace[1509056363] 'process raft request' (duration: 131.590006ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322343Z","caller":"traceutil/trace.go:171","msg":"trace[1007473943] transaction","detail":"{read_only:false; number_of_response:0; response_revision:15; }","duration":"135.201557ms","start":"2026-07-06T22:16:17.187135Z","end":"2026-07-06T22:16:17.322336Z","steps":["trace[1007473943] 'process raft request' (duration: 134.388827ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322315Z","caller":"traceutil/trace.go:171","msg":"trace[591189993] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"135.341381ms","start":"2026-07-06T22:16:17.186842Z","end":"2026-07-06T22:16:17.322183Z","steps":["trace[591189993] 'process raft request' (duration: 134.649243ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322275Z","caller":"traceutil/trace.go:171","msg":"trace[1454491757] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"133.815782ms","start":"2026-07-06T22:16:17.188362Z","end":"2026-07-06T22:16:17.322178Z","steps":["trace[1454491757] 'process raft request' (duration: 133.358391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:17.322497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.475889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-06T22:16:17.322448Z","caller":"traceutil/trace.go:171","msg":"trace[1746973133] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"134.394057ms","start":"2026-07-06T22:16:17.187981Z","end":"2026-07-06T22:16:17.322375Z","steps":["trace[1746973133] 'process raft request' (duration: 133.701939ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.322611Z","caller":"traceutil/trace.go:171","msg":"trace[986921036] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:20; }","duration":"107.605902ms","start":"2026-07-06T22:16:17.214981Z","end":"2026-07-06T22:16:17.322587Z","steps":["trace[986921036] 'agreement among raft nodes before linearized reading' (duration: 107.345805ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:17.327476Z","caller":"traceutil/trace.go:171","msg":"trace[1635150271] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"104.39889ms","start":"2026-07-06T22:16:17.223047Z","end":"2026-07-06T22:16:17.327446Z","steps":["trace[1635150271] 'process raft request' (duration: 104.134322ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:21.56999Z","caller":"traceutil/trace.go:171","msg":"trace[411118979] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"126.832583ms","start":"2026-07-06T22:16:21.443147Z","end":"2026-07-06T22:16:21.56998Z","steps":["trace[411118979] 'process raft request' (duration: 61.922183ms)","trace[411118979] 'compare' (duration: 64.726295ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:21.569948Z","caller":"traceutil/trace.go:171","msg":"trace[1170283219] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"125.534091ms","start":"2026-07-06T22:16:21.444395Z","end":"2026-07-06T22:16:21.569929Z","steps":["trace[1170283219] 'read index received' (duration: 60.705893ms)","trace[1170283219] 'applied index is now lower than readState.Index' (duration: 64.827528ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:16:21.57017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.747406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-06T22:16:21.570236Z","caller":"traceutil/trace.go:171","msg":"trace[1377008274] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:260; }","duration":"125.844438ms","start":"2026-07-06T22:16:21.444379Z","end":"2026-07-06T22:16:21.570223Z","steps":["trace[1377008274] 'agreement among raft nodes before linearized reading' (duration: 125.684204ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:28.323755Z","caller":"traceutil/trace.go:171","msg":"trace[1497511356] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"246.69049ms","start":"2026-07-06T22:16:28.077043Z","end":"2026-07-06T22:16:28.323734Z","steps":["trace[1497511356] 'read index received' (duration: 246.514135ms)","trace[1497511356] 'applied index is now lower than readState.Index' (duration: 175.495µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:28.324032Z","caller":"traceutil/trace.go:171","msg":"trace[1835110778] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"298.029122ms","start":"2026-07-06T22:16:28.025958Z","end":"2026-07-06T22:16:28.323988Z","steps":["trace[1835110778] 'process raft request' (duration: 297.658712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:28.324201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.138851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T22:16:28.324241Z","caller":"traceutil/trace.go:171","msg":"trace[1784218554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"247.221312ms","start":"2026-07-06T22:16:28.077008Z","end":"2026-07-06T22:16:28.324229Z","steps":["trace[1784218554] 'agreement among raft nodes before linearized reading' (duration: 246.814172ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.250253Z","caller":"traceutil/trace.go:171","msg":"trace[282766445] linearizableReadLoop","detail":"{readStateIndex:414; appliedIndex:413; }","duration":"191.013874ms","start":"2026-07-06T22:16:39.059208Z","end":"2026-07-06T22:16:39.250222Z","steps":["trace[282766445] 'read index received' (duration: 190.641104ms)","trace[282766445] 'applied index is now lower than readState.Index' (duration: 371.55µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:39.250427Z","caller":"traceutil/trace.go:171","msg":"trace[153347816] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"203.033214ms","start":"2026-07-06T22:16:39.047348Z","end":"2026-07-06T22:16:39.250381Z","steps":["trace[153347816] 'process raft request' (duration: 202.49045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:39.250467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.22718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:39.250524Z","caller":"traceutil/trace.go:171","msg":"trace[105318605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:397; }","duration":"191.361463ms","start":"2026-07-06T22:16:39.059145Z","end":"2026-07-06T22:16:39.250507Z","steps":["trace[105318605] 'agreement among raft nodes before linearized reading' (duration: 191.203359ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.457988Z","caller":"traceutil/trace.go:171","msg":"trace[438565274] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"135.501524ms","start":"2026-07-06T22:16:39.322455Z","end":"2026-07-06T22:16:39.457956Z","steps":["trace[438565274] 'process raft request' (duration: 126.82116ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.458117Z","caller":"traceutil/trace.go:171","msg":"trace[627943096] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"134.757124ms","start":"2026-07-06T22:16:39.323323Z","end":"2026-07-06T22:16:39.45808Z","steps":["trace[627943096] 'process raft request' (duration: 134.529807ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.458195Z","caller":"traceutil/trace.go:171","msg":"trace[1928671333] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"132.510615ms","start":"2026-07-06T22:16:39.325673Z","end":"2026-07-06T22:16:39.458184Z","steps":["trace[1928671333] 'process raft request' (duration: 132.231408ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.458365Z","caller":"traceutil/trace.go:171","msg":"trace[107457746] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"131.993413ms","start":"2026-07-06T22:16:39.326358Z","end":"2026-07-06T22:16:39.458351Z","steps":["trace[107457746] 'process raft request' (duration: 131.618123ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:39.458093Z","caller":"traceutil/trace.go:171","msg":"trace[383091057] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"129.386206ms","start":"2026-07-06T22:16:39.328685Z","end":"2026-07-06T22:16:39.458071Z","steps":["trace[383091057] 'process raft request' (duration: 129.340825ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:45.765195Z","caller":"traceutil/trace.go:171","msg":"trace[2085173316] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"277.866373ms","start":"2026-07-06T22:16:45.487305Z","end":"2026-07-06T22:16:45.765171Z","steps":["trace[2085173316] 'process raft request' (duration: 277.721459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:46.6415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.223951ms","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-07-06T22:16:46.641594Z","caller":"traceutil/trace.go:171","msg":"trace[1845104626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:441; }","duration":"155.362565ms","start":"2026-07-06T22:16:46.486209Z","end":"2026-07-06T22:16:46.641571Z","steps":["trace[1845104626] 'range keys from in-memory index tree' (duration: 155.123998ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:46.881299Z","caller":"traceutil/trace.go:171","msg":"trace[1305132031] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"139.351123ms","start":"2026-07-06T22:16:46.741933Z","end":"2026-07-06T22:16:46.881284Z","steps":["trace[1305132031] 'process raft request' (duration: 132.525597ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.746945Z","caller":"traceutil/trace.go:171","msg":"trace[234010787] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"127.785063ms","start":"2026-07-06T22:16:52.619137Z","end":"2026-07-06T22:16:52.746922Z","steps":["trace[234010787] 'process raft request' (duration: 95.608574ms)","trace[234010787] 'compare' (duration: 32.078787ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:52.750845Z","caller":"traceutil/trace.go:171","msg":"trace[507107256] transaction","detail":"{read_only:false; number_of_response:0; response_revision:511; }","duration":"123.152445ms","start":"2026-07-06T22:16:52.627659Z","end":"2026-07-06T22:16:52.750812Z","steps":["trace[507107256] 'process raft request' (duration: 122.985681ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.75088Z","caller":"traceutil/trace.go:171","msg":"trace[2095713393] transaction","detail":"{read_only:false; number_of_response:0; response_revision:511; }","duration":"123.133025ms","start":"2026-07-06T22:16:52.627717Z","end":"2026-07-06T22:16:52.75085Z","steps":["trace[2095713393] 'process raft request' (duration: 123.026702ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.751076Z","caller":"traceutil/trace.go:171","msg":"trace[1427603526] transaction","detail":"{read_only:false; number_of_response:0; response_revision:511; }","duration":"123.285758ms","start":"2026-07-06T22:16:52.627781Z","end":"2026-07-06T22:16:52.751067Z","steps":["trace[1427603526] 'process raft request' (duration: 123.006071ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.751155Z","caller":"traceutil/trace.go:171","msg":"trace[16717370] transaction","detail":"{read_only:false; number_of_response:0; response_revision:511; }","duration":"123.406881ms","start":"2026-07-06T22:16:52.627741Z","end":"2026-07-06T22:16:52.751148Z","steps":["trace[16717370] 'process raft request' (duration: 123.028552ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.752422Z","caller":"traceutil/trace.go:171","msg":"trace[331125593] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"105.523561ms","start":"2026-07-06T22:16:52.646853Z","end":"2026-07-06T22:16:52.752376Z","steps":["trace[331125593] 'process raft request' (duration: 104.146275ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.752443Z","caller":"traceutil/trace.go:171","msg":"trace[647419232] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"122.276882ms","start":"2026-07-06T22:16:52.630118Z","end":"2026-07-06T22:16:52.752395Z","steps":["trace[647419232] 'process raft request' (duration: 120.705372ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:52.752467Z","caller":"traceutil/trace.go:171","msg":"trace[1262465867] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:531; }","duration":"122.479067ms","start":"2026-07-06T22:16:52.629907Z","end":"2026-07-06T22:16:52.752386Z","steps":["trace[1262465867] 'read index received' (duration: 85.145915ms)","trace[1262465867] 'applied index is now lower than readState.Index' (duration: 37.329792ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:52.752789Z","caller":"traceutil/trace.go:171","msg":"trace[1591264758] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"114.086671ms","start":"2026-07-06T22:16:52.638678Z","end":"2026-07-06T22:16:52.752765Z","steps":["trace[1591264758] 'process raft request' (duration: 112.233143ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:52.753201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.250617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10078"} {"level":"info","ts":"2026-07-06T22:16:52.753266Z","caller":"traceutil/trace.go:171","msg":"trace[951076906] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:514; }","duration":"123.36415ms","start":"2026-07-06T22:16:52.629877Z","end":"2026-07-06T22:16:52.753241Z","steps":["trace[951076906] 'agreement among raft nodes before linearized reading' (duration: 122.847767ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.051665Z","caller":"traceutil/trace.go:171","msg":"trace[898097347] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"215.198809ms","start":"2026-07-06T22:16:55.836442Z","end":"2026-07-06T22:16:56.051641Z","steps":["trace[898097347] 'read index received' (duration: 213.504305ms)","trace[898097347] 'applied index is now lower than readState.Index' (duration: 1.693314ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:56.051777Z","caller":"traceutil/trace.go:171","msg":"trace[2056526589] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"336.196627ms","start":"2026-07-06T22:16:55.715534Z","end":"2026-07-06T22:16:56.05173Z","steps":["trace[2056526589] 'process raft request' (duration: 334.667728ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.051962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.446145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.052021Z","caller":"traceutil/trace.go:171","msg":"trace[198305857] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"215.591558ms","start":"2026-07-06T22:16:55.836414Z","end":"2026-07-06T22:16:56.052006Z","steps":["trace[198305857] 'agreement among raft nodes before linearized reading' (duration: 215.372402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.641099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.052319Z","caller":"traceutil/trace.go:171","msg":"trace[2087158001] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"215.759053ms","start":"2026-07-06T22:16:55.836536Z","end":"2026-07-06T22:16:56.052295Z","steps":["trace[2087158001] 'agreement among raft nodes before linearized reading' (duration: 215.548157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:55.715511Z","time spent":"336.36578ms","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T22:16:56.052321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.834852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.052493Z","caller":"traceutil/trace.go:171","msg":"trace[33464189] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"124.060128ms","start":"2026-07-06T22:16:55.928414Z","end":"2026-07-06T22:16:56.052474Z","steps":["trace[33464189] 'agreement among raft nodes before linearized reading' (duration: 123.78964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.482227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.0526Z","caller":"traceutil/trace.go:171","msg":"trace[178881652] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"108.548048ms","start":"2026-07-06T22:16:55.94404Z","end":"2026-07-06T22:16:56.052588Z","steps":["trace[178881652] 'agreement among raft nodes before linearized reading' (duration: 108.467916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.457482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.052833Z","caller":"traceutil/trace.go:171","msg":"trace[884545446] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"123.495044ms","start":"2026-07-06T22:16:55.929328Z","end":"2026-07-06T22:16:56.052823Z","steps":["trace[884545446] 'agreement among raft nodes before linearized reading' (duration: 123.440592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.327306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.05297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.424458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.052995Z","caller":"traceutil/trace.go:171","msg":"trace[227699683] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"124.49331ms","start":"2026-07-06T22:16:55.928495Z","end":"2026-07-06T22:16:56.052989Z","steps":["trace[227699683] 'agreement among raft nodes before linearized reading' (duration: 124.430437ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.053001Z","caller":"traceutil/trace.go:171","msg":"trace[8073221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"171.416399ms","start":"2026-07-06T22:16:55.881564Z","end":"2026-07-06T22:16:56.05298Z","steps":["trace[8073221] 'agreement among raft nodes before linearized reading' (duration: 171.312336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.052798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.577639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.053153Z","caller":"traceutil/trace.go:171","msg":"trace[221377787] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"108.947768ms","start":"2026-07-06T22:16:55.944196Z","end":"2026-07-06T22:16:56.053144Z","steps":["trace[221377787] 'agreement among raft nodes before linearized reading' (duration: 108.574518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.053218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.655995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.053272Z","caller":"traceutil/trace.go:171","msg":"trace[1849671605] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:576; }","duration":"171.730457ms","start":"2026-07-06T22:16:55.881528Z","end":"2026-07-06T22:16:56.053258Z","steps":["trace[1849671605] 'agreement among raft nodes before linearized reading' (duration: 171.650605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.053565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.093365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:16:56.053622Z","caller":"traceutil/trace.go:171","msg":"trace[1511036710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"177.166568ms","start":"2026-07-06T22:16:55.87644Z","end":"2026-07-06T22:16:56.053607Z","steps":["trace[1511036710] 'agreement among raft nodes before linearized reading' (duration: 177.010894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.053568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.432919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.053701Z","caller":"traceutil/trace.go:171","msg":"trace[1783950683] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:576; }","duration":"216.599695ms","start":"2026-07-06T22:16:55.837088Z","end":"2026-07-06T22:16:56.053688Z","steps":["trace[1783950683] 'agreement among raft nodes before linearized reading' (duration: 216.44042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.053888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.357009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.05393Z","caller":"traceutil/trace.go:171","msg":"trace[406934671] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:576; }","duration":"109.41484ms","start":"2026-07-06T22:16:55.944503Z","end":"2026-07-06T22:16:56.053918Z","steps":["trace[406934671] 'agreement among raft nodes before linearized reading' (duration: 109.337398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.540572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.114913ms","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-07-06T22:16:56.540731Z","caller":"traceutil/trace.go:171","msg":"trace[2003270677] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:576; }","duration":"455.282627ms","start":"2026-07-06T22:16:56.08542Z","end":"2026-07-06T22:16:56.540702Z","steps":["trace[2003270677] 'range keys from in-memory index tree' (duration: 455.079952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.541365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.825788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:16:56.541469Z","caller":"traceutil/trace.go:171","msg":"trace[917566194] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"481.675397ms","start":"2026-07-06T22:16:56.059773Z","end":"2026-07-06T22:16:56.541448Z","steps":["trace[917566194] 'process raft request' (duration: 202.063489ms)","trace[917566194] 'compare' (duration: 278.732926ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:16:56.541626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.059761Z","time spent":"481.766089ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:16:56.541881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.446625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.541461Z","caller":"traceutil/trace.go:171","msg":"trace[1789282273] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"480.058695ms","start":"2026-07-06T22:16:56.061387Z","end":"2026-07-06T22:16:56.541446Z","steps":["trace[1789282273] 'read index received' (duration: 200.396276ms)","trace[1789282273] 'applied index is now lower than readState.Index' (duration: 279.661129ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:56.541949Z","caller":"traceutil/trace.go:171","msg":"trace[900081774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"480.567879ms","start":"2026-07-06T22:16:56.061355Z","end":"2026-07-06T22:16:56.541923Z","steps":["trace[900081774] 'agreement among raft nodes before linearized reading' (duration: 480.438956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.54202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.061346Z","time spent":"480.662592ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:16:56.542145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.717065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.542178Z","caller":"traceutil/trace.go:171","msg":"trace[1511585086] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"477.774727ms","start":"2026-07-06T22:16:56.064396Z","end":"2026-07-06T22:16:56.54217Z","steps":["trace[1511585086] 'agreement among raft nodes before linearized reading' (duration: 477.718135ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.54217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.257693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.5422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.064387Z","time spent":"477.807137ms","remote":"127.0.0.1:56100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-06T22:16:56.542196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.364634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.542285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.107751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.542355Z","caller":"traceutil/trace.go:171","msg":"trace[1223969671] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:577; }","duration":"280.196424ms","start":"2026-07-06T22:16:56.262148Z","end":"2026-07-06T22:16:56.542344Z","steps":["trace[1223969671] 'agreement among raft nodes before linearized reading' (duration: 280.110021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.542494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.204687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.542522Z","caller":"traceutil/trace.go:171","msg":"trace[442035168] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:577; }","duration":"355.267509ms","start":"2026-07-06T22:16:56.187248Z","end":"2026-07-06T22:16:56.542516Z","steps":["trace[442035168] 'agreement among raft nodes before linearized reading' (duration: 355.221748ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.542586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.187234Z","time spent":"355.3043ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:16:56.542378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.659202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.542677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.872662ms","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-07-06T22:16:56.542709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.714454ms","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":"info","ts":"2026-07-06T22:16:56.542747Z","caller":"traceutil/trace.go:171","msg":"trace[438368282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"328.777386ms","start":"2026-07-06T22:16:56.213958Z","end":"2026-07-06T22:16:56.542735Z","steps":["trace[438368282] 'agreement among raft nodes before linearized reading' (duration: 328.719685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.542781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.213942Z","time spent":"328.831387ms","remote":"127.0.0.1:56134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:16:56.542788Z","caller":"traceutil/trace.go:171","msg":"trace[223303159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"327.958714ms","start":"2026-07-06T22:16:56.214764Z","end":"2026-07-06T22:16:56.542723Z","steps":["trace[223303159] 'agreement among raft nodes before linearized reading' (duration: 327.840491ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.542808Z","caller":"traceutil/trace.go:171","msg":"trace[1586909029] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"257.93689ms","start":"2026-07-06T22:16:56.284684Z","end":"2026-07-06T22:16:56.542621Z","steps":["trace[1586909029] 'agreement among raft nodes before linearized reading' (duration: 257.654663ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.542235Z","caller":"traceutil/trace.go:171","msg":"trace[2055653966] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:577; }","duration":"354.350804ms","start":"2026-07-06T22:16:56.187848Z","end":"2026-07-06T22:16:56.542199Z","steps":["trace[2055653966] 'agreement among raft nodes before linearized reading' (duration: 354.262042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.543814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.187836Z","time spent":"355.956006ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-06T22:16:56.542745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.539566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.54231Z","caller":"traceutil/trace.go:171","msg":"trace[254245370] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"257.488988ms","start":"2026-07-06T22:16:56.284801Z","end":"2026-07-06T22:16:56.54229Z","steps":["trace[254245370] 'agreement among raft nodes before linearized reading' (duration: 257.364385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.5429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.214753Z","time spent":"328.108438ms","remote":"127.0.0.1:56134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-07-06T22:16:56.542915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.916849ms","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":"warn","ts":"2026-07-06T22:16:56.54292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.112098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.542954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.689993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.542984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.788425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.543014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.113808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.543045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.512436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.542133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.835474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.543051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.450874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:56.543119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.398919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:56.544178Z","caller":"traceutil/trace.go:171","msg":"trace[2017998493] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:577; }","duration":"356.977553ms","start":"2026-07-06T22:16:56.187178Z","end":"2026-07-06T22:16:56.544155Z","steps":["trace[2017998493] 'agreement among raft nodes before linearized reading' (duration: 355.545456ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.545275Z","caller":"traceutil/trace.go:171","msg":"trace[503289851] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"387.464519ms","start":"2026-07-06T22:16:56.157796Z","end":"2026-07-06T22:16:56.54526Z","steps":["trace[503289851] 'agreement among raft nodes before linearized reading' (duration: 385.067707ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.545751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.18717Z","time spent":"358.517082ms","remote":"127.0.0.1:56118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-07-06T22:16:56.545423Z","caller":"traceutil/trace.go:171","msg":"trace[1442699540] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:577; }","duration":"331.462196ms","start":"2026-07-06T22:16:56.21395Z","end":"2026-07-06T22:16:56.545412Z","steps":["trace[1442699540] 'agreement among raft nodes before linearized reading' (duration: 328.93986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.546065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.21393Z","time spent":"332.117322ms","remote":"127.0.0.1:56134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"info","ts":"2026-07-06T22:16:56.545351Z","caller":"traceutil/trace.go:171","msg":"trace[1707181585] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"388.157507ms","start":"2026-07-06T22:16:56.157173Z","end":"2026-07-06T22:16:56.545331Z","steps":["trace[1707181585] 'agreement among raft nodes before linearized reading' (duration: 385.792895ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.546181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.157163Z","time spent":"389.003349ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-07-06T22:16:56.545475Z","caller":"traceutil/trace.go:171","msg":"trace[1161582884] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"388.229999ms","start":"2026-07-06T22:16:56.15723Z","end":"2026-07-06T22:16:56.54546Z","steps":["trace[1161582884] 'agreement among raft nodes before linearized reading' (duration: 385.705433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.546279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.157212Z","time spent":"389.05096ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:16:56.545509Z","caller":"traceutil/trace.go:171","msg":"trace[1998777027] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:577; }","duration":"480.9871ms","start":"2026-07-06T22:16:56.064514Z","end":"2026-07-06T22:16:56.545501Z","steps":["trace[1998777027] 'agreement among raft nodes before linearized reading' (duration: 478.515777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.54644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.064507Z","time spent":"481.920354ms","remote":"127.0.0.1:56100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:16:56.545529Z","caller":"traceutil/trace.go:171","msg":"trace[433432990] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:577; }","duration":"283.263822ms","start":"2026-07-06T22:16:56.262254Z","end":"2026-07-06T22:16:56.545517Z","steps":["trace[433432990] 'agreement among raft nodes before linearized reading' (duration: 279.808613ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.54554Z","caller":"traceutil/trace.go:171","msg":"trace[1125435672] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:577; }","duration":"406.656264ms","start":"2026-07-06T22:16:56.138875Z","end":"2026-07-06T22:16:56.545531Z","steps":["trace[1125435672] 'agreement among raft nodes before linearized reading' (duration: 404.121838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:56.546698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.138858Z","time spent":"407.829494ms","remote":"127.0.0.1:58436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-06T22:16:56.546496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.157752Z","time spent":"388.090545ms","remote":"127.0.0.1:56108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-07-06T22:16:56.545551Z","caller":"traceutil/trace.go:171","msg":"trace[839489845] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:577; }","duration":"260.979438ms","start":"2026-07-06T22:16:56.284565Z","end":"2026-07-06T22:16:56.545544Z","steps":["trace[839489845] 'agreement among raft nodes before linearized reading' (duration: 258.462854ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:56.54559Z","caller":"traceutil/trace.go:171","msg":"trace[1200462162] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:577; }","duration":"282.903383ms","start":"2026-07-06T22:16:56.262677Z","end":"2026-07-06T22:16:56.545581Z","steps":["trace[1200462162] 'agreement among raft nodes before linearized reading' (duration: 280.398769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.867008ms","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-07-06T22:16:57.093306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.249703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:57.093347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.395617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:57.093359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.682719ms","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-07-06T22:16:57.093354Z","caller":"traceutil/trace.go:171","msg":"trace[1984167094] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"412.025852ms","start":"2026-07-06T22:16:56.681305Z","end":"2026-07-06T22:16:57.093331Z","steps":["trace[1984167094] 'range keys from in-memory index tree' (duration: 411.812436ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.093383Z","caller":"traceutil/trace.go:171","msg":"trace[1602587533] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"432.460199ms","start":"2026-07-06T22:16:56.660911Z","end":"2026-07-06T22:16:57.093371Z","steps":["trace[1602587533] 'range keys from in-memory index tree' (duration: 432.235063ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.093402Z","caller":"traceutil/trace.go:171","msg":"trace[1526548980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"412.748561ms","start":"2026-07-06T22:16:56.680638Z","end":"2026-07-06T22:16:57.093386Z","steps":["trace[1526548980] 'count revisions from in-memory index tree' (duration: 412.630758ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.77507ms","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":"warn","ts":"2026-07-06T22:16:57.093284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.74255ms","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":"warn","ts":"2026-07-06T22:16:57.093436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.680619Z","time spent":"412.807933ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:16:57.093441Z","caller":"traceutil/trace.go:171","msg":"trace[2015897814] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:578; }","duration":"256.845482ms","start":"2026-07-06T22:16:56.836583Z","end":"2026-07-06T22:16:57.093428Z","steps":["trace[2015897814] 'range keys from in-memory index tree' (duration: 256.751479ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.902112ms","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":"info","ts":"2026-07-06T22:16:57.093501Z","caller":"traceutil/trace.go:171","msg":"trace[1215934530] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:578; }","duration":"256.938943ms","start":"2026-07-06T22:16:56.836555Z","end":"2026-07-06T22:16:57.093494Z","steps":["trace[1215934530] 'count revisions from in-memory index tree' (duration: 256.849621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.353305ms","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-07-06T22:16:57.093407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.681297Z","time spent":"412.100274ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"info","ts":"2026-07-06T22:16:57.093377Z","caller":"traceutil/trace.go:171","msg":"trace[337364953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"432.318516ms","start":"2026-07-06T22:16:56.661036Z","end":"2026-07-06T22:16:57.093354Z","steps":["trace[337364953] 'count revisions from in-memory index tree' (duration: 432.207082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.660994Z","time spent":"432.760287ms","remote":"127.0.0.1:56214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:16:57.093427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.660893Z","time spent":"432.52141ms","remote":"127.0.0.1:56214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-07-06T22:16:57.09344Z","caller":"traceutil/trace.go:171","msg":"trace[76479496] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:578; }","duration":"412.952756ms","start":"2026-07-06T22:16:56.680478Z","end":"2026-07-06T22:16:57.093431Z","steps":["trace[76479496] 'range keys from in-memory index tree' (duration: 412.665618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.680467Z","time spent":"413.353695ms","remote":"127.0.0.1:56222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-07-06T22:16:57.093272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.521352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:57.093892Z","caller":"traceutil/trace.go:171","msg":"trace[262724996] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:578; }","duration":"430.192089ms","start":"2026-07-06T22:16:56.663681Z","end":"2026-07-06T22:16:57.093873Z","steps":["trace[262724996] 'range keys from in-memory index tree' (duration: 429.462861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.093956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:16:56.663673Z","time spent":"430.258691ms","remote":"127.0.0.1:56214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"info","ts":"2026-07-06T22:16:57.093551Z","caller":"traceutil/trace.go:171","msg":"trace[497981441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"257.397065ms","start":"2026-07-06T22:16:56.836139Z","end":"2026-07-06T22:16:57.093536Z","steps":["trace[497981441] 'range keys from in-memory index tree' (duration: 257.217631ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.142327Z","caller":"traceutil/trace.go:171","msg":"trace[1969744878] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"187.959016ms","start":"2026-07-06T22:16:56.954343Z","end":"2026-07-06T22:16:57.142302Z","steps":["trace[1969744878] 'read index received' (duration: 187.120453ms)","trace[1969744878] 'applied index is now lower than readState.Index' (duration: 837.613µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:16:57.142387Z","caller":"traceutil/trace.go:171","msg":"trace[1430140676] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"190.187553ms","start":"2026-07-06T22:16:56.952171Z","end":"2026-07-06T22:16:57.142359Z","steps":["trace[1430140676] 'process raft request' (duration: 189.501406ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.142473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.110249ms","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":"info","ts":"2026-07-06T22:16:57.142534Z","caller":"traceutil/trace.go:171","msg":"trace[719276245] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:579; }","duration":"188.202792ms","start":"2026-07-06T22:16:56.954316Z","end":"2026-07-06T22:16:57.142518Z","steps":["trace[719276245] 'agreement among raft nodes before linearized reading' (duration: 188.087119ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.249371Z","caller":"traceutil/trace.go:171","msg":"trace[991167571] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"151.28239ms","start":"2026-07-06T22:16:57.098056Z","end":"2026-07-06T22:16:57.249338Z","steps":["trace[991167571] 'process raft request' (duration: 146.791275ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.249381Z","caller":"traceutil/trace.go:171","msg":"trace[1968125113] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"106.966997ms","start":"2026-07-06T22:16:57.142393Z","end":"2026-07-06T22:16:57.24936Z","steps":["trace[1968125113] 'read index received' (duration: 102.471441ms)","trace[1968125113] 'applied index is now lower than readState.Index' (duration: 4.493216ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:16:57.249734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.689375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:57.249755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.717533ms","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":"info","ts":"2026-07-06T22:16:57.249777Z","caller":"traceutil/trace.go:171","msg":"trace[1823233514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:581; }","duration":"164.781048ms","start":"2026-07-06T22:16:57.084984Z","end":"2026-07-06T22:16:57.249765Z","steps":["trace[1823233514] 'agreement among raft nodes before linearized reading' (duration: 164.45426ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.249796Z","caller":"traceutil/trace.go:171","msg":"trace[573926279] 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:581; }","duration":"217.788834ms","start":"2026-07-06T22:16:57.031994Z","end":"2026-07-06T22:16:57.249783Z","steps":["trace[573926279] 'agreement among raft nodes before linearized reading' (duration: 217.512297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.249828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.509317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:57.249837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.694662ms","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-07-06T22:16:57.249862Z","caller":"traceutil/trace.go:171","msg":"trace[1370089848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"274.569749ms","start":"2026-07-06T22:16:56.975281Z","end":"2026-07-06T22:16:57.249851Z","steps":["trace[1370089848] 'agreement among raft nodes before linearized reading' (duration: 274.22623ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.249871Z","caller":"traceutil/trace.go:171","msg":"trace[1617358556] 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:581; }","duration":"217.764434ms","start":"2026-07-06T22:16:57.032097Z","end":"2026-07-06T22:16:57.249861Z","steps":["trace[1617358556] 'agreement among raft nodes before linearized reading' (duration: 217.350674ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.249894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.304103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:57.249912Z","caller":"traceutil/trace.go:171","msg":"trace[2066400535] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:581; }","duration":"295.349234ms","start":"2026-07-06T22:16:56.954556Z","end":"2026-07-06T22:16:57.249905Z","steps":["trace[2066400535] 'agreement among raft nodes before linearized reading' (duration: 294.861892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.249957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.842066ms","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-07-06T22:16:57.250015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.482432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:16:57.25002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.781277ms","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-07-06T22:16:57.25004Z","caller":"traceutil/trace.go:171","msg":"trace[1732628408] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"275.531953ms","start":"2026-07-06T22:16:56.9745Z","end":"2026-07-06T22:16:57.250031Z","steps":["trace[1732628408] 'agreement among raft nodes before linearized reading' (duration: 275.017941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.250052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.037612ms","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-07-06T22:16:57.25004Z","caller":"traceutil/trace.go:171","msg":"trace[2067155095] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:581; }","duration":"217.963799ms","start":"2026-07-06T22:16:57.032057Z","end":"2026-07-06T22:16:57.25002Z","steps":["trace[2067155095] 'agreement among raft nodes before linearized reading' (duration: 217.405985ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.25007Z","caller":"traceutil/trace.go:171","msg":"trace[1984396193] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"232.085843ms","start":"2026-07-06T22:16:57.01798Z","end":"2026-07-06T22:16:57.250065Z","steps":["trace[1984396193] 'agreement among raft nodes before linearized reading' (duration: 231.54803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.250141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.073013ms","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-07-06T22:16:57.250159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.678517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:57.250175Z","caller":"traceutil/trace.go:171","msg":"trace[1900969933] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:581; }","duration":"275.722319ms","start":"2026-07-06T22:16:56.974449Z","end":"2026-07-06T22:16:57.250171Z","steps":["trace[1900969933] 'agreement among raft nodes before linearized reading' (duration: 275.074452ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.250175Z","caller":"traceutil/trace.go:171","msg":"trace[626028941] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"232.120004ms","start":"2026-07-06T22:16:57.018045Z","end":"2026-07-06T22:16:57.250165Z","steps":["trace[626028941] 'agreement among raft nodes before linearized reading' (duration: 231.476918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.24999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.692124ms","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-07-06T22:16:57.250211Z","caller":"traceutil/trace.go:171","msg":"trace[24289958] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"245.9251ms","start":"2026-07-06T22:16:57.004282Z","end":"2026-07-06T22:16:57.250207Z","steps":["trace[24289958] 'agreement among raft nodes before linearized reading' (duration: 245.214262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.250257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.81788ms","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":"warn","ts":"2026-07-06T22:16:57.250285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.172485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:16:57.250048Z","caller":"traceutil/trace.go:171","msg":"trace[406195610] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"245.808637ms","start":"2026-07-06T22:16:57.00423Z","end":"2026-07-06T22:16:57.250039Z","steps":["trace[406195610] 'agreement among raft nodes before linearized reading' (duration: 245.267914ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.250303Z","caller":"traceutil/trace.go:171","msg":"trace[717439766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:581; }","duration":"294.893813ms","start":"2026-07-06T22:16:56.955396Z","end":"2026-07-06T22:16:57.25029Z","steps":["trace[717439766] 'agreement among raft nodes before linearized reading' (duration: 294.133483ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.250317Z","caller":"traceutil/trace.go:171","msg":"trace[1695743900] 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:581; }","duration":"218.206076ms","start":"2026-07-06T22:16:57.0321Z","end":"2026-07-06T22:16:57.250306Z","steps":["trace[1695743900] 'agreement among raft nodes before linearized reading' (duration: 217.356434ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:16:57.249989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.998765ms","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-07-06T22:16:57.250367Z","caller":"traceutil/trace.go:171","msg":"trace[2112523320] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"231.377035ms","start":"2026-07-06T22:16:57.018979Z","end":"2026-07-06T22:16:57.250356Z","steps":["trace[2112523320] 'agreement among raft nodes before linearized reading' (duration: 230.535994ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:57.999623Z","caller":"traceutil/trace.go:171","msg":"trace[80692705] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"210.167428ms","start":"2026-07-06T22:16:57.78943Z","end":"2026-07-06T22:16:57.999598Z","steps":["trace[80692705] 'process raft request' (duration: 204.887463ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:58.634556Z","caller":"traceutil/trace.go:171","msg":"trace[1224758579] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"131.074389ms","start":"2026-07-06T22:16:58.503458Z","end":"2026-07-06T22:16:58.634532Z","steps":["trace[1224758579] 'process raft request' (duration: 130.499644ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:16:59.390809Z","caller":"traceutil/trace.go:171","msg":"trace[1055295118] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"112.862209ms","start":"2026-07-06T22:16:59.277913Z","end":"2026-07-06T22:16:59.390775Z","steps":["trace[1055295118] 'process raft request' (duration: 53.805117ms)","trace[1055295118] 'compare' (duration: 58.866867ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:01.787365Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585717028,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:17:01.906972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261221593s","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-07-06T22:17:01.907001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.257613721s","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-07-06T22:17:01.907047Z","caller":"traceutil/trace.go:171","msg":"trace[789179191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"1.261321746s","start":"2026-07-06T22:17:00.645705Z","end":"2026-07-06T22:17:01.907027Z","steps":["trace[789179191] 'range keys from in-memory index tree' (duration: 1.26106094s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:01.90712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:00.645689Z","time spent":"1.261390907s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T22:17:01.907098Z","caller":"traceutil/trace.go:171","msg":"trace[852795889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.257738304s","start":"2026-07-06T22:17:00.649334Z","end":"2026-07-06T22:17:01.907072Z","steps":["trace[852795889] 'range keys from in-memory index tree' (duration: 1.257408975s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:01.907205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:00.649317Z","time spent":"1.257858568s","remote":"127.0.0.1:58482","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-07-06T22:17:01.931364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.061057884s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T22:17:01.932584Z","caller":"traceutil/trace.go:171","msg":"trace[1096458083] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.06321847s","start":"2026-07-06T22:17:00.869341Z","end":"2026-07-06T22:17:01.932559Z","steps":["trace[1096458083] 'process raft request' (duration: 1.062539622s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:01.932958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:00.869318Z","time spent":"1.063343253s","remote":"127.0.0.1:58368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:02.672189Z","caller":"traceutil/trace.go:171","msg":"trace[1465300959] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.562022178s","start":"2026-07-06T22:17:01.110139Z","end":"2026-07-06T22:17:02.672161Z","steps":["trace[1465300959] 'process raft request' (duration: 1.561804683s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.672351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.110108Z","time spent":"1.562156391s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:02.672573Z","caller":"traceutil/trace.go:171","msg":"trace[936370276] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"760.858795ms","start":"2026-07-06T22:17:01.911667Z","end":"2026-07-06T22:17:02.672526Z","steps":["trace[936370276] 'process raft request' (duration: 760.446574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.672806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.91165Z","time spent":"761.05937ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:02.672891Z","caller":"traceutil/trace.go:171","msg":"trace[67036321] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"761.056409ms","start":"2026-07-06T22:17:01.911821Z","end":"2026-07-06T22:17:02.672877Z","steps":["trace[67036321] 'process raft request' (duration: 760.754372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.672972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.911815Z","time spent":"761.117482ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:02.672794Z","caller":"traceutil/trace.go:171","msg":"trace[697596930] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"1.385503007s","start":"2026-07-06T22:17:01.287253Z","end":"2026-07-06T22:17:02.672756Z","steps":["trace[697596930] 'read index received' (duration: 644.448044ms)","trace[697596930] 'applied index is now lower than readState.Index' (duration: 741.053443ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:02.673045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.385753573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-06T22:17:02.673208Z","caller":"traceutil/trace.go:171","msg":"trace[881490437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.38598412s","start":"2026-07-06T22:17:01.287208Z","end":"2026-07-06T22:17:02.673192Z","steps":["trace[881490437] 'agreement among raft nodes before linearized reading' (duration: 1.385661381s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.673269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.287188Z","time spent":"1.386068073s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-06T22:17:02.678202Z","caller":"traceutil/trace.go:171","msg":"trace[1906511042] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"671.344677ms","start":"2026-07-06T22:17:02.006845Z","end":"2026-07-06T22:17:02.67819Z","steps":["trace[1906511042] 'process raft request' (duration: 671.285855ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.67827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:02.006824Z","time spent":"671.414989ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T22:17:02.678482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.273372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:17:02.678512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.153713532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r846p\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-06T22:17:02.678534Z","caller":"traceutil/trace.go:171","msg":"trace[90423121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:606; }","duration":"617.369396ms","start":"2026-07-06T22:17:02.061149Z","end":"2026-07-06T22:17:02.678519Z","steps":["trace[90423121] 'agreement among raft nodes before linearized reading' (duration: 617.229582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.678566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:02.06113Z","time spent":"617.429158ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:02.678559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269094976s","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-07-06T22:17:02.678566Z","caller":"traceutil/trace.go:171","msg":"trace[810860611] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r846p; range_end:; response_count:1; response_revision:606; }","duration":"1.153833325s","start":"2026-07-06T22:17:01.524719Z","end":"2026-07-06T22:17:02.678552Z","steps":["trace[810860611] 'agreement among raft nodes before linearized reading' (duration: 1.153711542s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.678603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.524684Z","time spent":"1.153914598s","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-r846p\" "} {"level":"info","ts":"2026-07-06T22:17:02.678603Z","caller":"traceutil/trace.go:171","msg":"trace[1243023158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.269183318s","start":"2026-07-06T22:17:01.409408Z","end":"2026-07-06T22:17:02.678591Z","steps":["trace[1243023158] 'agreement among raft nodes before linearized reading' (duration: 1.268955473s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.678626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:01.409384Z","time spent":"1.269237279s","remote":"127.0.0.1:58482","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-07-06T22:17:02.918195Z","caller":"traceutil/trace.go:171","msg":"trace[1883021464] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"238.680234ms","start":"2026-07-06T22:17:02.679491Z","end":"2026-07-06T22:17:02.918171Z","steps":["trace[1883021464] 'read index received' (duration: 179.922499ms)","trace[1883021464] 'applied index is now lower than readState.Index' (duration: 58.756735ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:02.918324Z","caller":"traceutil/trace.go:171","msg":"trace[2091410517] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"240.632144ms","start":"2026-07-06T22:17:02.67768Z","end":"2026-07-06T22:17:02.918313Z","steps":["trace[2091410517] 'process raft request' (duration: 181.787677ms)","trace[2091410517] 'compare' (duration: 58.518428ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:02.918617Z","caller":"traceutil/trace.go:171","msg":"trace[1885569280] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"236.527078ms","start":"2026-07-06T22:17:02.682052Z","end":"2026-07-06T22:17:02.918579Z","steps":["trace[1885569280] 'process raft request' (duration: 236.075447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.918862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.510514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-06T22:17:02.918902Z","caller":"traceutil/trace.go:171","msg":"trace[781757113] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:609; }","duration":"237.590886ms","start":"2026-07-06T22:17:02.6813Z","end":"2026-07-06T22:17:02.91889Z","steps":["trace[781757113] 'agreement among raft nodes before linearized reading' (duration: 237.436141ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:02.919042Z","caller":"traceutil/trace.go:171","msg":"trace[1709479894] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"227.27917ms","start":"2026-07-06T22:17:02.691753Z","end":"2026-07-06T22:17:02.919032Z","steps":["trace[1709479894] 'process raft request' (duration: 226.677334ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:02.919087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.579216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:02.919129Z","caller":"traceutil/trace.go:171","msg":"trace[1227703760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"239.643478ms","start":"2026-07-06T22:17:02.679471Z","end":"2026-07-06T22:17:02.919114Z","steps":["trace[1227703760] 'agreement among raft nodes before linearized reading' (duration: 239.065733ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:04.41047Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.477240183s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-06T22:17:05.230895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.02725ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:05.231551Z","caller":"traceutil/trace.go:171","msg":"trace[54939236] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"2.298369591s","start":"2026-07-06T22:17:02.933132Z","end":"2026-07-06T22:17:05.231501Z","steps":["trace[54939236] 'process raft request' (duration: 1.477643374s)","trace[54939236] 'compare' (duration: 819.318891ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:05.232379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:02.933115Z","time spent":"2.298548466s","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T22:17:05.26095Z","caller":"traceutil/trace.go:171","msg":"trace[1706716709] linearizableReadLoop","detail":"{readStateIndex:638; appliedIndex:636; }","duration":"1.201735201s","start":"2026-07-06T22:17:04.059173Z","end":"2026-07-06T22:17:05.260908Z","steps":["trace[1706716709] 'read index received' (duration: 351.624696ms)","trace[1706716709] 'applied index is now lower than readState.Index' (duration: 850.106325ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:05.261202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201999928s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:05.26126Z","caller":"traceutil/trace.go:171","msg":"trace[807939704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"1.202101659s","start":"2026-07-06T22:17:04.059138Z","end":"2026-07-06T22:17:05.261239Z","steps":["trace[807939704] 'agreement among raft nodes before linearized reading' (duration: 1.201943886s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.26131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:04.059123Z","time spent":"1.202173453s","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:05.264178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.096288ms","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-07-06T22:17:05.264233Z","caller":"traceutil/trace.go:171","msg":"trace[1184256866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"340.17157ms","start":"2026-07-06T22:17:04.924049Z","end":"2026-07-06T22:17:05.264221Z","steps":["trace[1184256866] 'agreement among raft nodes before linearized reading' (duration: 339.984594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.264258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:04.924042Z","time spent":"340.209111ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T22:17:05.264283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.494235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:17:05.264342Z","caller":"traceutil/trace.go:171","msg":"trace[529948543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"587.615309ms","start":"2026-07-06T22:17:04.676713Z","end":"2026-07-06T22:17:05.264329Z","steps":["trace[529948543] 'agreement among raft nodes before linearized reading' (duration: 587.500157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.264372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:04.676685Z","time spent":"587.67979ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:17:05.264643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.803202ms","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-07-06T22:17:05.264674Z","caller":"traceutil/trace.go:171","msg":"trace[629520035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"341.872494ms","start":"2026-07-06T22:17:04.922791Z","end":"2026-07-06T22:17:05.264664Z","steps":["trace[629520035] 'agreement among raft nodes before linearized reading' (duration: 341.745721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.264704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:04.922773Z","time spent":"341.923344ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T22:17:05.264869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.381633ms","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-07-06T22:17:05.264902Z","caller":"traceutil/trace.go:171","msg":"trace[1813994870] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"549.458465ms","start":"2026-07-06T22:17:04.715434Z","end":"2026-07-06T22:17:05.264892Z","steps":["trace[1813994870] 'agreement among raft nodes before linearized reading' (duration: 549.396224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:05.264936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:04.715409Z","time spent":"549.519096ms","remote":"127.0.0.1:58482","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-07-06T22:17:06.832119Z","caller":"traceutil/trace.go:171","msg":"trace[1052345867] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"158.531006ms","start":"2026-07-06T22:17:06.673565Z","end":"2026-07-06T22:17:06.832096Z","steps":["trace[1052345867] 'process raft request' (duration: 158.366572ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:08.729458Z","caller":"traceutil/trace.go:171","msg":"trace[1039274075] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"254.46835ms","start":"2026-07-06T22:17:08.47497Z","end":"2026-07-06T22:17:08.729438Z","steps":["trace[1039274075] 'process raft request' (duration: 254.190303ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:08.729671Z","caller":"traceutil/trace.go:171","msg":"trace[1995906285] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"244.826691ms","start":"2026-07-06T22:17:08.484538Z","end":"2026-07-06T22:17:08.729365Z","steps":["trace[1995906285] 'read index received' (duration: 244.555104ms)","trace[1995906285] 'applied index is now lower than readState.Index' (duration: 269.927µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:08.729796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.231032ms","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-07-06T22:17:08.729952Z","caller":"traceutil/trace.go:171","msg":"trace[2023663389] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"245.432068ms","start":"2026-07-06T22:17:08.484504Z","end":"2026-07-06T22:17:08.729936Z","steps":["trace[2023663389] 'agreement among raft nodes before linearized reading' (duration: 245.057669ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:08.759601Z","caller":"traceutil/trace.go:171","msg":"trace[346551952] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"170.630828ms","start":"2026-07-06T22:17:08.588927Z","end":"2026-07-06T22:17:08.759558Z","steps":["trace[346551952] 'process raft request' (duration: 170.30577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:08.759682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.619229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r846p\" ","response":"range_response_count:1 size:17790"} {"level":"warn","ts":"2026-07-06T22:17:08.759712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.533738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.216\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-06T22:17:08.759776Z","caller":"traceutil/trace.go:171","msg":"trace[1624540957] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r846p; range_end:; response_count:1; response_revision:671; }","duration":"172.763574ms","start":"2026-07-06T22:17:08.586991Z","end":"2026-07-06T22:17:08.759754Z","steps":["trace[1624540957] 'agreement among raft nodes before linearized reading' (duration: 172.461166ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:08.75978Z","caller":"traceutil/trace.go:171","msg":"trace[10271331] range","detail":"{range_begin:/registry/masterleases/199.204.45.216; range_end:; response_count:1; response_revision:671; }","duration":"172.657311ms","start":"2026-07-06T22:17:08.587108Z","end":"2026-07-06T22:17:08.759765Z","steps":["trace[10271331] 'agreement among raft nodes before linearized reading' (duration: 172.389424ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:09.577761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.161435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:09.578118Z","caller":"traceutil/trace.go:171","msg":"trace[42146321] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"185.973295ms","start":"2026-07-06T22:17:09.392115Z","end":"2026-07-06T22:17:09.578089Z","steps":["trace[42146321] 'process raft request' (duration: 69.385648ms)","trace[42146321] 'compare' (duration: 116.020802ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:09.578284Z","caller":"traceutil/trace.go:171","msg":"trace[75097713] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:709; }","duration":"106.165878ms","start":"2026-07-06T22:17:09.472097Z","end":"2026-07-06T22:17:09.578263Z","steps":["trace[75097713] 'read index received' (duration: 59.281329ms)","trace[75097713] 'applied index is now lower than readState.Index' (duration: 46.882509ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:09.578287Z","caller":"traceutil/trace.go:171","msg":"trace[651481410] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"184.352564ms","start":"2026-07-06T22:17:09.393901Z","end":"2026-07-06T22:17:09.578254Z","steps":["trace[651481410] 'process raft request' (duration: 184.051235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:09.57852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.419054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-06T22:17:09.578568Z","caller":"traceutil/trace.go:171","msg":"trace[1887201869] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:682; }","duration":"106.466585ms","start":"2026-07-06T22:17:09.472084Z","end":"2026-07-06T22:17:09.578551Z","steps":["trace[1887201869] 'agreement among raft nodes before linearized reading' (duration: 106.27065ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:10.316235Z","caller":"traceutil/trace.go:171","msg":"trace[1768901842] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"233.620973ms","start":"2026-07-06T22:17:10.082588Z","end":"2026-07-06T22:17:10.316209Z","steps":["trace[1768901842] 'process raft request' (duration: 211.313777ms)","trace[1768901842] 'compare' (duration: 22.098931ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:10.944808Z","caller":"traceutil/trace.go:171","msg":"trace[847817731] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"512.326458ms","start":"2026-07-06T22:17:10.432458Z","end":"2026-07-06T22:17:10.944784Z","steps":["trace[847817731] 'process raft request' (duration: 512.095351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:10.944952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.432425Z","time spent":"512.45173ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:11.215967Z","caller":"traceutil/trace.go:171","msg":"trace[1367622761] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"747.209972ms","start":"2026-07-06T22:17:10.468738Z","end":"2026-07-06T22:17:11.215948Z","steps":["trace[1367622761] 'process raft request' (duration: 746.428942ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.215825Z","caller":"traceutil/trace.go:171","msg":"trace[1214625264] linearizableReadLoop","detail":"{readStateIndex:720; appliedIndex:718; }","duration":"740.307546ms","start":"2026-07-06T22:17:10.475454Z","end":"2026-07-06T22:17:11.215761Z","steps":["trace[1214625264] 'read index received' (duration: 469.120684ms)","trace[1214625264] 'applied index is now lower than readState.Index' (duration: 271.185452ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:11.216596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.132257ms","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-07-06T22:17:11.216631Z","caller":"traceutil/trace.go:171","msg":"trace[1879807060] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"741.197188ms","start":"2026-07-06T22:17:10.475423Z","end":"2026-07-06T22:17:11.21662Z","steps":["trace[1879807060] 'agreement among raft nodes before linearized reading' (duration: 741.037044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.216655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.475399Z","time spent":"741.249129ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-07-06T22:17:11.216605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.468719Z","time spent":"747.664105ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:11.216846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.558798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-06T22:17:11.216967Z","caller":"traceutil/trace.go:171","msg":"trace[532072110] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"345.729362ms","start":"2026-07-06T22:17:10.871209Z","end":"2026-07-06T22:17:11.216938Z","steps":["trace[532072110] 'agreement among raft nodes before linearized reading' (duration: 345.380824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.217054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.871189Z","time spent":"345.846446ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188274,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-06T22:17:11.217042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.678728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"warn","ts":"2026-07-06T22:17:11.217082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.026465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188486"} {"level":"info","ts":"2026-07-06T22:17:11.217128Z","caller":"traceutil/trace.go:171","msg":"trace[957271829] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"146.103397ms","start":"2026-07-06T22:17:11.071016Z","end":"2026-07-06T22:17:11.21712Z","steps":["trace[957271829] 'agreement among raft nodes before linearized reading' (duration: 145.997394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.216858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.868074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-r846p\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-07-06T22:17:11.218273Z","caller":"traceutil/trace.go:171","msg":"trace[1529366378] range","detail":"{range_begin:/registry/pods/kube-system/cilium-r846p; range_end:; response_count:1; response_revision:691; }","duration":"608.313312ms","start":"2026-07-06T22:17:10.609919Z","end":"2026-07-06T22:17:11.218232Z","steps":["trace[1529366378] 'agreement among raft nodes before linearized reading' (duration: 606.631929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.218339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.609902Z","time spent":"608.416155ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18359,"request content":"key:\"/registry/pods/kube-system/cilium-r846p\" "} {"level":"warn","ts":"2026-07-06T22:17:11.217225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.170784ms","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-07-06T22:17:11.218743Z","caller":"traceutil/trace.go:171","msg":"trace[523170168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:691; }","duration":"449.733344ms","start":"2026-07-06T22:17:10.768993Z","end":"2026-07-06T22:17:11.218726Z","steps":["trace[523170168] 'agreement among raft nodes before linearized reading' (duration: 447.884086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.218784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.768973Z","time spent":"449.798895ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-06T22:17:11.218018Z","caller":"traceutil/trace.go:171","msg":"trace[811437] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:691; }","duration":"545.827381ms","start":"2026-07-06T22:17:10.671313Z","end":"2026-07-06T22:17:11.217141Z","steps":["trace[811437] 'agreement among raft nodes before linearized reading' (duration: 545.604085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.219158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:10.671295Z","time spent":"547.843824ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13251,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-07-06T22:17:11.218188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.111689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:11.219625Z","caller":"traceutil/trace.go:171","msg":"trace[1586875098] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:691; }","duration":"157.558621ms","start":"2026-07-06T22:17:11.06205Z","end":"2026-07-06T22:17:11.219608Z","steps":["trace[1586875098] 'agreement among raft nodes before linearized reading' (duration: 155.119769ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.427032Z","caller":"traceutil/trace.go:171","msg":"trace[986155375] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"181.887698ms","start":"2026-07-06T22:17:11.245126Z","end":"2026-07-06T22:17:11.427013Z","steps":["trace[986155375] 'process raft request' (duration: 181.747815ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.672439Z","caller":"traceutil/trace.go:171","msg":"trace[1154102491] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"276.469448ms","start":"2026-07-06T22:17:11.395943Z","end":"2026-07-06T22:17:11.672413Z","steps":["trace[1154102491] 'read index received' (duration: 30.940538ms)","trace[1154102491] 'applied index is now lower than readState.Index' (duration: 245.52799ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:11.672554Z","caller":"traceutil/trace.go:171","msg":"trace[1161961550] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"367.072453ms","start":"2026-07-06T22:17:11.305471Z","end":"2026-07-06T22:17:11.672543Z","steps":["trace[1161961550] 'process raft request' (duration: 363.785779ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.672672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:11.305418Z","time spent":"367.168725ms","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:17:11.672947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.995351ms","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-07-06T22:17:11.673036Z","caller":"traceutil/trace.go:171","msg":"trace[466760775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:694; }","duration":"277.168545ms","start":"2026-07-06T22:17:11.395849Z","end":"2026-07-06T22:17:11.673017Z","steps":["trace[466760775] 'agreement among raft nodes before linearized reading' (duration: 276.94832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.680077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.527202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-07-06T22:17:11.680137Z","caller":"traceutil/trace.go:171","msg":"trace[1832087264] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:695; }","duration":"273.615503ms","start":"2026-07-06T22:17:11.406508Z","end":"2026-07-06T22:17:11.680124Z","steps":["trace[1832087264] 'agreement among raft nodes before linearized reading' (duration: 273.346577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:11.680391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.62352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:17:11.680493Z","caller":"traceutil/trace.go:171","msg":"trace[1677363926] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:695; }","duration":"262.758025ms","start":"2026-07-06T22:17:11.417717Z","end":"2026-07-06T22:17:11.680475Z","steps":["trace[1677363926] 'agreement among raft nodes before linearized reading' (duration: 262.511948ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.807236Z","caller":"traceutil/trace.go:171","msg":"trace[1699217163] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"113.969328ms","start":"2026-07-06T22:17:11.693238Z","end":"2026-07-06T22:17:11.807207Z","steps":["trace[1699217163] 'process raft request' (duration: 113.883525ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.807386Z","caller":"traceutil/trace.go:171","msg":"trace[108313787] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"122.362215ms","start":"2026-07-06T22:17:11.685003Z","end":"2026-07-06T22:17:11.807365Z","steps":["trace[108313787] 'process raft request' (duration: 122.073027ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:11.807588Z","caller":"traceutil/trace.go:171","msg":"trace[1249761766] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"122.646662ms","start":"2026-07-06T22:17:11.684876Z","end":"2026-07-06T22:17:11.807523Z","steps":["trace[1249761766] 'process raft request' (duration: 118.817943ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:12.131434Z","caller":"traceutil/trace.go:171","msg":"trace[359745180] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"112.298526ms","start":"2026-07-06T22:17:12.019115Z","end":"2026-07-06T22:17:12.131414Z","steps":["trace[359745180] 'process raft request' (duration: 69.864582ms)","trace[359745180] 'compare' (duration: 42.232248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:12.13201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.715281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:12.132064Z","caller":"traceutil/trace.go:171","msg":"trace[2136655777] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:703; }","duration":"101.824575ms","start":"2026-07-06T22:17:12.030227Z","end":"2026-07-06T22:17:12.132051Z","steps":["trace[2136655777] 'agreement among raft nodes before linearized reading' (duration: 101.712552ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:12.134485Z","caller":"traceutil/trace.go:171","msg":"trace[159365134] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:731; }","duration":"101.073556ms","start":"2026-07-06T22:17:12.030259Z","end":"2026-07-06T22:17:12.131333Z","steps":["trace[159365134] 'read index received' (duration: 58.680743ms)","trace[159365134] 'applied index is now lower than readState.Index' (duration: 42.391673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:12.134666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.33963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:12.134719Z","caller":"traceutil/trace.go:171","msg":"trace[432237826] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:703; }","duration":"104.410571ms","start":"2026-07-06T22:17:12.030297Z","end":"2026-07-06T22:17:12.134707Z","steps":["trace[432237826] 'agreement among raft nodes before linearized reading' (duration: 104.328819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:12.135142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.048143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:12.135217Z","caller":"traceutil/trace.go:171","msg":"trace[702890510] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:703; }","duration":"104.144675ms","start":"2026-07-06T22:17:12.031054Z","end":"2026-07-06T22:17:12.135199Z","steps":["trace[702890510] 'agreement among raft nodes before linearized reading' (duration: 104.031862ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:13.0659Z","caller":"traceutil/trace.go:171","msg":"trace[1372228340] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"134.2252ms","start":"2026-07-06T22:17:12.931659Z","end":"2026-07-06T22:17:13.065884Z","steps":["trace[1372228340] 'process raft request' (duration: 131.211642ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:14.668145Z","caller":"traceutil/trace.go:171","msg":"trace[1642729119] transaction","detail":"{read_only:false; number_of_response:0; response_revision:715; }","duration":"187.842262ms","start":"2026-07-06T22:17:14.480271Z","end":"2026-07-06T22:17:14.668113Z","steps":["trace[1642729119] 'process raft request' (duration: 151.826884ms)","trace[1642729119] 'compare' (duration: 35.837114ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:24.137627Z","caller":"traceutil/trace.go:171","msg":"trace[651518226] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"651.70348ms","start":"2026-07-06T22:17:23.485897Z","end":"2026-07-06T22:17:24.1376Z","steps":["trace[651518226] 'process raft request' (duration: 650.771407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.138109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.485877Z","time spent":"651.817224ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:24.403454Z","caller":"traceutil/trace.go:171","msg":"trace[2125729498] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"714.88565ms","start":"2026-07-06T22:17:23.688544Z","end":"2026-07-06T22:17:24.40343Z","steps":["trace[2125729498] 'process raft request' (duration: 714.751496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.403631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.688499Z","time spent":"715.056894ms","remote":"127.0.0.1:58466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:17:24.404506Z","caller":"traceutil/trace.go:171","msg":"trace[1872419725] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:812; }","duration":"667.409565ms","start":"2026-07-06T22:17:23.737085Z","end":"2026-07-06T22:17:24.404495Z","steps":["trace[1872419725] 'read index received' (duration: 399.283392ms)","trace[1872419725] 'applied index is now lower than readState.Index' (duration: 268.125503ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:24.404697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.523998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:17:24.404728Z","caller":"traceutil/trace.go:171","msg":"trace[1282218082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:781; }","duration":"345.582449ms","start":"2026-07-06T22:17:24.059138Z","end":"2026-07-06T22:17:24.40472Z","steps":["trace[1282218082] 'agreement among raft nodes before linearized reading' (duration: 345.536428ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.404747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:24.059122Z","time spent":"345.620461ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:17:24.404759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.257122ms","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-07-06T22:17:24.404791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.546441ms","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-07-06T22:17:24.404812Z","caller":"traceutil/trace.go:171","msg":"trace[2087824762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:781; }","duration":"629.363165ms","start":"2026-07-06T22:17:23.775436Z","end":"2026-07-06T22:17:24.404799Z","steps":["trace[2087824762] 'agreement among raft nodes before linearized reading' (duration: 629.19383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.404805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.805411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:17:24.40485Z","caller":"traceutil/trace.go:171","msg":"trace[1144604451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:781; }","duration":"496.652254ms","start":"2026-07-06T22:17:23.908185Z","end":"2026-07-06T22:17:24.404837Z","steps":["trace[1144604451] 'agreement among raft nodes before linearized reading' (duration: 496.457688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.404853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.775415Z","time spent":"629.432616ms","remote":"127.0.0.1:58482","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-07-06T22:17:24.404874Z","caller":"traceutil/trace.go:171","msg":"trace[282527437] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:781; }","duration":"500.929094ms","start":"2026-07-06T22:17:23.903927Z","end":"2026-07-06T22:17:24.404856Z","steps":["trace[282527437] 'agreement among raft nodes before linearized reading' (duration: 500.722838ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.404916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.903852Z","time spent":"501.055368ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:17:24.404991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.908172Z","time spent":"496.807527ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T22:17:24.405529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.454233ms","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-07-06T22:17:24.40556Z","caller":"traceutil/trace.go:171","msg":"trace[813432955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:781; }","duration":"668.486853ms","start":"2026-07-06T22:17:23.737065Z","end":"2026-07-06T22:17:24.405552Z","steps":["trace[813432955] 'agreement among raft nodes before linearized reading' (duration: 667.505417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:24.405582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:17:23.737005Z","time spent":"668.571995ms","remote":"127.0.0.1:58482","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-07-06T22:17:28.456816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.619964ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:17:28.456971Z","caller":"traceutil/trace.go:171","msg":"trace[2023140435] linearizableReadLoop","detail":"{readStateIndex:861; appliedIndex:860; }","duration":"102.827761ms","start":"2026-07-06T22:17:28.354125Z","end":"2026-07-06T22:17:28.456953Z","steps":["trace[2023140435] 'read index received' (duration: 55.191µs)","trace[2023140435] 'applied index is now lower than readState.Index' (duration: 102.77108ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:28.457243Z","caller":"traceutil/trace.go:171","msg":"trace[334189266] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"211.00453ms","start":"2026-07-06T22:17:28.246006Z","end":"2026-07-06T22:17:28.45701Z","steps":["trace[334189266] 'process raft request' (duration: 39.114578ms)","trace[334189266] 'compare' (duration: 171.248796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:17:28.458114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.988171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050276"} {"level":"info","ts":"2026-07-06T22:17:28.458146Z","caller":"traceutil/trace.go:171","msg":"trace[1323681504] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:827; }","duration":"104.066983ms","start":"2026-07-06T22:17:28.35407Z","end":"2026-07-06T22:17:28.458137Z","steps":["trace[1323681504] 'agreement among raft nodes before linearized reading' (duration: 103.082028ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.63761Z","caller":"traceutil/trace.go:171","msg":"trace[2094931657] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"175.129475ms","start":"2026-07-06T22:17:28.462455Z","end":"2026-07-06T22:17:28.637584Z","steps":["trace[2094931657] 'process raft request' (duration: 174.860537ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:28.637696Z","caller":"traceutil/trace.go:171","msg":"trace[66474376] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"175.297359ms","start":"2026-07-06T22:17:28.462365Z","end":"2026-07-06T22:17:28.637662Z","steps":["trace[66474376] 'process raft request' (duration: 96.921199ms)","trace[66474376] 'compare' (duration: 77.878207ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:28.637893Z","caller":"traceutil/trace.go:171","msg":"trace[1991187714] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"174.104838ms","start":"2026-07-06T22:17:28.463763Z","end":"2026-07-06T22:17:28.637868Z","steps":["trace[1991187714] 'process raft request' (duration: 173.623996ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:31.658552Z","caller":"traceutil/trace.go:171","msg":"trace[454592697] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"178.76787ms","start":"2026-07-06T22:17:31.479751Z","end":"2026-07-06T22:17:31.658519Z","steps":["trace[454592697] 'process raft request' (duration: 176.624345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:17:31.65882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.871451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-mxrpk\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-07-06T22:17:31.658877Z","caller":"traceutil/trace.go:171","msg":"trace[728877863] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-mxrpk; range_end:; response_count:1; response_revision:855; }","duration":"178.984994ms","start":"2026-07-06T22:17:31.479878Z","end":"2026-07-06T22:17:31.658863Z","steps":["trace[728877863] 'agreement among raft nodes before linearized reading' (duration: 178.718718ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:31.658494Z","caller":"traceutil/trace.go:171","msg":"trace[1905104977] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:889; }","duration":"178.541184ms","start":"2026-07-06T22:17:31.47993Z","end":"2026-07-06T22:17:31.658472Z","steps":["trace[1905104977] 'read index received' (duration: 176.45545ms)","trace[1905104977] 'applied index is now lower than readState.Index' (duration: 2.084914ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:32.788374Z","caller":"traceutil/trace.go:171","msg":"trace[1847403690] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"129.412027ms","start":"2026-07-06T22:17:32.658907Z","end":"2026-07-06T22:17:32.788319Z","steps":["trace[1847403690] 'process raft request' (duration: 128.049211ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:33.008442Z","caller":"traceutil/trace.go:171","msg":"trace[990559712] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"212.742214ms","start":"2026-07-06T22:17:32.79567Z","end":"2026-07-06T22:17:33.008412Z","steps":["trace[990559712] 'process raft request' (duration: 121.570304ms)","trace[990559712] 'compare' (duration: 91.052847ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:39.889585Z","caller":"traceutil/trace.go:171","msg":"trace[552201288] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"125.759917ms","start":"2026-07-06T22:17:39.763796Z","end":"2026-07-06T22:17:39.889556Z","steps":["trace[552201288] 'process raft request' (duration: 125.588393ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:40.038076Z","caller":"traceutil/trace.go:171","msg":"trace[432806212] transaction","detail":"{read_only:false; number_of_response:1; response_revision:927; }","duration":"146.460167ms","start":"2026-07-06T22:17:39.891588Z","end":"2026-07-06T22:17:40.038048Z","steps":["trace[432806212] 'process raft request' (duration: 146.325773ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:45.12301Z","caller":"traceutil/trace.go:171","msg":"trace[1852254219] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"138.993913ms","start":"2026-07-06T22:17:44.983991Z","end":"2026-07-06T22:17:45.122985Z","steps":["trace[1852254219] 'process raft request' (duration: 49.345519ms)","trace[1852254219] 'compare' (duration: 89.268414ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:17:52.404502Z","caller":"traceutil/trace.go:171","msg":"trace[1121247959] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"234.595751ms","start":"2026-07-06T22:17:52.169877Z","end":"2026-07-06T22:17:52.404472Z","steps":["trace[1121247959] 'process raft request' (duration: 234.152089ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:53.745661Z","caller":"traceutil/trace.go:171","msg":"trace[654784616] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"241.639536ms","start":"2026-07-06T22:17:53.504Z","end":"2026-07-06T22:17:53.74564Z","steps":["trace[654784616] 'process raft request' (duration: 241.510222ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:17:59.886898Z","caller":"traceutil/trace.go:171","msg":"trace[296212595] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"120.47655ms","start":"2026-07-06T22:17:59.766392Z","end":"2026-07-06T22:17:59.886869Z","steps":["trace[296212595] 'process raft request' (duration: 120.316355ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:16.801297Z","caller":"traceutil/trace.go:171","msg":"trace[85206447] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"131.906138ms","start":"2026-07-06T22:18:16.669371Z","end":"2026-07-06T22:18:16.801277Z","steps":["trace[85206447] 'read index received' (duration: 131.772845ms)","trace[85206447] 'applied index is now lower than readState.Index' (duration: 132.593µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:18:16.801352Z","caller":"traceutil/trace.go:171","msg":"trace[991134340] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"200.425278ms","start":"2026-07-06T22:18:16.600885Z","end":"2026-07-06T22:18:16.801311Z","steps":["trace[991134340] 'process raft request' (duration: 200.232162ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:16.801454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.065131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:18:16.801485Z","caller":"traceutil/trace.go:171","msg":"trace[965655015] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1051; }","duration":"132.170314ms","start":"2026-07-06T22:18:16.669304Z","end":"2026-07-06T22:18:16.801474Z","steps":["trace[965655015] 'agreement among raft nodes before linearized reading' (duration: 132.083662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:31.711608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.05418ms","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-07-06T22:18:31.71169Z","caller":"traceutil/trace.go:171","msg":"trace[1276727724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"383.180163ms","start":"2026-07-06T22:18:31.328492Z","end":"2026-07-06T22:18:31.711672Z","steps":["trace[1276727724] 'range keys from in-memory index tree' (duration: 382.895146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:31.711728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.328473Z","time spent":"383.245055ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T22:18:31.71175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.498646ms","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-07-06T22:18:31.711797Z","caller":"traceutil/trace.go:171","msg":"trace[968563751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"175.574259ms","start":"2026-07-06T22:18:31.536212Z","end":"2026-07-06T22:18:31.711786Z","steps":["trace[968563751] 'range keys from in-memory index tree' (duration: 175.375563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:31.711936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.201609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-06T22:18:31.711968Z","caller":"traceutil/trace.go:171","msg":"trace[1980399458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"550.257729ms","start":"2026-07-06T22:18:31.161702Z","end":"2026-07-06T22:18:31.71196Z","steps":["trace[1980399458] 'range keys from in-memory index tree' (duration: 550.059175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:31.712196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.161688Z","time spent":"550.501396ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:18:31.712234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.54106ms","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-07-06T22:18:31.712319Z","caller":"traceutil/trace.go:171","msg":"trace[939888050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"774.651183ms","start":"2026-07-06T22:18:30.937634Z","end":"2026-07-06T22:18:31.712285Z","steps":["trace[939888050] 'range keys from in-memory index tree' (duration: 774.191602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:31.712356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:30.937604Z","time spent":"774.744246ms","remote":"127.0.0.1:58482","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-07-06T22:18:32.153229Z","caller":"traceutil/trace.go:171","msg":"trace[1755664949] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"437.608756ms","start":"2026-07-06T22:18:31.715577Z","end":"2026-07-06T22:18:32.153185Z","steps":["trace[1755664949] 'process raft request' (duration: 437.37794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:32.153429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.715557Z","time spent":"437.792091ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:18:32.153869Z","caller":"traceutil/trace.go:171","msg":"trace[1226055174] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"438.072938ms","start":"2026-07-06T22:18:31.715589Z","end":"2026-07-06T22:18:32.153662Z","steps":["trace[1226055174] 'process raft request' (duration: 437.524974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:32.153955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.715577Z","time spent":"438.340525ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:18:32.3103Z","caller":"traceutil/trace.go:171","msg":"trace[1345456251] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"593.736046ms","start":"2026-07-06T22:18:31.716534Z","end":"2026-07-06T22:18:32.31027Z","steps":["trace[1345456251] 'process raft request' (duration: 593.645394ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:32.310466Z","caller":"traceutil/trace.go:171","msg":"trace[1620611023] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1146; }","duration":"250.711911ms","start":"2026-07-06T22:18:32.059729Z","end":"2026-07-06T22:18:32.310441Z","steps":["trace[1620611023] 'read index received' (duration: 93.171405ms)","trace[1620611023] 'applied index is now lower than readState.Index' (duration: 157.539316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:18:32.310493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.716518Z","time spent":"593.88432ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:18:32.310533Z","caller":"traceutil/trace.go:171","msg":"trace[638054518] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"594.800864ms","start":"2026-07-06T22:18:31.715702Z","end":"2026-07-06T22:18:32.310503Z","steps":["trace[638054518] 'process raft request' (duration: 552.468097ms)","trace[638054518] 'compare' (duration: 41.844694ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:18:32.310635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.902667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:18:32.310667Z","caller":"traceutil/trace.go:171","msg":"trace[1622698834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"250.98804ms","start":"2026-07-06T22:18:32.059668Z","end":"2026-07-06T22:18:32.310656Z","steps":["trace[1622698834] 'agreement among raft nodes before linearized reading' (duration: 250.858866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:32.310686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:18:31.715693Z","time spent":"594.898957ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:18:32.310737Z","caller":"traceutil/trace.go:171","msg":"trace[2135945574] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"285.85606ms","start":"2026-07-06T22:18:32.024845Z","end":"2026-07-06T22:18:32.310701Z","steps":["trace[2135945574] 'process raft request' (duration: 285.376717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:18:44.159408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.762253ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-06T22:18:45.382354Z","caller":"traceutil/trace.go:171","msg":"trace[1994367654] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"151.614453ms","start":"2026-07-06T22:18:45.230719Z","end":"2026-07-06T22:18:45.382334Z","steps":["trace[1994367654] 'read index received' (duration: 151.448109ms)","trace[1994367654] 'applied index is now lower than readState.Index' (duration: 165.434µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:18:45.382474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.739136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:18:45.382499Z","caller":"traceutil/trace.go:171","msg":"trace[298978822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1144; }","duration":"151.813398ms","start":"2026-07-06T22:18:45.230677Z","end":"2026-07-06T22:18:45.382491Z","steps":["trace[298978822] 'agreement among raft nodes before linearized reading' (duration: 151.733876ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:45.382473Z","caller":"traceutil/trace.go:171","msg":"trace[1953747623] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"208.60372ms","start":"2026-07-06T22:18:45.173838Z","end":"2026-07-06T22:18:45.382442Z","steps":["trace[1953747623] 'process raft request' (duration: 208.351384ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:58.541759Z","caller":"traceutil/trace.go:171","msg":"trace[1340832420] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"218.49889ms","start":"2026-07-06T22:18:58.323227Z","end":"2026-07-06T22:18:58.541726Z","steps":["trace[1340832420] 'process raft request' (duration: 218.264975ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:18:58.544314Z","caller":"traceutil/trace.go:171","msg":"trace[869511020] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"219.737933ms","start":"2026-07-06T22:18:58.324554Z","end":"2026-07-06T22:18:58.544292Z","steps":["trace[869511020] 'process raft request' (duration: 219.539247ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:03.855202Z","caller":"traceutil/trace.go:171","msg":"trace[791440219] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"736.896461ms","start":"2026-07-06T22:19:03.118273Z","end":"2026-07-06T22:19:03.85517Z","steps":["trace[791440219] 'process raft request' (duration: 736.733527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:03.855367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:03.118242Z","time spent":"737.053406ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:19:04.757855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.366883ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-06T22:19:04.758162Z","caller":"traceutil/trace.go:171","msg":"trace[1182644159] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"181.152659ms","start":"2026-07-06T22:19:04.576983Z","end":"2026-07-06T22:19:04.758135Z","steps":["trace[1182644159] 'process raft request' (duration: 180.994265ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:04.758117Z","caller":"traceutil/trace.go:171","msg":"trace[1177466355] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"696.54193ms","start":"2026-07-06T22:19:04.061541Z","end":"2026-07-06T22:19:04.758082Z","steps":["trace[1177466355] 'read index received' (duration: 202.972982ms)","trace[1177466355] 'applied index is now lower than readState.Index' (duration: 493.565498ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:04.75834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.789638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:04.75844Z","caller":"traceutil/trace.go:171","msg":"trace[1428392892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"696.939302ms","start":"2026-07-06T22:19:04.06148Z","end":"2026-07-06T22:19:04.758419Z","steps":["trace[1428392892] 'agreement among raft nodes before linearized reading' (duration: 696.692824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:04.758511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:04.06146Z","time spent":"697.039014ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:19:04.758537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.262477ms","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-07-06T22:19:04.758587Z","caller":"traceutil/trace.go:171","msg":"trace[866130773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1204; }","duration":"161.326329ms","start":"2026-07-06T22:19:04.597242Z","end":"2026-07-06T22:19:04.758569Z","steps":["trace[866130773] 'agreement among raft nodes before linearized reading' (duration: 161.240997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:04.75846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.944728ms","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-07-06T22:19:04.758652Z","caller":"traceutil/trace.go:171","msg":"trace[1373521465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1204; }","duration":"180.185864ms","start":"2026-07-06T22:19:04.578447Z","end":"2026-07-06T22:19:04.758633Z","steps":["trace[1373521465] 'agreement among raft nodes before linearized reading' (duration: 179.845564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:04.758767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.616934ms","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-07-06T22:19:04.7588Z","caller":"traceutil/trace.go:171","msg":"trace[726856184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1204; }","duration":"167.662165ms","start":"2026-07-06T22:19:04.59113Z","end":"2026-07-06T22:19:04.758792Z","steps":["trace[726856184] 'agreement among raft nodes before linearized reading' (duration: 167.575713ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:06.307048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.585597ms","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-07-06T22:19:06.307172Z","caller":"traceutil/trace.go:171","msg":"trace[860822987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1209; }","duration":"222.725931ms","start":"2026-07-06T22:19:06.084428Z","end":"2026-07-06T22:19:06.307154Z","steps":["trace[860822987] 'range keys from in-memory index tree' (duration: 222.567027ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:06.307998Z","caller":"traceutil/trace.go:171","msg":"trace[568821333] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"287.318158ms","start":"2026-07-06T22:19:06.020652Z","end":"2026-07-06T22:19:06.30797Z","steps":["trace[568821333] 'process raft request' (duration: 267.563889ms)","trace[568821333] 'compare' (duration: 18.509566ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:06.308107Z","caller":"traceutil/trace.go:171","msg":"trace[1380233723] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1264; }","duration":"246.709463ms","start":"2026-07-06T22:19:06.061074Z","end":"2026-07-06T22:19:06.307783Z","steps":["trace[1380233723] 'read index received' (duration: 227.060696ms)","trace[1380233723] 'applied index is now lower than readState.Index' (duration: 19.646586ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:06.308129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.03663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:06.30821Z","caller":"traceutil/trace.go:171","msg":"trace[1924442567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"247.190285ms","start":"2026-07-06T22:19:06.061004Z","end":"2026-07-06T22:19:06.308194Z","steps":["trace[1924442567] 'agreement among raft nodes before linearized reading' (duration: 247.037221ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:07.213831Z","caller":"traceutil/trace.go:171","msg":"trace[1829308069] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"410.351781ms","start":"2026-07-06T22:19:06.803456Z","end":"2026-07-06T22:19:07.213808Z","steps":["trace[1829308069] 'read index received' (duration: 410.231918ms)","trace[1829308069] 'applied index is now lower than readState.Index' (duration: 116.213µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:07.213959Z","caller":"traceutil/trace.go:171","msg":"trace[1768668187] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"449.842798ms","start":"2026-07-06T22:19:06.764105Z","end":"2026-07-06T22:19:07.213948Z","steps":["trace[1768668187] 'process raft request' (duration: 449.552371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.214071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:06.76408Z","time spent":"449.90758ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:19:07.214373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.910756ms","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-07-06T22:19:07.214473Z","caller":"traceutil/trace.go:171","msg":"trace[1627960036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"411.05032ms","start":"2026-07-06T22:19:06.803396Z","end":"2026-07-06T22:19:07.214447Z","steps":["trace[1627960036] 'agreement among raft nodes before linearized reading' (duration: 410.808953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.214526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:06.803349Z","time spent":"411.164622ms","remote":"127.0.0.1:58482","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-07-06T22:19:07.214935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.256365ms","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-07-06T22:19:07.214957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.358909ms","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-07-06T22:19:07.214993Z","caller":"traceutil/trace.go:171","msg":"trace[994587228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"368.325708ms","start":"2026-07-06T22:19:06.846653Z","end":"2026-07-06T22:19:07.214979Z","steps":["trace[994587228] 'agreement among raft nodes before linearized reading' (duration: 368.187713ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:07.215003Z","caller":"traceutil/trace.go:171","msg":"trace[259010494] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"368.437411ms","start":"2026-07-06T22:19:06.846553Z","end":"2026-07-06T22:19:07.214991Z","steps":["trace[259010494] 'agreement among raft nodes before linearized reading' (duration: 368.310447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.215043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:06.846536Z","time spent":"368.498652ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T22:19:07.215041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:06.846649Z","time spent":"368.38153ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:19:07.215097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.752524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:07.21514Z","caller":"traceutil/trace.go:171","msg":"trace[1036011595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"152.820415ms","start":"2026-07-06T22:19:07.062308Z","end":"2026-07-06T22:19:07.215128Z","steps":["trace[1036011595] 'agreement among raft nodes before linearized reading' (duration: 152.756534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.215599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.059534ms","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-07-06T22:19:07.215654Z","caller":"traceutil/trace.go:171","msg":"trace[1220437864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"383.152067ms","start":"2026-07-06T22:19:06.832488Z","end":"2026-07-06T22:19:07.21564Z","steps":["trace[1220437864] 'agreement among raft nodes before linearized reading' (duration: 382.262753ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:07.215688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:06.832467Z","time spent":"383.211759ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T22:19:08.26044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.340011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:19:08.260597Z","caller":"traceutil/trace.go:171","msg":"trace[614895069] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"1.040844427s","start":"2026-07-06T22:19:07.219729Z","end":"2026-07-06T22:19:08.260573Z","steps":["trace[614895069] 'process raft request' (duration: 85.292451ms)","trace[614895069] 'compare' (duration: 955.038203ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:08.260674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.219708Z","time spent":"1.04092808s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:08.26103Z","caller":"traceutil/trace.go:171","msg":"trace[1656569214] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.040256622s","start":"2026-07-06T22:19:07.220749Z","end":"2026-07-06T22:19:08.261005Z","steps":["trace[1656569214] 'process raft request' (duration: 1.040142859s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:08.261164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.220724Z","time spent":"1.040368734s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:08.261229Z","caller":"traceutil/trace.go:171","msg":"trace[1963170148] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"1.040247451s","start":"2026-07-06T22:19:07.220963Z","end":"2026-07-06T22:19:08.261211Z","steps":["trace[1963170148] 'process raft request' (duration: 1.040007805s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:08.261328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.220942Z","time spent":"1.040337894s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:08.261393Z","caller":"traceutil/trace.go:171","msg":"trace[578761262] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1267; }","duration":"340.26617ms","start":"2026-07-06T22:19:07.921104Z","end":"2026-07-06T22:19:08.26137Z","steps":["trace[578761262] 'read index received' (duration: 339.730826ms)","trace[578761262] 'applied index is now lower than readState.Index' (duration: 534.154µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:08.261409Z","caller":"traceutil/trace.go:171","msg":"trace[1924445995] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.039518683s","start":"2026-07-06T22:19:07.221872Z","end":"2026-07-06T22:19:08.261391Z","steps":["trace[1924445995] 'process raft request' (duration: 1.039264606s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:08.261507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.221862Z","time spent":"1.039603494s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:19:08.261562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.949068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:08.261596Z","caller":"traceutil/trace.go:171","msg":"trace[2086713980] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1215; }","duration":"325.01756ms","start":"2026-07-06T22:19:07.936569Z","end":"2026-07-06T22:19:08.261586Z","steps":["trace[2086713980] 'agreement among raft nodes before linearized reading' (duration: 324.941408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:08.261599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.494476ms","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-07-06T22:19:08.261633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.611541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:08.261663Z","caller":"traceutil/trace.go:171","msg":"trace[1106676538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"200.670503ms","start":"2026-07-06T22:19:08.060983Z","end":"2026-07-06T22:19:08.261653Z","steps":["trace[1106676538] 'agreement among raft nodes before linearized reading' (duration: 200.618382ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:08.261658Z","caller":"traceutil/trace.go:171","msg":"trace[753201883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"340.580509ms","start":"2026-07-06T22:19:07.921063Z","end":"2026-07-06T22:19:08.261643Z","steps":["trace[753201883] 'agreement among raft nodes before linearized reading' (duration: 340.382203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:08.261701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.921045Z","time spent":"340.64543ms","remote":"127.0.0.1:58482","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-07-06T22:19:08.26187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:07.936558Z","time spent":"325.295467ms","remote":"127.0.0.1:58440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-07-06T22:19:14.483182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.06878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:19:14.483295Z","caller":"traceutil/trace.go:171","msg":"trace[231879703] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"152.24399ms","start":"2026-07-06T22:19:14.33103Z","end":"2026-07-06T22:19:14.483274Z","steps":["trace[231879703] 'process raft request' (duration: 47.008095ms)","trace[231879703] 'compare' (duration: 104.938907ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:20.554055Z","caller":"traceutil/trace.go:171","msg":"trace[629869821] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1310; }","duration":"164.329818ms","start":"2026-07-06T22:19:20.389708Z","end":"2026-07-06T22:19:20.554038Z","steps":["trace[629869821] 'read index received' (duration: 146.798608ms)","trace[629869821] 'applied index is now lower than readState.Index' (duration: 17.5306ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:20.554137Z","caller":"traceutil/trace.go:171","msg":"trace[1248870907] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"169.010211ms","start":"2026-07-06T22:19:20.38511Z","end":"2026-07-06T22:19:20.55412Z","steps":["trace[1248870907] 'process raft request' (duration: 151.381438ms)","trace[1248870907] 'compare' (duration: 17.385777ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:20.554121Z","caller":"traceutil/trace.go:171","msg":"trace[389383870] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"168.488447ms","start":"2026-07-06T22:19:20.385603Z","end":"2026-07-06T22:19:20.554091Z","steps":["trace[389383870] 'process raft request' (duration: 168.390745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:20.554339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.843704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:19:20.554362Z","caller":"traceutil/trace.go:171","msg":"trace[2067227365] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"162.133109ms","start":"2026-07-06T22:19:20.39222Z","end":"2026-07-06T22:19:20.554353Z","steps":["trace[2067227365] 'process raft request' (duration: 161.802691ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:20.554372Z","caller":"traceutil/trace.go:171","msg":"trace[1527201589] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1255; }","duration":"182.920396ms","start":"2026-07-06T22:19:20.371445Z","end":"2026-07-06T22:19:20.554365Z","steps":["trace[1527201589] 'agreement among raft nodes before linearized reading' (duration: 182.715371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:20.554758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.833843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:19:20.554785Z","caller":"traceutil/trace.go:171","msg":"trace[1515366527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"169.887005ms","start":"2026-07-06T22:19:20.384892Z","end":"2026-07-06T22:19:20.554779Z","steps":["trace[1515366527] 'agreement among raft nodes before linearized reading' (duration: 169.784822ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:28.14959Z","caller":"traceutil/trace.go:171","msg":"trace[2065851992] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1389; }","duration":"105.351247ms","start":"2026-07-06T22:19:28.044222Z","end":"2026-07-06T22:19:28.149574Z","steps":["trace[2065851992] 'read index received' (duration: 105.185273ms)","trace[2065851992] 'applied index is now lower than readState.Index' (duration: 165.294µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:28.149724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.47993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:19:28.149741Z","caller":"traceutil/trace.go:171","msg":"trace[549981115] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1329; }","duration":"105.552582ms","start":"2026-07-06T22:19:28.044184Z","end":"2026-07-06T22:19:28.149736Z","steps":["trace[549981115] 'agreement among raft nodes before linearized reading' (duration: 105.45295ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:28.149736Z","caller":"traceutil/trace.go:171","msg":"trace[1170130613] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"765.186014ms","start":"2026-07-06T22:19:27.384493Z","end":"2026-07-06T22:19:28.149679Z","steps":["trace[1170130613] 'process raft request' (duration: 764.960988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:28.149897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:27.384466Z","time spent":"765.342778ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:40.300293Z","caller":"traceutil/trace.go:171","msg":"trace[485835239] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"107.743751ms","start":"2026-07-06T22:19:40.192531Z","end":"2026-07-06T22:19:40.300275Z","steps":["trace[485835239] 'process raft request' (duration: 107.636999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:40.692746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.467237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:40.692809Z","caller":"traceutil/trace.go:171","msg":"trace[1216871994] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1369; }","duration":"165.586232ms","start":"2026-07-06T22:19:40.527207Z","end":"2026-07-06T22:19:40.692793Z","steps":["trace[1216871994] 'count revisions from in-memory index tree' (duration: 165.322254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:40.692769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.546054ms","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-07-06T22:19:40.69299Z","caller":"traceutil/trace.go:171","msg":"trace[120590990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"196.78778ms","start":"2026-07-06T22:19:40.496194Z","end":"2026-07-06T22:19:40.692982Z","steps":["trace[120590990] 'range keys from in-memory index tree' (duration: 196.451331ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:42.167081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.9737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-06T22:19:42.167187Z","caller":"traceutil/trace.go:171","msg":"trace[1970215117] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1440; }","duration":"105.181024ms","start":"2026-07-06T22:19:42.061986Z","end":"2026-07-06T22:19:42.167167Z","steps":["trace[1970215117] 'read index received' (duration: 44.851µs)","trace[1970215117] 'applied index is now lower than readState.Index' (duration: 105.134673ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:42.167271Z","caller":"traceutil/trace.go:171","msg":"trace[248340804] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"540.415749ms","start":"2026-07-06T22:19:41.626844Z","end":"2026-07-06T22:19:42.16726Z","steps":["trace[248340804] 'process raft request' (duration: 253.195492ms)","trace[248340804] 'compare' (duration: 286.498458ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:42.167325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:41.626821Z","time spent":"540.47991ms","remote":"127.0.0.1:58354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T22:19:42.167584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.582865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:42.167662Z","caller":"traceutil/trace.go:171","msg":"trace[685301415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1377; }","duration":"105.708898ms","start":"2026-07-06T22:19:42.061934Z","end":"2026-07-06T22:19:42.167643Z","steps":["trace[685301415] 'agreement among raft nodes before linearized reading' (duration: 105.567294ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:42.436193Z","caller":"traceutil/trace.go:171","msg":"trace[1711757919] linearizableReadLoop","detail":"{readStateIndex:1442; appliedIndex:1441; }","duration":"120.647211ms","start":"2026-07-06T22:19:42.31551Z","end":"2026-07-06T22:19:42.436157Z","steps":["trace[1711757919] 'read index received' (duration: 103.606743ms)","trace[1711757919] 'applied index is now lower than readState.Index' (duration: 17.035688ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:42.436443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.917457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-06T22:19:42.436486Z","caller":"traceutil/trace.go:171","msg":"trace[1908573657] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1378; }","duration":"120.999099ms","start":"2026-07-06T22:19:42.315477Z","end":"2026-07-06T22:19:42.436476Z","steps":["trace[1908573657] 'agreement among raft nodes before linearized reading' (duration: 120.796664ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:42.436896Z","caller":"traceutil/trace.go:171","msg":"trace[62116063] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"129.049021ms","start":"2026-07-06T22:19:42.307812Z","end":"2026-07-06T22:19:42.436861Z","steps":["trace[62116063] 'process raft request' (duration: 111.383328ms)","trace[62116063] 'compare' (duration: 16.677307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:43.7292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.021439ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-06T22:19:45.174425Z","caller":"traceutil/trace.go:171","msg":"trace[1258056518] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"280.230522ms","start":"2026-07-06T22:19:44.894165Z","end":"2026-07-06T22:19:45.174395Z","steps":["trace[1258056518] 'process raft request' (duration: 280.176171ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:45.174464Z","caller":"traceutil/trace.go:171","msg":"trace[1073179843] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"280.468509ms","start":"2026-07-06T22:19:44.893965Z","end":"2026-07-06T22:19:45.174434Z","steps":["trace[1073179843] 'process raft request' (duration: 280.274445ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:45.174574Z","caller":"traceutil/trace.go:171","msg":"trace[1301729170] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1465; }","duration":"115.065643ms","start":"2026-07-06T22:19:45.059499Z","end":"2026-07-06T22:19:45.174565Z","steps":["trace[1301729170] 'read index received' (duration: 114.693683ms)","trace[1301729170] 'applied index is now lower than readState.Index' (duration: 371.31µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:45.174645Z","caller":"traceutil/trace.go:171","msg":"trace[104947504] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"279.688268ms","start":"2026-07-06T22:19:44.894929Z","end":"2026-07-06T22:19:45.174617Z","steps":["trace[104947504] 'process raft request' (duration: 279.441092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:45.174704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.191037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:45.174733Z","caller":"traceutil/trace.go:171","msg":"trace[1256246667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1403; }","duration":"115.267918ms","start":"2026-07-06T22:19:45.059457Z","end":"2026-07-06T22:19:45.174725Z","steps":["trace[1256246667] 'agreement among raft nodes before linearized reading' (duration: 115.162695ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:46.045479Z","caller":"traceutil/trace.go:171","msg":"trace[350109370] linearizableReadLoop","detail":"{readStateIndex:1469; appliedIndex:1468; }","duration":"146.486349ms","start":"2026-07-06T22:19:45.898971Z","end":"2026-07-06T22:19:46.045457Z","steps":["trace[350109370] 'read index received' (duration: 146.256113ms)","trace[350109370] 'applied index is now lower than readState.Index' (duration: 229.276µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:46.045636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.642503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T22:19:46.045682Z","caller":"traceutil/trace.go:171","msg":"trace[1865733351] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1404; }","duration":"146.754985ms","start":"2026-07-06T22:19:45.898917Z","end":"2026-07-06T22:19:46.045672Z","steps":["trace[1865733351] 'agreement among raft nodes before linearized reading' (duration: 146.631382ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:46.046101Z","caller":"traceutil/trace.go:171","msg":"trace[1449830302] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"806.281985ms","start":"2026-07-06T22:19:45.239806Z","end":"2026-07-06T22:19:46.046088Z","steps":["trace[1449830302] 'process raft request' (duration: 805.498974ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:46.046193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:45.239792Z","time spent":"806.342956ms","remote":"127.0.0.1:58430","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:46.166101Z","caller":"traceutil/trace.go:171","msg":"trace[688787663] transaction","detail":"{read_only:false; response_revision:1405; number_of_response:1; }","duration":"114.507256ms","start":"2026-07-06T22:19:46.05157Z","end":"2026-07-06T22:19:46.166078Z","steps":["trace[688787663] 'process raft request' (duration: 98.938664ms)","trace[688787663] 'compare' (duration: 15.073569ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:46.211423Z","caller":"traceutil/trace.go:171","msg":"trace[1138140716] linearizableReadLoop","detail":"{readStateIndex:1472; appliedIndex:1469; }","duration":"151.630987ms","start":"2026-07-06T22:19:46.059775Z","end":"2026-07-06T22:19:46.211406Z","steps":["trace[1138140716] 'read index received' (duration: 90.858671ms)","trace[1138140716] 'applied index is now lower than readState.Index' (duration: 60.771596ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:46.211654Z","caller":"traceutil/trace.go:171","msg":"trace[1791282927] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"157.975696ms","start":"2026-07-06T22:19:46.05367Z","end":"2026-07-06T22:19:46.211646Z","steps":["trace[1791282927] 'process raft request' (duration: 157.596185ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:46.212059Z","caller":"traceutil/trace.go:171","msg":"trace[1004600113] transaction","detail":"{read_only:false; response_revision:1407; number_of_response:1; }","duration":"156.488016ms","start":"2026-07-06T22:19:46.055561Z","end":"2026-07-06T22:19:46.212049Z","steps":["trace[1004600113] 'process raft request' (duration: 155.807248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:46.212236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.446368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:46.212314Z","caller":"traceutil/trace.go:171","msg":"trace[488127685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1407; }","duration":"152.536341ms","start":"2026-07-06T22:19:46.059742Z","end":"2026-07-06T22:19:46.212279Z","steps":["trace[488127685] 'agreement among raft nodes before linearized reading' (duration: 152.439088ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:47.011081Z","caller":"traceutil/trace.go:171","msg":"trace[105695087] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"112.895543ms","start":"2026-07-06T22:19:46.898146Z","end":"2026-07-06T22:19:47.011041Z","steps":["trace[105695087] 'process raft request' (duration: 112.651377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.011116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.044444ms","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-07-06T22:19:47.011436Z","caller":"traceutil/trace.go:171","msg":"trace[1333746354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1409; }","duration":"100.380653ms","start":"2026-07-06T22:19:46.911038Z","end":"2026-07-06T22:19:47.011419Z","steps":["trace[1333746354] 'agreement among raft nodes before linearized reading' (duration: 99.864459ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:47.51044Z","caller":"traceutil/trace.go:171","msg":"trace[110668971] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"493.37811ms","start":"2026-07-06T22:19:47.017036Z","end":"2026-07-06T22:19:47.510415Z","steps":["trace[110668971] 'process raft request' (duration: 493.236235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.510585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.01702Z","time spent":"493.487843ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:19:47.63915Z","caller":"traceutil/trace.go:171","msg":"trace[1092485283] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1474; }","duration":"577.951645ms","start":"2026-07-06T22:19:47.061179Z","end":"2026-07-06T22:19:47.63913Z","steps":["trace[1092485283] 'read index received' (duration: 449.055268ms)","trace[1092485283] 'applied index is now lower than readState.Index' (duration: 128.895597ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:47.639227Z","caller":"traceutil/trace.go:171","msg":"trace[801547386] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"619.39907ms","start":"2026-07-06T22:19:47.019795Z","end":"2026-07-06T22:19:47.639195Z","steps":["trace[801547386] 'process raft request' (duration: 584.60118ms)","trace[801547386] 'compare' (duration: 34.637975ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T22:19:47.639272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.076848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:47.639297Z","caller":"traceutil/trace.go:171","msg":"trace[357314145] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"578.15113ms","start":"2026-07-06T22:19:47.061138Z","end":"2026-07-06T22:19:47.639289Z","steps":["trace[357314145] 'agreement among raft nodes before linearized reading' (duration: 578.094459ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.639322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.061117Z","time spent":"578.200701ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:19:47.639342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.019771Z","time spent":"619.507123ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:19:47.639544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.185842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-06T22:19:47.639581Z","caller":"traceutil/trace.go:171","msg":"trace[286307582] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1411; }","duration":"460.240403ms","start":"2026-07-06T22:19:47.179327Z","end":"2026-07-06T22:19:47.639568Z","steps":["trace[286307582] 'agreement among raft nodes before linearized reading' (duration: 460.127611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.63962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.179313Z","time spent":"460.296804ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-06T22:19:47.639663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.688603ms","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-07-06T22:19:47.639687Z","caller":"traceutil/trace.go:171","msg":"trace[75048248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1411; }","duration":"458.725513ms","start":"2026-07-06T22:19:47.180955Z","end":"2026-07-06T22:19:47.63968Z","steps":["trace[75048248] 'agreement among raft nodes before linearized reading' (duration: 458.662292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.639704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.180949Z","time spent":"458.750654ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-06T22:19:47.639746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.3319ms","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-07-06T22:19:47.639773Z","caller":"traceutil/trace.go:171","msg":"trace[1662625877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1411; }","duration":"459.3581ms","start":"2026-07-06T22:19:47.180406Z","end":"2026-07-06T22:19:47.639764Z","steps":["trace[1662625877] 'agreement among raft nodes before linearized reading' (duration: 459.279268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:47.6398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:47.180366Z","time spent":"459.426492ms","remote":"127.0.0.1:58482","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-07-06T22:19:47.815255Z","caller":"traceutil/trace.go:171","msg":"trace[603944954] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"172.031307ms","start":"2026-07-06T22:19:47.643186Z","end":"2026-07-06T22:19:47.815218Z","steps":["trace[603944954] 'process raft request' (duration: 140.027121ms)","trace[603944954] 'compare' (duration: 31.841052ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:47.821867Z","caller":"traceutil/trace.go:171","msg":"trace[902500504] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"178.517068ms","start":"2026-07-06T22:19:47.643331Z","end":"2026-07-06T22:19:47.821848Z","steps":["trace[902500504] 'process raft request' (duration: 178.375893ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:47.822081Z","caller":"traceutil/trace.go:171","msg":"trace[947579812] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"178.039236ms","start":"2026-07-06T22:19:47.644005Z","end":"2026-07-06T22:19:47.822045Z","steps":["trace[947579812] 'process raft request' (duration: 177.807849ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:19:48.886222Z","caller":"traceutil/trace.go:171","msg":"trace[381199664] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"268.728533ms","start":"2026-07-06T22:19:48.617467Z","end":"2026-07-06T22:19:48.886195Z","steps":["trace[381199664] 'process raft request' (duration: 268.552939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:57.065475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585718979,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-06T22:19:57.279614Z","caller":"traceutil/trace.go:171","msg":"trace[309204811] linearizableReadLoop","detail":"{readStateIndex:1552; appliedIndex:1551; }","duration":"714.576166ms","start":"2026-07-06T22:19:56.565015Z","end":"2026-07-06T22:19:57.279591Z","steps":["trace[309204811] 'read index received' (duration: 714.388981ms)","trace[309204811] 'applied index is now lower than readState.Index' (duration: 186.095µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:19:57.279729Z","caller":"traceutil/trace.go:171","msg":"trace[1816836777] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"770.512115ms","start":"2026-07-06T22:19:56.509206Z","end":"2026-07-06T22:19:57.279718Z","steps":["trace[1816836777] 'process raft request' (duration: 770.254508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:57.279828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:56.509183Z","time spent":"770.572727ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:19:57.280278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.265804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-06T22:19:57.280266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.042713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-06T22:19:57.280323Z","caller":"traceutil/trace.go:171","msg":"trace[1139263863] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1485; }","duration":"715.352276ms","start":"2026-07-06T22:19:56.564959Z","end":"2026-07-06T22:19:57.280311Z","steps":["trace[1139263863] 'agreement among raft nodes before linearized reading' (duration: 715.236404ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:57.280361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:56.56494Z","time spent":"715.412068ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-06T22:19:57.280401Z","caller":"traceutil/trace.go:171","msg":"trace[108696281] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1485; }","duration":"302.170616ms","start":"2026-07-06T22:19:56.978175Z","end":"2026-07-06T22:19:57.280345Z","steps":["trace[108696281] 'agreement among raft nodes before linearized reading' (duration: 301.890048ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:57.280714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.803204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-06T22:19:57.280783Z","caller":"traceutil/trace.go:171","msg":"trace[1260114764] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1485; }","duration":"226.925487ms","start":"2026-07-06T22:19:57.053843Z","end":"2026-07-06T22:19:57.280768Z","steps":["trace[1260114764] 'agreement among raft nodes before linearized reading' (duration: 226.700341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:19:57.280824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:19:56.978144Z","time spent":"302.29579ms","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-06T22:19:57.280332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.691388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:19:57.281033Z","caller":"traceutil/trace.go:171","msg":"trace[1841409472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1485; }","duration":"217.485828ms","start":"2026-07-06T22:19:57.063533Z","end":"2026-07-06T22:19:57.281019Z","steps":["trace[1841409472] 'agreement among raft nodes before linearized reading' (duration: 216.646496ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:20:56.240404Z","caller":"traceutil/trace.go:171","msg":"trace[947502118] linearizableReadLoop","detail":"{readStateIndex:1837; appliedIndex:1836; }","duration":"178.588887ms","start":"2026-07-06T22:20:56.061793Z","end":"2026-07-06T22:20:56.240382Z","steps":["trace[947502118] 'read index received' (duration: 178.446243ms)","trace[947502118] 'applied index is now lower than readState.Index' (duration: 141.414µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:20:56.240744Z","caller":"traceutil/trace.go:171","msg":"trace[2002823257] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"298.654591ms","start":"2026-07-06T22:20:55.942075Z","end":"2026-07-06T22:20:56.240729Z","steps":["trace[2002823257] 'process raft request' (duration: 298.185959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:20:56.240951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.153711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:20:56.241004Z","caller":"traceutil/trace.go:171","msg":"trace[1768161599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1758; }","duration":"179.251744ms","start":"2026-07-06T22:20:56.061729Z","end":"2026-07-06T22:20:56.240981Z","steps":["trace[1768161599] 'agreement among raft nodes before linearized reading' (duration: 179.179512ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:21:23.145457Z","caller":"traceutil/trace.go:171","msg":"trace[1159289239] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"102.39484ms","start":"2026-07-06T22:21:23.043034Z","end":"2026-07-06T22:21:23.145429Z","steps":["trace[1159289239] 'process raft request' (duration: 102.191444ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:12.848515Z","caller":"traceutil/trace.go:171","msg":"trace[831492762] transaction","detail":"{read_only:false; response_revision:2384; number_of_response:1; }","duration":"189.181147ms","start":"2026-07-06T22:23:12.658956Z","end":"2026-07-06T22:23:12.848137Z","steps":["trace[831492762] 'process raft request' (duration: 188.985231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:13.439153Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585721211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:23:13.939388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585721211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:23:14.440199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585721211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:23:14.940948Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585721211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:23:15.05938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00052766s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-06T22:23:15.05945Z","caller":"traceutil/trace.go:171","msg":"trace[1056195846] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000645133s","start":"2026-07-06T22:23:13.058789Z","end":"2026-07-06T22:23:15.059434Z","steps":["trace[1056195846] 'agreement among raft nodes before linearized reading' (duration: 2.000550891s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.059487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:13.058774Z","time spent":"2.000706354s","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/06 22:23:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-06T22:23:15.441398Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4831411569585721211,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-06T22:23:15.51725Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.621919383s","expected-duration":"1s"} {"level":"info","ts":"2026-07-06T22:23:15.519172Z","caller":"traceutil/trace.go:171","msg":"trace[1190076172] transaction","detail":"{read_only:false; response_revision:2385; number_of_response:1; }","duration":"2.623923027s","start":"2026-07-06T22:23:12.89522Z","end":"2026-07-06T22:23:15.519143Z","steps":["trace[1190076172] 'process raft request' (duration: 2.622237432s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.519313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:12.895201Z","time spent":"2.62404253s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T22:23:15.531832Z","caller":"traceutil/trace.go:171","msg":"trace[121845113] linearizableReadLoop","detail":"{readStateIndex:2493; appliedIndex:2490; }","duration":"2.593572398s","start":"2026-07-06T22:23:12.938241Z","end":"2026-07-06T22:23:15.531813Z","steps":["trace[121845113] 'read index received' (duration: 2.579294394s)","trace[121845113] 'applied index is now lower than readState.Index' (duration: 14.277214ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:23:15.531946Z","caller":"traceutil/trace.go:171","msg":"trace[1773563936] transaction","detail":"{read_only:false; response_revision:2386; number_of_response:1; }","duration":"1.421218008s","start":"2026-07-06T22:23:14.110721Z","end":"2026-07-06T22:23:15.531939Z","steps":["trace[1773563936] 'process raft request' (duration: 1.421002602s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.532048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:14.110701Z","time spent":"1.421266228s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T22:23:15.532154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.593907607s","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-07-06T22:23:15.532199Z","caller":"traceutil/trace.go:171","msg":"trace[606852790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2386; }","duration":"2.593988939s","start":"2026-07-06T22:23:12.938199Z","end":"2026-07-06T22:23:15.532188Z","steps":["trace[606852790] 'agreement among raft nodes before linearized reading' (duration: 2.593835605s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.532228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:12.938179Z","time spent":"2.594041351s","remote":"127.0.0.1:58482","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-07-06T22:23:15.532562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.153729361s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"warn","ts":"2026-07-06T22:23:15.532585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146359187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-06T22:23:15.532611Z","caller":"traceutil/trace.go:171","msg":"trace[558284069] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2386; }","duration":"2.153816103s","start":"2026-07-06T22:23:13.378782Z","end":"2026-07-06T22:23:15.532598Z","steps":["trace[558284069] 'agreement among raft nodes before linearized reading' (duration: 2.15371634s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:15.532616Z","caller":"traceutil/trace.go:171","msg":"trace[2135267725] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2386; }","duration":"1.146427339s","start":"2026-07-06T22:23:14.386181Z","end":"2026-07-06T22:23:15.532608Z","steps":["trace[2135267725] 'agreement among raft nodes before linearized reading' (duration: 1.146345677s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.53264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:13.378761Z","time spent":"2.153869995s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-06T22:23:15.532641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:14.386161Z","time spent":"1.146473411s","remote":"127.0.0.1:58482","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-07-06T22:23:15.532634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143684078s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-07-06T22:23:15.532575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.593082575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-06T22:23:15.53271Z","caller":"traceutil/trace.go:171","msg":"trace[1799935010] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2386; }","duration":"1.143791141s","start":"2026-07-06T22:23:14.388902Z","end":"2026-07-06T22:23:15.532693Z","steps":["trace[1799935010] 'agreement among raft nodes before linearized reading' (duration: 1.143577896s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:15.532712Z","caller":"traceutil/trace.go:171","msg":"trace[843523129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2386; }","duration":"2.59326035s","start":"2026-07-06T22:23:12.939443Z","end":"2026-07-06T22:23:15.532704Z","steps":["trace[843523129] 'agreement among raft nodes before linearized reading' (duration: 2.593020494s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.53274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:12.939427Z","time spent":"2.59330532s","remote":"127.0.0.1:58482","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-07-06T22:23:15.532763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.592770936s","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-07-06T22:23:15.532661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.532647795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-06T22:23:15.532803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.041161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T22:23:15.532809Z","caller":"traceutil/trace.go:171","msg":"trace[124963160] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2386; }","duration":"2.532843141s","start":"2026-07-06T22:23:12.999957Z","end":"2026-07-06T22:23:15.5328Z","steps":["trace[124963160] 'agreement among raft nodes before linearized reading' (duration: 2.532650716s)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:15.53282Z","caller":"traceutil/trace.go:171","msg":"trace[1404931943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2386; }","duration":"473.103732ms","start":"2026-07-06T22:23:15.059711Z","end":"2026-07-06T22:23:15.532814Z","steps":["trace[1404931943] 'agreement among raft nodes before linearized reading' (duration: 473.078652ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:15.532812Z","caller":"traceutil/trace.go:171","msg":"trace[2069998018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2386; }","duration":"2.592826458s","start":"2026-07-06T22:23:12.93997Z","end":"2026-07-06T22:23:15.532797Z","steps":["trace[2069998018] 'agreement among raft nodes before linearized reading' (duration: 2.592714565s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.532846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:15.059667Z","time spent":"473.167314ms","remote":"127.0.0.1:58286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:23:15.532857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:12.939963Z","time spent":"2.592882609s","remote":"127.0.0.1:58482","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-07-06T22:23:15.532916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115042045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-06T22:23:15.532944Z","caller":"traceutil/trace.go:171","msg":"trace[691905337] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2386; }","duration":"1.115090565s","start":"2026-07-06T22:23:14.417846Z","end":"2026-07-06T22:23:15.532936Z","steps":["trace[691905337] 'agreement among raft nodes before linearized reading' (duration: 1.115013772s)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.532775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.525569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T22:23:15.532972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:14.417835Z","time spent":"1.115130147s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-06T22:23:15.533001Z","caller":"traceutil/trace.go:171","msg":"trace[1049450361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2386; }","duration":"304.777197ms","start":"2026-07-06T22:23:15.228209Z","end":"2026-07-06T22:23:15.532986Z","steps":["trace[1049450361] 'agreement among raft nodes before linearized reading' (duration: 304.54641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:15.532741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:14.388891Z","time spent":"1.143844172s","remote":"127.0.0.1:58482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-06T22:23:15.533044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:15.228191Z","time spent":"304.838608ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-06T22:23:15.532842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T22:23:12.999932Z","time spent":"2.532899103s","remote":"127.0.0.1:58770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-07-06T22:23:49.845783Z","caller":"traceutil/trace.go:171","msg":"trace[1244548626] transaction","detail":"{read_only:false; response_revision:2572; number_of_response:1; }","duration":"135.788421ms","start":"2026-07-06T22:23:49.709959Z","end":"2026-07-06T22:23:49.845747Z","steps":["trace[1244548626] 'process raft request' (duration: 39.796457ms)","trace[1244548626] 'compare' (duration: 95.854631ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:23:49.851122Z","caller":"traceutil/trace.go:171","msg":"trace[1241543745] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"140.458414ms","start":"2026-07-06T22:23:49.710647Z","end":"2026-07-06T22:23:49.851105Z","steps":["trace[1241543745] 'process raft request' (duration: 140.342712ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:49.85129Z","caller":"traceutil/trace.go:171","msg":"trace[418139755] transaction","detail":"{read_only:false; response_revision:2574; number_of_response:1; }","duration":"139.870059ms","start":"2026-07-06T22:23:49.71141Z","end":"2026-07-06T22:23:49.85128Z","steps":["trace[418139755] 'process raft request' (duration: 139.659664ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T22:23:49.851614Z","caller":"traceutil/trace.go:171","msg":"trace[1604591802] linearizableReadLoop","detail":"{readStateIndex:2690; appliedIndex:2686; }","duration":"139.225732ms","start":"2026-07-06T22:23:49.712366Z","end":"2026-07-06T22:23:49.851592Z","steps":["trace[1604591802] 'read index received' (duration: 37.471085ms)","trace[1604591802] 'applied index is now lower than readState.Index' (duration: 101.753457ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T22:23:49.851682Z","caller":"traceutil/trace.go:171","msg":"trace[617983197] transaction","detail":"{read_only:false; response_revision:2575; number_of_response:1; }","duration":"139.889309ms","start":"2026-07-06T22:23:49.71177Z","end":"2026-07-06T22:23:49.85166Z","steps":["trace[617983197] 'process raft request' (duration: 139.474188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:49.851788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.406226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-06T22:23:49.851822Z","caller":"traceutil/trace.go:171","msg":"trace[961428469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2575; }","duration":"139.471858ms","start":"2026-07-06T22:23:49.712339Z","end":"2026-07-06T22:23:49.851811Z","steps":["trace[961428469] 'agreement among raft nodes before linearized reading' (duration: 139.347525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T22:23:49.851932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.636174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-06T22:23:49.852019Z","caller":"traceutil/trace.go:171","msg":"trace[988153221] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2575; }","duration":"136.749286ms","start":"2026-07-06T22:23:49.715253Z","end":"2026-07-06T22:23:49.852003Z","steps":["trace[988153221] 'agreement among raft nodes before linearized reading' (duration: 136.51462ms)"],"step_count":1}