{"level":"warn","ts":"2026-05-05T22:15:12.247063Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:15:12.247206Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242: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.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242: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-05-05T22:15:12.247302Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:15:12.24734Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-05T22:15:12.247372Z","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-05-05T22:15:12.248048Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-05-05T22:15:12.248159Z","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.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242: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-05-05T22:15:12.252964Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.562594ms"} {"level":"info","ts":"2026-05-05T22:15:12.261739Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-05-05T22:15:12.261843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:15:12.261873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-05-05T22:15:12.261934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:15:12.261952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-05-05T22:15:12.262012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-05-05T22:15:12.282528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:15:12.284715Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:15:12.287272Z","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-05-05T22:15:12.290771Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:15:12.290891Z","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-05-05T22:15:12.291007Z","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-05-05T22:15:12.291018Z","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-05-05T22:15:12.290948Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:15:12.294625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-05-05T22:15:12.295213Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-05-05T22:15:12.296219Z","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-05-05T22:15:12.296391Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-05T22:15:12.296453Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-05-05T22:15:12.296682Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:15:12.296756Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:15:12.964299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:15:12.964362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:15:12.964387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-05-05T22:15:12.964406Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:15:12.964419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-05T22:15:12.964436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-05-05T22:15:12.964456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-05-05T22:15:12.996893Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:13.035623Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:15:13.035993Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:13.036109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:15:13.038655Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:15:13.038712Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:15:13.040852Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:15:13.042512Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:13.042701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:13.042747Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:15:13.043541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-05-05T22:15:14.812202Z","caller":"traceutil/trace.go:171","msg":"trace[1541105937] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"234.631328ms","start":"2026-05-05T22:15:14.577538Z","end":"2026-05-05T22:15:14.81217Z","steps":["trace[1541105937] 'read index received' (duration: 229.039976ms)","trace[1541105937] 'applied index is now lower than readState.Index' (duration: 5.589852ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:14.812224Z","caller":"traceutil/trace.go:171","msg":"trace[1832473616] transaction","detail":"{read_only:false; response_revision:81; number_of_response:1; }","duration":"251.17609ms","start":"2026-05-05T22:15:14.561019Z","end":"2026-05-05T22:15:14.812195Z","steps":["trace[1832473616] 'process raft request' (duration: 245.584278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:14.812449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.872133ms","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-05-05T22:15:14.812518Z","caller":"traceutil/trace.go:171","msg":"trace[500392858] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:81; }","duration":"235.004906ms","start":"2026-05-05T22:15:14.577496Z","end":"2026-05-05T22:15:14.812501Z","steps":["trace[500392858] 'agreement among raft nodes before linearized reading' (duration: 234.792512ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:14.81296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.67186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:15:14.813264Z","caller":"traceutil/trace.go:171","msg":"trace[1345942724] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:81; }","duration":"142.899714ms","start":"2026-05-05T22:15:14.670176Z","end":"2026-05-05T22:15:14.813076Z","steps":["trace[1345942724] 'agreement among raft nodes before linearized reading' (duration: 142.610749ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:37.579529Z","caller":"traceutil/trace.go:171","msg":"trace[1922814207] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"218.270812ms","start":"2026-05-05T22:15:37.361231Z","end":"2026-05-05T22:15:37.579501Z","steps":["trace[1922814207] 'process raft request' (duration: 218.124949ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:37.580102Z","caller":"traceutil/trace.go:171","msg":"trace[1439363555] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:484; }","duration":"213.543147ms","start":"2026-05-05T22:15:37.366547Z","end":"2026-05-05T22:15:37.58009Z","steps":["trace[1439363555] 'read index received' (duration: 213.539117ms)","trace[1439363555] 'applied index is now lower than readState.Index' (duration: 3.22µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:37.580241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.681169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:15:37.580272Z","caller":"traceutil/trace.go:171","msg":"trace[890245374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:466; }","duration":"213.7441ms","start":"2026-05-05T22:15:37.366518Z","end":"2026-05-05T22:15:37.580262Z","steps":["trace[890245374] 'agreement among raft nodes before linearized reading' (duration: 213.670829ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:39.63141Z","caller":"traceutil/trace.go:171","msg":"trace[1192528178] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"152.63508ms","start":"2026-05-05T22:15:39.478748Z","end":"2026-05-05T22:15:39.631383Z","steps":["trace[1192528178] 'process raft request' (duration: 72.846692ms)","trace[1192528178] 'compare' (duration: 79.600905ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:39.631597Z","caller":"traceutil/trace.go:171","msg":"trace[2136397281] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"152.711222ms","start":"2026-05-05T22:15:39.478865Z","end":"2026-05-05T22:15:39.631577Z","steps":["trace[2136397281] 'process raft request' (duration: 152.460147ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:39.631886Z","caller":"traceutil/trace.go:171","msg":"trace[1166630412] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"152.917996ms","start":"2026-05-05T22:15:39.478926Z","end":"2026-05-05T22:15:39.631844Z","steps":["trace[1166630412] 'process raft request' (duration: 152.566819ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:39.632076Z","caller":"traceutil/trace.go:171","msg":"trace[1855255690] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"152.671761ms","start":"2026-05-05T22:15:39.479388Z","end":"2026-05-05T22:15:39.632059Z","steps":["trace[1855255690] 'process raft request' (duration: 152.397827ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:39.632143Z","caller":"traceutil/trace.go:171","msg":"trace[1422163197] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:507; }","duration":"150.862808ms","start":"2026-05-05T22:15:39.481248Z","end":"2026-05-05T22:15:39.632111Z","steps":["trace[1422163197] 'read index received' (duration: 70.424558ms)","trace[1422163197] 'applied index is now lower than readState.Index' (duration: 80.4354ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:39.632192Z","caller":"traceutil/trace.go:171","msg":"trace[1152081299] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"147.038169ms","start":"2026-05-05T22:15:39.485141Z","end":"2026-05-05T22:15:39.632179Z","steps":["trace[1152081299] 'process raft request' (duration: 146.842266ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:39.632467Z","caller":"traceutil/trace.go:171","msg":"trace[692118060] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"146.694753ms","start":"2026-05-05T22:15:39.485756Z","end":"2026-05-05T22:15:39.632451Z","steps":["trace[692118060] 'process raft request' (duration: 146.313995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:39.632517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.251266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-05-05T22:15:39.632563Z","caller":"traceutil/trace.go:171","msg":"trace[293852623] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:495; }","duration":"151.344528ms","start":"2026-05-05T22:15:39.481206Z","end":"2026-05-05T22:15:39.632551Z","steps":["trace[293852623] 'agreement among raft nodes before linearized reading' (duration: 151.024651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:39.633231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-vhwdb\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-05-05T22:15:39.635248Z","caller":"traceutil/trace.go:171","msg":"trace[343690576] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-vhwdb; range_end:; response_count:1; response_revision:496; }","duration":"151.956498ms","start":"2026-05-05T22:15:39.483261Z","end":"2026-05-05T22:15:39.635217Z","steps":["trace[343690576] 'agreement among raft nodes before linearized reading' (duration: 149.723348ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:41.579796Z","caller":"traceutil/trace.go:171","msg":"trace[227326308] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"153.868993ms","start":"2026-05-05T22:15:41.425905Z","end":"2026-05-05T22:15:41.579774Z","steps":["trace[227326308] 'process raft request' (duration: 150.450202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:41.584988Z","caller":"traceutil/trace.go:171","msg":"trace[1245425327] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"154.481334ms","start":"2026-05-05T22:15:41.430493Z","end":"2026-05-05T22:15:41.584974Z","steps":["trace[1245425327] 'process raft request' (duration: 153.860273ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:41.585097Z","caller":"traceutil/trace.go:171","msg":"trace[1355074640] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"111.212398ms","start":"2026-05-05T22:15:41.473861Z","end":"2026-05-05T22:15:41.585073Z","steps":["trace[1355074640] 'process raft request' (duration: 111.152287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:41.585135Z","caller":"traceutil/trace.go:171","msg":"trace[2142418457] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"112.316818ms","start":"2026-05-05T22:15:41.472809Z","end":"2026-05-05T22:15:41.585125Z","steps":["trace[2142418457] 'process raft request' (duration: 111.83336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:42.326593Z","caller":"traceutil/trace.go:171","msg":"trace[1423404209] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"111.776929ms","start":"2026-05-05T22:15:42.214792Z","end":"2026-05-05T22:15:42.326569Z","steps":["trace[1423404209] 'process raft request' (duration: 101.469241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:42.806781Z","caller":"traceutil/trace.go:171","msg":"trace[1609895569] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"351.055333ms","start":"2026-05-05T22:15:42.455688Z","end":"2026-05-05T22:15:42.806743Z","steps":["trace[1609895569] 'process raft request' (duration: 350.87559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:42.807385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.455656Z","time spent":"351.232496ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:15:43.141973Z","caller":"traceutil/trace.go:171","msg":"trace[1661994413] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"672.348306ms","start":"2026-05-05T22:15:42.469582Z","end":"2026-05-05T22:15:43.14193Z","steps":["trace[1661994413] 'process raft request' (duration: 671.980219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.142217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.469558Z","time spent":"672.544839ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:15:43.142626Z","caller":"traceutil/trace.go:171","msg":"trace[1511141106] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"638.305798ms","start":"2026-05-05T22:15:42.504256Z","end":"2026-05-05T22:15:43.142561Z","steps":["trace[1511141106] 'process raft request' (duration: 637.830899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.142923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.504237Z","time spent":"638.561962ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:15:43.144312Z","caller":"traceutil/trace.go:171","msg":"trace[394280550] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"519.95737ms","start":"2026-05-05T22:15:42.624333Z","end":"2026-05-05T22:15:43.14429Z","steps":["trace[394280550] 'process raft request' (duration: 518.411032ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.144899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.624297Z","time spent":"520.082853ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:15:43.144958Z","caller":"traceutil/trace.go:171","msg":"trace[416684343] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"483.267083ms","start":"2026-05-05T22:15:42.661574Z","end":"2026-05-05T22:15:43.144841Z","steps":["trace[416684343] 'process raft request' (duration: 482.681482ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.145147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.661546Z","time spent":"483.456266ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:15:43.145537Z","caller":"traceutil/trace.go:171","msg":"trace[297668612] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:620; }","duration":"424.016197ms","start":"2026-05-05T22:15:42.72142Z","end":"2026-05-05T22:15:43.145436Z","steps":["trace[297668612] 'read index received' (duration: 85.135155ms)","trace[297668612] 'applied index is now lower than readState.Index' (duration: 338.741209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:43.146562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.126796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-vhwdb\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-05-05T22:15:43.146638Z","caller":"traceutil/trace.go:171","msg":"trace[985236140] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-vhwdb; range_end:; response_count:1; response_revision:603; }","duration":"425.241569ms","start":"2026-05-05T22:15:42.721377Z","end":"2026-05-05T22:15:43.146619Z","steps":["trace[985236140] 'agreement among raft nodes before linearized reading' (duration: 424.214821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.14668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.721358Z","time spent":"425.311039ms","remote":"127.0.0.1:40106","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-vhwdb\" "} {"level":"info","ts":"2026-05-05T22:15:43.150074Z","caller":"traceutil/trace.go:171","msg":"trace[2132157418] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"332.086518ms","start":"2026-05-05T22:15:42.817969Z","end":"2026-05-05T22:15:43.150055Z","steps":["trace[2132157418] 'process raft request' (duration: 331.504857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.150158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.813941ms","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-05-05T22:15:43.150189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:42.817955Z","time spent":"332.158179ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:15:43.150204Z","caller":"traceutil/trace.go:171","msg":"trace[961859016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"284.892622ms","start":"2026-05-05T22:15:42.865298Z","end":"2026-05-05T22:15:43.150191Z","steps":["trace[961859016] 'agreement among raft nodes before linearized reading' (duration: 284.758009ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:43.150376Z","caller":"traceutil/trace.go:171","msg":"trace[1881391652] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"288.380315ms","start":"2026-05-05T22:15:42.861985Z","end":"2026-05-05T22:15:43.150365Z","steps":["trace[1881391652] 'process raft request' (duration: 287.676903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:43.967045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.396387ms","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-05-05T22:15:43.967133Z","caller":"traceutil/trace.go:171","msg":"trace[86810026] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:625; }","duration":"103.530529ms","start":"2026-05-05T22:15:43.863582Z","end":"2026-05-05T22:15:43.967113Z","steps":["trace[86810026] 'range keys from in-memory index tree' (duration: 103.227663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:45.879119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.156686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:15:45.879239Z","caller":"traceutil/trace.go:171","msg":"trace[2114244238] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"540.771966ms","start":"2026-05-05T22:15:45.338449Z","end":"2026-05-05T22:15:45.879221Z","steps":["trace[2114244238] 'process raft request' (duration: 71.012869ms)","trace[2114244238] 'compare' (duration: 469.027304ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:45.87931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.338424Z","time spent":"540.845858ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:15:45.960461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640487707,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:15:46.381699Z","caller":"traceutil/trace.go:171","msg":"trace[95017776] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.028112785s","start":"2026-05-05T22:15:45.353556Z","end":"2026-05-05T22:15:46.381669Z","steps":["trace[95017776] 'process raft request' (duration: 1.027141187s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.381841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.353532Z","time spent":"1.028244956s","remote":"127.0.0.1:40012","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:15:46.400806Z","caller":"traceutil/trace.go:171","msg":"trace[1348834635] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"940.581796ms","start":"2026-05-05T22:15:45.4602Z","end":"2026-05-05T22:15:46.400781Z","steps":["trace[1348834635] 'read index received' (duration: 920.361338ms)","trace[1348834635] 'applied index is now lower than readState.Index' (duration: 20.219658ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:46.402354Z","caller":"traceutil/trace.go:171","msg":"trace[811230550] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"714.142055ms","start":"2026-05-05T22:15:45.688202Z","end":"2026-05-05T22:15:46.402344Z","steps":["trace[811230550] 'process raft request' (duration: 712.462144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.402462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.688146Z","time spent":"714.243377ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:15:46.403402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.195662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-05T22:15:46.403434Z","caller":"traceutil/trace.go:171","msg":"trace[80669153] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"943.272984ms","start":"2026-05-05T22:15:45.460153Z","end":"2026-05-05T22:15:46.403426Z","steps":["trace[80669153] 'agreement among raft nodes before linearized reading' (duration: 943.127411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.403488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.460132Z","time spent":"943.349326ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-05T22:15:46.403671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.142799ms","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-05-05T22:15:46.403741Z","caller":"traceutil/trace.go:171","msg":"trace[670209148] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"641.294692ms","start":"2026-05-05T22:15:45.762428Z","end":"2026-05-05T22:15:46.403723Z","steps":["trace[670209148] 'agreement among raft nodes before linearized reading' (duration: 641.093198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.403778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.762406Z","time spent":"641.365614ms","remote":"127.0.0.1:40184","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-05-05T22:15:46.404137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.24314ms","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-05-05T22:15:46.404171Z","caller":"traceutil/trace.go:171","msg":"trace[101607291] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"143.310732ms","start":"2026-05-05T22:15:46.260849Z","end":"2026-05-05T22:15:46.40416Z","steps":["trace[101607291] 'agreement among raft nodes before linearized reading' (duration: 143.182499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.404352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.792881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T22:15:46.404375Z","caller":"traceutil/trace.go:171","msg":"trace[117310269] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:640; }","duration":"295.855911ms","start":"2026-05-05T22:15:46.108514Z","end":"2026-05-05T22:15:46.404369Z","steps":["trace[117310269] 'agreement among raft nodes before linearized reading' (duration: 295.81477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.404895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.167736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:15:46.404931Z","caller":"traceutil/trace.go:171","msg":"trace[1726052181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"627.241787ms","start":"2026-05-05T22:15:45.777681Z","end":"2026-05-05T22:15:46.404923Z","steps":["trace[1726052181] 'agreement among raft nodes before linearized reading' (duration: 627.152374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.404976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.77766Z","time spent":"627.309399ms","remote":"127.0.0.1:40184","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-05-05T22:15:46.405189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.542552ms","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-05-05T22:15:46.405222Z","caller":"traceutil/trace.go:171","msg":"trace[453025940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:640; }","duration":"417.642954ms","start":"2026-05-05T22:15:45.987571Z","end":"2026-05-05T22:15:46.405214Z","steps":["trace[453025940] 'agreement among raft nodes before linearized reading' (duration: 417.521202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.405271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.987522Z","time spent":"417.742405ms","remote":"127.0.0.1:40184","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-05-05T22:15:46.405465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.231001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-05-05T22:15:46.405617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.933135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-05-05T22:15:46.405661Z","caller":"traceutil/trace.go:171","msg":"trace[598833307] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"344.018237ms","start":"2026-05-05T22:15:46.061633Z","end":"2026-05-05T22:15:46.405651Z","steps":["trace[598833307] 'agreement among raft nodes before linearized reading' (duration: 342.822485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.405695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:46.061613Z","time spent":"344.074617ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-05T22:15:46.405493Z","caller":"traceutil/trace.go:171","msg":"trace[614751401] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:640; }","duration":"544.305403ms","start":"2026-05-05T22:15:45.861181Z","end":"2026-05-05T22:15:46.405486Z","steps":["trace[614751401] 'agreement among raft nodes before linearized reading' (duration: 544.226651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:46.40593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:45.861159Z","time spent":"544.72643ms","remote":"127.0.0.1:39962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-05T22:15:49.576546Z","caller":"traceutil/trace.go:171","msg":"trace[2001765734] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"243.811966ms","start":"2026-05-05T22:15:49.332701Z","end":"2026-05-05T22:15:49.576513Z","steps":["trace[2001765734] 'read index received' (duration: 67.147019ms)","trace[2001765734] 'applied index is now lower than readState.Index' (duration: 176.663697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:49.57666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.949098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:49.576688Z","caller":"traceutil/trace.go:171","msg":"trace[1356340085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"244.04856ms","start":"2026-05-05T22:15:49.33263Z","end":"2026-05-05T22:15:49.576679Z","steps":["trace[1356340085] 'agreement among raft nodes before linearized reading' (duration: 243.983799ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:49.576855Z","caller":"traceutil/trace.go:171","msg":"trace[572602181] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"268.990593ms","start":"2026-05-05T22:15:49.30783Z","end":"2026-05-05T22:15:49.576821Z","steps":["trace[572602181] 'process raft request' (duration: 268.195619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:50.231434Z","caller":"traceutil/trace.go:171","msg":"trace[1574163960] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"174.281543ms","start":"2026-05-05T22:15:50.05712Z","end":"2026-05-05T22:15:50.231401Z","steps":["trace[1574163960] 'process raft request' (duration: 173.612481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:50.614997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.853591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:15:50.615099Z","caller":"traceutil/trace.go:171","msg":"trace[140207149] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"281.784116ms","start":"2026-05-05T22:15:50.333299Z","end":"2026-05-05T22:15:50.615083Z","steps":["trace[140207149] 'read index received' (duration: 148.774622ms)","trace[140207149] 'applied index is now lower than readState.Index' (duration: 133.008304ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:50.615174Z","caller":"traceutil/trace.go:171","msg":"trace[1557784456] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"330.947808ms","start":"2026-05-05T22:15:50.284189Z","end":"2026-05-05T22:15:50.615137Z","steps":["trace[1557784456] 'process raft request' (duration: 197.881303ms)","trace[1557784456] 'compare' (duration: 132.700588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:50.615338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:50.284128Z","time spent":"331.157642ms","remote":"127.0.0.1:40178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:15:50.615362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.425322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T22:15:50.615357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.191918ms","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-05-05T22:15:50.61541Z","caller":"traceutil/trace.go:171","msg":"trace[793808410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"177.498422ms","start":"2026-05-05T22:15:50.437897Z","end":"2026-05-05T22:15:50.615396Z","steps":["trace[793808410] 'agreement among raft nodes before linearized reading' (duration: 177.288199ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:50.615415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.462272ms","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-05-05T22:15:50.615515Z","caller":"traceutil/trace.go:171","msg":"trace[1750541100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"177.637215ms","start":"2026-05-05T22:15:50.437853Z","end":"2026-05-05T22:15:50.61549Z","steps":["trace[1750541100] 'agreement among raft nodes before linearized reading' (duration: 177.36182ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:50.615442Z","caller":"traceutil/trace.go:171","msg":"trace[733416127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:670; }","duration":"171.326211ms","start":"2026-05-05T22:15:50.444096Z","end":"2026-05-05T22:15:50.615422Z","steps":["trace[733416127] 'agreement among raft nodes before linearized reading' (duration: 171.086447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:50.615326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.975429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:50.615764Z","caller":"traceutil/trace.go:171","msg":"trace[575153997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"282.495228ms","start":"2026-05-05T22:15:50.333245Z","end":"2026-05-05T22:15:50.61574Z","steps":["trace[575153997] 'agreement among raft nodes before linearized reading' (duration: 281.927849ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:50.765009Z","caller":"traceutil/trace.go:171","msg":"trace[596780316] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"112.175247ms","start":"2026-05-05T22:15:50.652806Z","end":"2026-05-05T22:15:50.764981Z","steps":["trace[596780316] 'process raft request' (duration: 110.995305ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:50.960474Z","caller":"traceutil/trace.go:171","msg":"trace[868283597] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"191.387805ms","start":"2026-05-05T22:15:50.769056Z","end":"2026-05-05T22:15:50.960443Z","steps":["trace[868283597] 'process raft request' (duration: 142.715801ms)","trace[868283597] 'compare' (duration: 48.47749ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:50.960492Z","caller":"traceutil/trace.go:171","msg":"trace[104896292] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"189.697924ms","start":"2026-05-05T22:15:50.770769Z","end":"2026-05-05T22:15:50.960467Z","steps":["trace[104896292] 'process raft request' (duration: 189.656093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:50.960964Z","caller":"traceutil/trace.go:171","msg":"trace[253129359] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"190.223363ms","start":"2026-05-05T22:15:50.770714Z","end":"2026-05-05T22:15:50.960937Z","steps":["trace[253129359] 'process raft request' (duration: 189.652602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:51.53482Z","caller":"traceutil/trace.go:171","msg":"trace[1042647823] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"201.304583ms","start":"2026-05-05T22:15:51.333492Z","end":"2026-05-05T22:15:51.534796Z","steps":["trace[1042647823] 'read index received' (duration: 201.242402ms)","trace[1042647823] 'applied index is now lower than readState.Index' (duration: 61.131µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:51.534897Z","caller":"traceutil/trace.go:171","msg":"trace[1084567527] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"343.478995ms","start":"2026-05-05T22:15:51.191391Z","end":"2026-05-05T22:15:51.53487Z","steps":["trace[1084567527] 'process raft request' (duration: 343.268331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:51.534971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.468136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:51.535004Z","caller":"traceutil/trace.go:171","msg":"trace[1127436546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"201.546029ms","start":"2026-05-05T22:15:51.333448Z","end":"2026-05-05T22:15:51.534994Z","steps":["trace[1127436546] 'agreement among raft nodes before linearized reading' (duration: 201.456056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:51.535033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:51.191367Z","time spent":"343.590347ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:15:51.639025Z","caller":"traceutil/trace.go:171","msg":"trace[209947708] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:705; }","duration":"104.11952ms","start":"2026-05-05T22:15:51.534884Z","end":"2026-05-05T22:15:51.639004Z","steps":["trace[209947708] 'read index received' (duration: 20.94047ms)","trace[209947708] 'applied index is now lower than readState.Index' (duration: 83.17774ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:51.639225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.253979ms","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-05-05T22:15:51.639261Z","caller":"traceutil/trace.go:171","msg":"trace[1614660288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"236.32461ms","start":"2026-05-05T22:15:51.402927Z","end":"2026-05-05T22:15:51.639252Z","steps":["trace[1614660288] 'agreement among raft nodes before linearized reading' (duration: 236.150137ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:51.869659Z","caller":"traceutil/trace.go:171","msg":"trace[339621592] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"118.266228ms","start":"2026-05-05T22:15:51.751366Z","end":"2026-05-05T22:15:51.869633Z","steps":["trace[339621592] 'process raft request' (duration: 103.493329ms)","trace[339621592] 'compare' (duration: 14.628305ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:52.362672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.875295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.362723Z","caller":"traceutil/trace.go:171","msg":"trace[1459195971] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:684; }","duration":"250.999307ms","start":"2026-05-05T22:15:52.111712Z","end":"2026-05-05T22:15:52.362712Z","steps":["trace[1459195971] 'range keys from in-memory index tree' (duration: 250.696291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.362897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.205784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:52.362965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.790952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.363014Z","caller":"traceutil/trace.go:171","msg":"trace[1963000576] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:684; }","duration":"222.327296ms","start":"2026-05-05T22:15:52.14064Z","end":"2026-05-05T22:15:52.362967Z","steps":["trace[1963000576] 'range keys from in-memory index tree' (duration: 222.145373ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:52.363056Z","caller":"traceutil/trace.go:171","msg":"trace[1916216759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:684; }","duration":"370.829692ms","start":"2026-05-05T22:15:51.992154Z","end":"2026-05-05T22:15:52.362984Z","steps":["trace[1916216759] 'count revisions from in-memory index tree' (duration: 370.764731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:51.992151Z","time spent":"370.955094ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:52.362911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.620697ms","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":"warn","ts":"2026-05-05T22:15:52.363362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.450743ms","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":"info","ts":"2026-05-05T22:15:52.363395Z","caller":"traceutil/trace.go:171","msg":"trace[1330905683] 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:684; }","duration":"349.491084ms","start":"2026-05-05T22:15:52.013888Z","end":"2026-05-05T22:15:52.363379Z","steps":["trace[1330905683] 'range keys from in-memory index tree' (duration: 349.413073ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:52.363437Z","caller":"traceutil/trace.go:171","msg":"trace[1266139282] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:684; }","duration":"198.106206ms","start":"2026-05-05T22:15:52.165259Z","end":"2026-05-05T22:15:52.363365Z","steps":["trace[1266139282] 'range keys from in-memory index tree' (duration: 197.585986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.212516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:52.363526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.399881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.363555Z","caller":"traceutil/trace.go:171","msg":"trace[1379243705] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:684; }","duration":"253.259478ms","start":"2026-05-05T22:15:52.110284Z","end":"2026-05-05T22:15:52.363544Z","steps":["trace[1379243705] 'count revisions from in-memory index tree' (duration: 253.174606ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:52.36357Z","caller":"traceutil/trace.go:171","msg":"trace[1262524794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:684; }","duration":"301.476112ms","start":"2026-05-05T22:15:52.062081Z","end":"2026-05-05T22:15:52.363557Z","steps":["trace[1262524794] 'range keys from in-memory index tree' (duration: 301.35285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.06207Z","time spent":"301.532414ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:52.36332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.203149ms","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":"info","ts":"2026-05-05T22:15:52.363662Z","caller":"traceutil/trace.go:171","msg":"trace[1053470228] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:684; }","duration":"371.562355ms","start":"2026-05-05T22:15:51.992084Z","end":"2026-05-05T22:15:52.363647Z","steps":["trace[1053470228] 'range keys from in-memory index tree' (duration: 371.101737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.429181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:52.363718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:51.992069Z","time spent":"371.636066ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-05-05T22:15:52.36378Z","caller":"traceutil/trace.go:171","msg":"trace[1042784259] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:684; }","duration":"253.485021ms","start":"2026-05-05T22:15:52.110255Z","end":"2026-05-05T22:15:52.36374Z","steps":["trace[1042784259] 'range keys from in-memory index tree' (duration: 253.350949ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.885633ms","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-05-05T22:15:52.363888Z","caller":"traceutil/trace.go:171","msg":"trace[1704396161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:684; }","duration":"371.401583ms","start":"2026-05-05T22:15:51.992442Z","end":"2026-05-05T22:15:52.363844Z","steps":["trace[1704396161] 'range keys from in-memory index tree' (duration: 370.860632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:51.992436Z","time spent":"371.485274ms","remote":"127.0.0.1:39914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:52.363437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.807785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.364026Z","caller":"traceutil/trace.go:171","msg":"trace[1075658995] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:684; }","duration":"223.418556ms","start":"2026-05-05T22:15:52.140593Z","end":"2026-05-05T22:15:52.364011Z","steps":["trace[1075658995] 'count revisions from in-memory index tree' (duration: 222.730713ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.363422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.013875Z","time spent":"349.537795ms","remote":"127.0.0.1:39922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:52.363478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.397132ms","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":"warn","ts":"2026-05-05T22:15:52.36425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.654924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.364289Z","caller":"traceutil/trace.go:171","msg":"trace[287353176] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:684; }","duration":"302.720105ms","start":"2026-05-05T22:15:52.06156Z","end":"2026-05-05T22:15:52.364281Z","steps":["trace[287353176] 'range keys from in-memory index tree' (duration: 302.618433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.364323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.061556Z","time spent":"302.759306ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-05-05T22:15:52.364243Z","caller":"traceutil/trace.go:171","msg":"trace[522252116] 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:684; }","duration":"350.172317ms","start":"2026-05-05T22:15:52.014062Z","end":"2026-05-05T22:15:52.364234Z","steps":["trace[522252116] 'count revisions from in-memory index tree' (duration: 349.372632ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.364438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.958289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:52.364472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.014058Z","time spent":"350.393951ms","remote":"127.0.0.1:39922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:15:52.364495Z","caller":"traceutil/trace.go:171","msg":"trace[1199834396] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:684; }","duration":"303.02565ms","start":"2026-05-05T22:15:52.061446Z","end":"2026-05-05T22:15:52.364471Z","steps":["trace[1199834396] 'count revisions from in-memory index tree' (duration: 302.899758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.364128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.914074ms","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":"warn","ts":"2026-05-05T22:15:52.364541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.061436Z","time spent":"303.092962ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:15:52.364549Z","caller":"traceutil/trace.go:171","msg":"trace[558649663] 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:684; }","duration":"349.552036ms","start":"2026-05-05T22:15:52.014982Z","end":"2026-05-05T22:15:52.364534Z","steps":["trace[558649663] 'range keys from in-memory index tree' (duration: 348.841183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.364603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.014971Z","time spent":"349.613737ms","remote":"127.0.0.1:39922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:52.362842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.625777ms","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":"info","ts":"2026-05-05T22:15:52.365153Z","caller":"traceutil/trace.go:171","msg":"trace[1853575221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:684; }","duration":"199.940438ms","start":"2026-05-05T22:15:52.165192Z","end":"2026-05-05T22:15:52.365132Z","steps":["trace[1853575221] 'count revisions from in-memory index tree' (duration: 197.588286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.362749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.46376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:52.36536Z","caller":"traceutil/trace.go:171","msg":"trace[501562968] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:684; }","duration":"224.102948ms","start":"2026-05-05T22:15:52.141245Z","end":"2026-05-05T22:15:52.365348Z","steps":["trace[501562968] 'range keys from in-memory index tree' (duration: 221.417009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:52.365981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.96852ms","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":"info","ts":"2026-05-05T22:15:52.366619Z","caller":"traceutil/trace.go:171","msg":"trace[704589673] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:684; }","duration":"200.636491ms","start":"2026-05-05T22:15:52.165972Z","end":"2026-05-05T22:15:52.366608Z","steps":["trace[704589673] 'range keys from in-memory index tree' (duration: 196.6112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.148152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640487863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:15:53.648475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640487863,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:15:53.70609Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.074909394s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T22:15:53.706535Z","caller":"traceutil/trace.go:171","msg":"trace[1015934963] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"1.059370981s","start":"2026-05-05T22:15:52.647141Z","end":"2026-05-05T22:15:53.706512Z","steps":["trace[1015934963] 'read index received' (duration: 1.059147807s)","trace[1015934963] 'applied index is now lower than readState.Index' (duration: 218.754µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:53.706575Z","caller":"traceutil/trace.go:171","msg":"trace[888051660] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.075479994s","start":"2026-05-05T22:15:52.631067Z","end":"2026-05-05T22:15:53.706547Z","steps":["trace[888051660] 'process raft request' (duration: 1.075296391s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.706821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.478834ms","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-05-05T22:15:53.706867Z","caller":"traceutil/trace.go:171","msg":"trace[1371428297] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:685; }","duration":"902.571776ms","start":"2026-05-05T22:15:52.804285Z","end":"2026-05-05T22:15:53.706857Z","steps":["trace[1371428297] 'agreement among raft nodes before linearized reading' (duration: 902.506935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.706904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.804265Z","time spent":"902.629837ms","remote":"127.0.0.1:40064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:53.706986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.453547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.707129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.131531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.707223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.242925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.707261Z","caller":"traceutil/trace.go:171","msg":"trace[154412958] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:685; }","duration":"872.306907ms","start":"2026-05-05T22:15:52.834945Z","end":"2026-05-05T22:15:53.707252Z","steps":["trace[154412958] 'agreement among raft nodes before linearized reading' (duration: 872.244076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.707297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.834935Z","time spent":"872.354977ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:53.707336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.877067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.707399Z","caller":"traceutil/trace.go:171","msg":"trace[1060746892] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:685; }","duration":"937.931918ms","start":"2026-05-05T22:15:52.769432Z","end":"2026-05-05T22:15:53.707364Z","steps":["trace[1060746892] 'agreement among raft nodes before linearized reading' (duration: 937.851416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.707467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.280475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.70745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.769427Z","time spent":"938.011269ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:53.707429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.254996ms","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-05-05T22:15:53.7075Z","caller":"traceutil/trace.go:171","msg":"trace[17550165] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:685; }","duration":"888.335267ms","start":"2026-05-05T22:15:52.819156Z","end":"2026-05-05T22:15:53.707491Z","steps":["trace[17550165] 'agreement among raft nodes before linearized reading' (duration: 888.287336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.70758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.819147Z","time spent":"888.392578ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"info","ts":"2026-05-05T22:15:53.707578Z","caller":"traceutil/trace.go:171","msg":"trace[1232544803] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:685; }","duration":"920.4468ms","start":"2026-05-05T22:15:52.787099Z","end":"2026-05-05T22:15:53.707546Z","steps":["trace[1232544803] 'agreement among raft nodes before linearized reading' (duration: 920.100914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.707629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.074621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.707608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.167025ms","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-05-05T22:15:53.707685Z","caller":"traceutil/trace.go:171","msg":"trace[766430816] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:685; }","duration":"889.151752ms","start":"2026-05-05T22:15:52.818519Z","end":"2026-05-05T22:15:53.70767Z","steps":["trace[766430816] 'agreement among raft nodes before linearized reading' (duration: 889.03646ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:53.707711Z","caller":"traceutil/trace.go:171","msg":"trace[1605346369] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:685; }","duration":"904.313487ms","start":"2026-05-05T22:15:52.803369Z","end":"2026-05-05T22:15:53.707683Z","steps":["trace[1605346369] 'agreement among raft nodes before linearized reading' (duration: 904.124514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.70717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.665186ms","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-05-05T22:15:53.706992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.13299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.707774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.787085Z","time spent":"920.655473ms","remote":"127.0.0.1:40064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.707776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.803361Z","time spent":"904.403439ms","remote":"127.0.0.1:40068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-05-05T22:15:53.707801Z","caller":"traceutil/trace.go:171","msg":"trace[1442080356] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:685; }","duration":"858.968514ms","start":"2026-05-05T22:15:52.848809Z","end":"2026-05-05T22:15:53.707778Z","steps":["trace[1442080356] 'agreement among raft nodes before linearized reading' (duration: 858.086659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:53.707588Z","caller":"traceutil/trace.go:171","msg":"trace[440026845] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:685; }","duration":"857.678772ms","start":"2026-05-05T22:15:52.849456Z","end":"2026-05-05T22:15:53.707135Z","steps":["trace[440026845] 'agreement among raft nodes before linearized reading' (duration: 857.377656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.707885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.848804Z","time spent":"859.027935ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.707917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.849451Z","time spent":"858.450155ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:53.707973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.907905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.706895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.631045Z","time spent":"1.07575521s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:15:53.708053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.775484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.708077Z","caller":"traceutil/trace.go:171","msg":"trace[1080717267] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:685; }","duration":"938.995887ms","start":"2026-05-05T22:15:52.769011Z","end":"2026-05-05T22:15:53.708007Z","steps":["trace[1080717267] 'agreement among raft nodes before linearized reading' (duration: 938.839373ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:53.708105Z","caller":"traceutil/trace.go:171","msg":"trace[1809025399] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:685; }","duration":"889.855625ms","start":"2026-05-05T22:15:52.818236Z","end":"2026-05-05T22:15:53.708092Z","steps":["trace[1809025399] 'agreement among raft nodes before linearized reading' (duration: 889.779884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.818225Z","time spent":"889.913446ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.708189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.94517ms","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-05-05T22:15:53.708187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.769Z","time spent":"939.10709ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:15:53.708255Z","caller":"traceutil/trace.go:171","msg":"trace[1238022630] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:685; }","duration":"921.023671ms","start":"2026-05-05T22:15:52.787218Z","end":"2026-05-05T22:15:53.708242Z","steps":["trace[1238022630] 'agreement among raft nodes before linearized reading' (duration: 920.917189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:53.707253Z","caller":"traceutil/trace.go:171","msg":"trace[968496800] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:685; }","duration":"938.231193ms","start":"2026-05-05T22:15:52.768975Z","end":"2026-05-05T22:15:53.707206Z","steps":["trace[968496800] 'agreement among raft nodes before linearized reading' (duration: 938.126761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.609422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.708459Z","caller":"traceutil/trace.go:171","msg":"trace[117928643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"374.688923ms","start":"2026-05-05T22:15:53.33376Z","end":"2026-05-05T22:15:53.708449Z","steps":["trace[117928643] 'agreement among raft nodes before linearized reading' (duration: 374.628772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.787215Z","time spent":"921.220564ms","remote":"127.0.0.1:40064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:53.707381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.362839ms","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-05-05T22:15:53.707786Z","caller":"traceutil/trace.go:171","msg":"trace[1998148651] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:685; }","duration":"904.373179ms","start":"2026-05-05T22:15:52.803399Z","end":"2026-05-05T22:15:53.707772Z","steps":["trace[1998148651] 'agreement among raft nodes before linearized reading' (duration: 903.469993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.968928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:53.708632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.726413ms","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-05-05T22:15:53.70859Z","caller":"traceutil/trace.go:171","msg":"trace[1811567694] 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:685; }","duration":"953.551601ms","start":"2026-05-05T22:15:52.754981Z","end":"2026-05-05T22:15:53.708533Z","steps":["trace[1811567694] 'agreement among raft nodes before linearized reading' (duration: 952.266928ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:53.708684Z","caller":"traceutil/trace.go:171","msg":"trace[1433707848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:685; }","duration":"970.793934ms","start":"2026-05-05T22:15:52.73788Z","end":"2026-05-05T22:15:53.708674Z","steps":["trace[1433707848] 'agreement among raft nodes before linearized reading' (duration: 970.719242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.737875Z","time spent":"970.839924ms","remote":"127.0.0.1:40032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:53.708728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.0615ms","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-05-05T22:15:53.708759Z","caller":"traceutil/trace.go:171","msg":"trace[931246157] 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:685; }","duration":"954.118991ms","start":"2026-05-05T22:15:52.754632Z","end":"2026-05-05T22:15:53.708751Z","steps":["trace[931246157] 'agreement among raft nodes before linearized reading' (duration: 954.06247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.969587ms","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-05-05T22:15:53.708803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.754629Z","time spent":"954.165872ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:15:53.708815Z","caller":"traceutil/trace.go:171","msg":"trace[288011723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:685; }","duration":"971.039988ms","start":"2026-05-05T22:15:52.737761Z","end":"2026-05-05T22:15:53.708801Z","steps":["trace[288011723] 'agreement among raft nodes before linearized reading' (duration: 970.966797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.737744Z","time spent":"971.164161ms","remote":"127.0.0.1:40032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.708491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.333744Z","time spent":"374.739914ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:15:53.708954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061629002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:15:53.708991Z","caller":"traceutil/trace.go:171","msg":"trace[1319128603] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:685; }","duration":"1.061715164s","start":"2026-05-05T22:15:52.647262Z","end":"2026-05-05T22:15:53.708977Z","steps":["trace[1319128603] 'agreement among raft nodes before linearized reading' (duration: 1.059552385s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.708461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.768968Z","time spent":"939.477976ms","remote":"127.0.0.1:40054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.708798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.754967Z","time spent":"953.775785ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:53.709096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.149526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.709123Z","caller":"traceutil/trace.go:171","msg":"trace[1005106265] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:685; }","duration":"860.208286ms","start":"2026-05-05T22:15:52.848907Z","end":"2026-05-05T22:15:53.709115Z","steps":["trace[1005106265] 'agreement among raft nodes before linearized reading' (duration: 857.976976ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.709163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.848888Z","time spent":"860.268437ms","remote":"127.0.0.1:40098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:53.70864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.803388Z","time spent":"905.231005ms","remote":"127.0.0.1:40068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:15:53.707168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.92754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.709413Z","caller":"traceutil/trace.go:171","msg":"trace[1458352840] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:685; }","duration":"874.188071ms","start":"2026-05-05T22:15:52.835207Z","end":"2026-05-05T22:15:53.709395Z","steps":["trace[1458352840] 'agreement among raft nodes before linearized reading' (duration: 871.92983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.709475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.83519Z","time spent":"874.267472ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-05T22:15:53.707743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.818507Z","time spent":"889.222333ms","remote":"127.0.0.1:40082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:15:53.708666Z","caller":"traceutil/trace.go:171","msg":"trace[915280443] 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:685; }","duration":"954.049449ms","start":"2026-05-05T22:15:52.754601Z","end":"2026-05-05T22:15:53.70865Z","steps":["trace[915280443] 'agreement among raft nodes before linearized reading' (duration: 953.966838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.709647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.722849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-05T22:15:53.709858Z","caller":"traceutil/trace.go:171","msg":"trace[326280075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:685; }","duration":"170.992444ms","start":"2026-05-05T22:15:53.538852Z","end":"2026-05-05T22:15:53.709844Z","steps":["trace[326280075] 'agreement among raft nodes before linearized reading' (duration: 168.859335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.709031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.647246Z","time spent":"1.061774946s","remote":"127.0.0.1:40184","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-05-05T22:15:53.709068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.793793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:15:53.710472Z","caller":"traceutil/trace.go:171","msg":"trace[1620751176] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:685; }","duration":"875.133377ms","start":"2026-05-05T22:15:52.835251Z","end":"2026-05-05T22:15:53.710384Z","steps":["trace[1620751176] 'agreement among raft nodes before linearized reading' (duration: 871.560283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.710635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.835244Z","time spent":"875.371691ms","remote":"127.0.0.1:40088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.7098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.75459Z","time spent":"955.183631ms","remote":"127.0.0.1:40038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T22:15:53.706764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.059603127s","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-05-05T22:15:53.711446Z","caller":"traceutil/trace.go:171","msg":"trace[1655874083] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"1.064329932s","start":"2026-05-05T22:15:52.647104Z","end":"2026-05-05T22:15:53.711434Z","steps":["trace[1655874083] 'agreement among raft nodes before linearized reading' (duration: 1.059512565s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:53.714822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:52.647087Z","time spent":"1.067710892s","remote":"127.0.0.1:40184","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-05-05T22:15:54.216283Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640487866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:15:54.465998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.917259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:15:54.46629Z","caller":"traceutil/trace.go:171","msg":"trace[867311783] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:709; }","duration":"750.506445ms","start":"2026-05-05T22:15:53.715766Z","end":"2026-05-05T22:15:54.466273Z","steps":["trace[867311783] 'read index received' (duration: 604.150719ms)","trace[867311783] 'applied index is now lower than readState.Index' (duration: 146.354496ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:54.466501Z","caller":"traceutil/trace.go:171","msg":"trace[1929509702] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"750.751799ms","start":"2026-05-05T22:15:53.715739Z","end":"2026-05-05T22:15:54.46649Z","steps":["trace[1929509702] 'process raft request' (duration: 750.376222ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:54.466591Z","caller":"traceutil/trace.go:171","msg":"trace[296108184] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"750.970743ms","start":"2026-05-05T22:15:53.715573Z","end":"2026-05-05T22:15:54.466544Z","steps":["trace[296108184] 'process raft request' (duration: 604.327641ms)","trace[296108184] 'compare' (duration: 145.226136ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:54.466728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.941943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:15:54.46675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.71556Z","time spent":"751.106276ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:15:54.466737Z","caller":"traceutil/trace.go:171","msg":"trace[1786997619] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"749.954274ms","start":"2026-05-05T22:15:53.716747Z","end":"2026-05-05T22:15:54.466701Z","steps":["trace[1786997619] 'process raft request' (duration: 749.482656ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:54.466795Z","caller":"traceutil/trace.go:171","msg":"trace[1207989410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"751.036004ms","start":"2026-05-05T22:15:53.715742Z","end":"2026-05-05T22:15:54.466778Z","steps":["trace[1207989410] 'agreement among raft nodes before linearized reading' (duration: 750.926092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:54.46684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.715734Z","time spent":"751.092887ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:15:54.467069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.546406ms","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-05-05T22:15:54.467123Z","caller":"traceutil/trace.go:171","msg":"trace[1133322713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:688; }","duration":"723.627108ms","start":"2026-05-05T22:15:53.743484Z","end":"2026-05-05T22:15:54.467111Z","steps":["trace[1133322713] 'agreement among raft nodes before linearized reading' (duration: 723.443514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:15:54.467156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.743471Z","time spent":"723.677728ms","remote":"127.0.0.1:40184","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-05-05T22:15:54.466576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.715729Z","time spent":"750.802581ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:15:54.467421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:15:53.716731Z","time spent":"750.595796ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:11.405696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.735389ms","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-05-05T22:16:11.405802Z","caller":"traceutil/trace.go:171","msg":"trace[896088347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:800; }","duration":"862.898603ms","start":"2026-05-05T22:16:10.542879Z","end":"2026-05-05T22:16:11.405778Z","steps":["trace[896088347] 'range keys from in-memory index tree' (duration: 862.549025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.405856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.542865Z","time spent":"862.975314ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:16:11.405706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071603706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:11.406882Z","caller":"traceutil/trace.go:171","msg":"trace[2141454729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"1.072844718s","start":"2026-05-05T22:16:10.334013Z","end":"2026-05-05T22:16:11.406858Z","steps":["trace[2141454729] 'range keys from in-memory index tree' (duration: 1.071479953s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.406952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.333983Z","time spent":"1.072953621s","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:16:11.406162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.458437ms","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-05-05T22:16:11.407046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.803283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:16:11.40714Z","caller":"traceutil/trace.go:171","msg":"trace[1315646223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"877.492107ms","start":"2026-05-05T22:16:10.52961Z","end":"2026-05-05T22:16:11.407102Z","steps":["trace[1315646223] 'range keys from in-memory index tree' (duration: 876.113451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.406432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.708902ms","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-05-05T22:16:11.407202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.529573Z","time spent":"877.607439ms","remote":"127.0.0.1:40184","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":"info","ts":"2026-05-05T22:16:11.407231Z","caller":"traceutil/trace.go:171","msg":"trace[1692031750] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:800; }","duration":"859.504308ms","start":"2026-05-05T22:16:10.547707Z","end":"2026-05-05T22:16:11.407212Z","steps":["trace[1692031750] 'range keys from in-memory index tree' (duration: 857.778124ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:11.407239Z","caller":"traceutil/trace.go:171","msg":"trace[695690421] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"539.446587ms","start":"2026-05-05T22:16:10.86776Z","end":"2026-05-05T22:16:11.407207Z","steps":["trace[695690421] 'process raft request' (duration: 422.558314ms)","trace[695690421] 'compare' (duration: 115.17253ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:11.407291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.54767Z","time spent":"859.60518ms","remote":"127.0.0.1:40184","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-05-05T22:16:11.406671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.122579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T22:16:11.407356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.867733Z","time spent":"539.568299ms","remote":"127.0.0.1:40178","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:11.40743Z","caller":"traceutil/trace.go:171","msg":"trace[1304829299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:800; }","duration":"860.946885ms","start":"2026-05-05T22:16:10.546469Z","end":"2026-05-05T22:16:11.407416Z","steps":["trace[1304829299] 'range keys from in-memory index tree' (duration: 859.351505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.40747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:10.546448Z","time spent":"861.009416ms","remote":"127.0.0.1:40184","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-05-05T22:16:11.773734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.354947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:16:11.774093Z","caller":"traceutil/trace.go:171","msg":"trace[1895566206] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"365.966978ms","start":"2026-05-05T22:16:11.408077Z","end":"2026-05-05T22:16:11.774044Z","steps":["trace[1895566206] 'read index received' (duration: 123.327564ms)","trace[1895566206] 'applied index is now lower than readState.Index' (duration: 242.634674ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:11.774267Z","caller":"traceutil/trace.go:171","msg":"trace[2027088278] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"361.904051ms","start":"2026-05-05T22:16:11.412333Z","end":"2026-05-05T22:16:11.774237Z","steps":["trace[2027088278] 'process raft request' (duration: 361.590866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.7743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.177371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:11.774395Z","caller":"traceutil/trace.go:171","msg":"trace[783593037] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:802; }","duration":"366.323904ms","start":"2026-05-05T22:16:11.408049Z","end":"2026-05-05T22:16:11.774373Z","steps":["trace[783593037] 'agreement among raft nodes before linearized reading' (duration: 366.09672ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.774454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:11.412313Z","time spent":"362.020483ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:11.77446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:11.408028Z","time spent":"366.420466ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:16:11.774605Z","caller":"traceutil/trace.go:171","msg":"trace[791152749] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"361.381021ms","start":"2026-05-05T22:16:11.4132Z","end":"2026-05-05T22:16:11.774581Z","steps":["trace[791152749] 'process raft request' (duration: 361.205817ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:11.774657Z","caller":"traceutil/trace.go:171","msg":"trace[1393621038] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"359.420344ms","start":"2026-05-05T22:16:11.41522Z","end":"2026-05-05T22:16:11.77464Z","steps":["trace[1393621038] 'process raft request' (duration: 359.315582ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:11.774654Z","caller":"traceutil/trace.go:171","msg":"trace[2023946934] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"362.222267ms","start":"2026-05-05T22:16:11.412333Z","end":"2026-05-05T22:16:11.774555Z","steps":["trace[2023946934] 'process raft request' (duration: 361.83678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:11.774808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:11.412307Z","time spent":"362.43103ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:11.774797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:11.415204Z","time spent":"359.495946ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:16:11.774832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:11.413186Z","time spent":"361.495872ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:11.930982Z","caller":"traceutil/trace.go:171","msg":"trace[762536492] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"123.359565ms","start":"2026-05-05T22:16:11.807584Z","end":"2026-05-05T22:16:11.930944Z","steps":["trace[762536492] 'process raft request' (duration: 63.997405ms)","trace[762536492] 'compare' (duration: 59.176746ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:17.991254Z","caller":"traceutil/trace.go:171","msg":"trace[1525215260] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"151.503775ms","start":"2026-05-05T22:16:17.839722Z","end":"2026-05-05T22:16:17.991226Z","steps":["trace[1525215260] 'process raft request' (duration: 113.341716ms)","trace[1525215260] 'compare' (duration: 37.849543ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:17.991289Z","caller":"traceutil/trace.go:171","msg":"trace[1910183274] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"151.412313ms","start":"2026-05-05T22:16:17.839847Z","end":"2026-05-05T22:16:17.991259Z","steps":["trace[1910183274] 'process raft request' (duration: 151.174959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:17.991505Z","caller":"traceutil/trace.go:171","msg":"trace[778898996] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"149.589519ms","start":"2026-05-05T22:16:17.841886Z","end":"2026-05-05T22:16:17.991476Z","steps":["trace[778898996] 'process raft request' (duration: 149.159201ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:46.241668Z","caller":"traceutil/trace.go:171","msg":"trace[3591298] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"118.068885ms","start":"2026-05-05T22:16:46.123569Z","end":"2026-05-05T22:16:46.241638Z","steps":["trace[3591298] 'read index received' (duration: 117.860331ms)","trace[3591298] 'applied index is now lower than readState.Index' (duration: 207.244µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:16:46.242013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.409251ms","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-05-05T22:16:46.242145Z","caller":"traceutil/trace.go:171","msg":"trace[1526824014] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1010; }","duration":"118.594735ms","start":"2026-05-05T22:16:46.123517Z","end":"2026-05-05T22:16:46.242112Z","steps":["trace[1526824014] 'agreement among raft nodes before linearized reading' (duration: 118.248618ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:46.927081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640488537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:16:47.427556Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640488537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:16:47.928481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640488537,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:16:48.28803Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449922605s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T22:16:48.289234Z","caller":"traceutil/trace.go:171","msg":"trace[1536181224] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.92253345s","start":"2026-05-05T22:16:46.366682Z","end":"2026-05-05T22:16:48.289216Z","steps":["trace[1536181224] 'process raft request' (duration: 1.921474181s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:48.289329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:46.366662Z","time spent":"1.922611771s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:48.336497Z","caller":"traceutil/trace.go:171","msg":"trace[2022517932] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1050; }","duration":"1.91034661s","start":"2026-05-05T22:16:46.426126Z","end":"2026-05-05T22:16:48.336472Z","steps":["trace[2022517932] 'read index received' (duration: 1.862070711s)","trace[2022517932] 'applied index is now lower than readState.Index' (duration: 48.274759ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:16:48.33672Z","caller":"traceutil/trace.go:171","msg":"trace[1412791900] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"1.404850324s","start":"2026-05-05T22:16:46.931775Z","end":"2026-05-05T22:16:48.336626Z","steps":["trace[1412791900] 'process raft request' (duration: 1.403072761s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:48.336817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002788988s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:16:48.336888Z","caller":"traceutil/trace.go:171","msg":"trace[1273194428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"1.002905039s","start":"2026-05-05T22:16:47.333961Z","end":"2026-05-05T22:16:48.336866Z","steps":["trace[1273194428] 'agreement among raft nodes before linearized reading' (duration: 1.002789087s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:48.336884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.910757357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T22:16:48.336974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.885670296s","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-05-05T22:16:48.336991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:46.931747Z","time spent":"1.405090999s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:16:48.337009Z","caller":"traceutil/trace.go:171","msg":"trace[1294609436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"1.910915022s","start":"2026-05-05T22:16:46.42608Z","end":"2026-05-05T22:16:48.336995Z","steps":["trace[1294609436] 'agreement among raft nodes before linearized reading' (duration: 1.910530954s)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:16:48.33702Z","caller":"traceutil/trace.go:171","msg":"trace[799523768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"1.885756507s","start":"2026-05-05T22:16:46.451249Z","end":"2026-05-05T22:16:48.337006Z","steps":["trace[799523768] 'agreement among raft nodes before linearized reading' (duration: 1.885667844s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:48.33706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:46.45123Z","time spent":"1.885822499s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:16:48.336959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:47.333945Z","time spent":"1.002999352s","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:16:48.336885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887705093s","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-05-05T22:16:48.337137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:46.426063Z","time spent":"1.911060464s","remote":"127.0.0.1:40184","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-05-05T22:16:48.337179Z","caller":"traceutil/trace.go:171","msg":"trace[1581528504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"1.888029141s","start":"2026-05-05T22:16:46.449133Z","end":"2026-05-05T22:16:48.337163Z","steps":["trace[1581528504] 'agreement among raft nodes before linearized reading' (duration: 1.887606642s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:16:48.33724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:16:46.44912Z","time spent":"1.888104142s","remote":"127.0.0.1:40184","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-05-05T22:17:10.834864Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640488727,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:17:11.046698Z","caller":"traceutil/trace.go:171","msg":"trace[623306163] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"735.76182ms","start":"2026-05-05T22:17:10.310913Z","end":"2026-05-05T22:17:11.046675Z","steps":["trace[623306163] 'process raft request' (duration: 735.596787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.046945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.310889Z","time spent":"735.878022ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:11.088501Z","caller":"traceutil/trace.go:171","msg":"trace[1884440559] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1127; }","duration":"754.059711ms","start":"2026-05-05T22:17:10.334415Z","end":"2026-05-05T22:17:11.088475Z","steps":["trace[1884440559] 'read index received' (duration: 712.180269ms)","trace[1884440559] 'applied index is now lower than readState.Index' (duration: 41.878462ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:11.088756Z","caller":"traceutil/trace.go:171","msg":"trace[527768598] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"655.852651ms","start":"2026-05-05T22:17:10.432801Z","end":"2026-05-05T22:17:11.088653Z","steps":["trace[527768598] 'process raft request' (duration: 655.544846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.432774Z","time spent":"656.021604ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:11.089038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.992138ms","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-05-05T22:17:11.089062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.934409ms","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-05-05T22:17:11.089107Z","caller":"traceutil/trace.go:171","msg":"trace[1428289610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1088; }","duration":"709.092579ms","start":"2026-05-05T22:17:10.379996Z","end":"2026-05-05T22:17:11.089088Z","steps":["trace[1428289610] 'agreement among raft nodes before linearized reading' (duration: 708.829635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.772535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:11.089216Z","caller":"traceutil/trace.go:171","msg":"trace[755354806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"754.838367ms","start":"2026-05-05T22:17:10.334369Z","end":"2026-05-05T22:17:11.089208Z","steps":["trace[755354806] 'agreement among raft nodes before linearized reading' (duration: 754.795786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.334346Z","time spent":"754.895787ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:17:11.089208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.379985Z","time spent":"709.204882ms","remote":"127.0.0.1:40184","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-05-05T22:17:11.089369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.612521ms","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-05-05T22:17:11.089437Z","caller":"traceutil/trace.go:171","msg":"trace[290705954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1088; }","duration":"650.716214ms","start":"2026-05-05T22:17:10.438708Z","end":"2026-05-05T22:17:11.089424Z","steps":["trace[290705954] 'agreement among raft nodes before linearized reading' (duration: 650.568661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.08947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.438695Z","time spent":"650.767544ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T22:17:11.08914Z","caller":"traceutil/trace.go:171","msg":"trace[1716331731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"625.065723ms","start":"2026-05-05T22:17:10.464057Z","end":"2026-05-05T22:17:11.089123Z","steps":["trace[1716331731] 'agreement among raft nodes before linearized reading' (duration: 624.820448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.526296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T22:17:11.089617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.46404Z","time spent":"625.558012ms","remote":"127.0.0.1:40184","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-05-05T22:17:11.089634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.874643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:11.089643Z","caller":"traceutil/trace.go:171","msg":"trace[115962652] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1088; }","duration":"608.678398ms","start":"2026-05-05T22:17:10.480951Z","end":"2026-05-05T22:17:11.089629Z","steps":["trace[115962652] 'agreement among raft nodes before linearized reading' (duration: 608.518915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.063796ms","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-05-05T22:17:11.089676Z","caller":"traceutil/trace.go:171","msg":"trace[927704362] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1088; }","duration":"572.926894ms","start":"2026-05-05T22:17:10.516738Z","end":"2026-05-05T22:17:11.089665Z","steps":["trace[927704362] 'agreement among raft nodes before linearized reading' (duration: 572.805042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.48094Z","time spent":"608.73322ms","remote":"127.0.0.1:40396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:17:11.089703Z","caller":"traceutil/trace.go:171","msg":"trace[1981919124] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"656.173457ms","start":"2026-05-05T22:17:10.433509Z","end":"2026-05-05T22:17:11.089683Z","steps":["trace[1981919124] 'agreement among raft nodes before linearized reading' (duration: 655.898562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.089717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.516694Z","time spent":"573.010436ms","remote":"127.0.0.1:40216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:17:11.089726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.712429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T22:17:11.089744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.433498Z","time spent":"656.237319ms","remote":"127.0.0.1:40184","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":"info","ts":"2026-05-05T22:17:11.089777Z","caller":"traceutil/trace.go:171","msg":"trace[1573005949] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1088; }","duration":"632.7802ms","start":"2026-05-05T22:17:10.45698Z","end":"2026-05-05T22:17:11.08976Z","steps":["trace[1573005949] 'agreement among raft nodes before linearized reading' (duration: 632.653467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.08981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:10.456969Z","time spent":"632.833461ms","remote":"127.0.0.1:40184","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-05-05T22:17:11.452049Z","caller":"traceutil/trace.go:171","msg":"trace[674464140] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"357.686705ms","start":"2026-05-05T22:17:11.094334Z","end":"2026-05-05T22:17:11.452021Z","steps":["trace[674464140] 'process raft request' (duration: 357.551493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.452214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:11.094316Z","time spent":"357.809707ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:11.472557Z","caller":"traceutil/trace.go:171","msg":"trace[2046523486] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"378.104525ms","start":"2026-05-05T22:17:11.094428Z","end":"2026-05-05T22:17:11.472532Z","steps":["trace[2046523486] 'process raft request' (duration: 378.032784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.472836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:11.094424Z","time spent":"378.320639ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:11.472986Z","caller":"traceutil/trace.go:171","msg":"trace[1708408036] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"377.596567ms","start":"2026-05-05T22:17:11.09538Z","end":"2026-05-05T22:17:11.472977Z","steps":["trace[1708408036] 'process raft request' (duration: 377.351001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.473081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:11.095369Z","time spent":"377.658147ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:11.472586Z","caller":"traceutil/trace.go:171","msg":"trace[155446176] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"378.181847ms","start":"2026-05-05T22:17:11.094376Z","end":"2026-05-05T22:17:11.472558Z","steps":["trace[155446176] 'process raft request' (duration: 377.961513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:11.472902Z","caller":"traceutil/trace.go:171","msg":"trace[15302746] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"377.972193ms","start":"2026-05-05T22:17:11.09491Z","end":"2026-05-05T22:17:11.472883Z","steps":["trace[15302746] 'process raft request' (duration: 377.588135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:11.473353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:11.094359Z","time spent":"378.942473ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:11.473376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:11.094904Z","time spent":"378.429631ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:13.439986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.52757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:13.440123Z","caller":"traceutil/trace.go:171","msg":"trace[1546884105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"103.747255ms","start":"2026-05-05T22:17:13.336344Z","end":"2026-05-05T22:17:13.440091Z","steps":["trace[1546884105] 'range keys from in-memory index tree' (duration: 103.406438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:13.440304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.411511ms","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-05-05T22:17:13.440464Z","caller":"traceutil/trace.go:171","msg":"trace[1938934239] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1094; }","duration":"263.598805ms","start":"2026-05-05T22:17:13.176787Z","end":"2026-05-05T22:17:13.440386Z","steps":["trace[1938934239] 'count revisions from in-memory index tree' (duration: 263.216208ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:15.761155Z","caller":"traceutil/trace.go:171","msg":"trace[1828032756] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"625.961439ms","start":"2026-05-05T22:17:15.135089Z","end":"2026-05-05T22:17:15.761051Z","steps":["trace[1828032756] 'process raft request' (duration: 625.600242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.761319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.135061Z","time spent":"626.172113ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:15.761411Z","caller":"traceutil/trace.go:171","msg":"trace[651614739] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1142; }","duration":"427.9213ms","start":"2026-05-05T22:17:15.333468Z","end":"2026-05-05T22:17:15.761389Z","steps":["trace[651614739] 'read index received' (duration: 427.91071ms)","trace[651614739] 'applied index is now lower than readState.Index' (duration: 8.4µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:15.761534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.054412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:15.761607Z","caller":"traceutil/trace.go:171","msg":"trace[383865414] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1100; }","duration":"428.167394ms","start":"2026-05-05T22:17:15.33342Z","end":"2026-05-05T22:17:15.761588Z","steps":["trace[383865414] 'agreement among raft nodes before linearized reading' (duration: 428.058302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.761649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:15.333401Z","time spent":"428.241015ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:17:15.761657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.0983ms","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-05-05T22:17:15.761711Z","caller":"traceutil/trace.go:171","msg":"trace[128492962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1100; }","duration":"258.181901ms","start":"2026-05-05T22:17:15.503515Z","end":"2026-05-05T22:17:15.761697Z","steps":["trace[128492962] 'agreement among raft nodes before linearized reading' (duration: 258.010318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.761901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.842641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:17:15.761949Z","caller":"traceutil/trace.go:171","msg":"trace[1608732487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1100; }","duration":"227.912972ms","start":"2026-05-05T22:17:15.534025Z","end":"2026-05-05T22:17:15.761938Z","steps":["trace[1608732487] 'agreement among raft nodes before linearized reading' (duration: 227.79774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.762199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.546637ms","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-05-05T22:17:15.762252Z","caller":"traceutil/trace.go:171","msg":"trace[1028326794] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1100; }","duration":"248.620367ms","start":"2026-05-05T22:17:15.513619Z","end":"2026-05-05T22:17:15.762239Z","steps":["trace[1028326794] 'agreement among raft nodes before linearized reading' (duration: 248.456724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.762258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.037553ms","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-05-05T22:17:15.762339Z","caller":"traceutil/trace.go:171","msg":"trace[1433841758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1100; }","duration":"229.163725ms","start":"2026-05-05T22:17:15.533157Z","end":"2026-05-05T22:17:15.76232Z","steps":["trace[1433841758] 'agreement among raft nodes before linearized reading' (duration: 228.845429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:15.762491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.912041ms","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-05-05T22:17:15.76257Z","caller":"traceutil/trace.go:171","msg":"trace[345261997] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1100; }","duration":"229.014272ms","start":"2026-05-05T22:17:15.533542Z","end":"2026-05-05T22:17:15.762557Z","steps":["trace[345261997] 'agreement among raft nodes before linearized reading' (duration: 228.798658ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:16.022904Z","caller":"traceutil/trace.go:171","msg":"trace[1354433105] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"256.04785ms","start":"2026-05-05T22:17:15.766835Z","end":"2026-05-05T22:17:16.022883Z","steps":["trace[1354433105] 'process raft request' (duration: 255.995619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:16.023076Z","caller":"traceutil/trace.go:171","msg":"trace[336893525] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"256.232544ms","start":"2026-05-05T22:17:15.766805Z","end":"2026-05-05T22:17:16.023038Z","steps":["trace[336893525] 'process raft request' (duration: 255.99534ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:16.023298Z","caller":"traceutil/trace.go:171","msg":"trace[2009978404] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"256.026129ms","start":"2026-05-05T22:17:15.767198Z","end":"2026-05-05T22:17:16.023224Z","steps":["trace[2009978404] 'process raft request' (duration: 255.899396ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:16.023395Z","caller":"traceutil/trace.go:171","msg":"trace[394297222] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"256.599451ms","start":"2026-05-05T22:17:15.766776Z","end":"2026-05-05T22:17:16.023376Z","steps":["trace[394297222] 'process raft request' (duration: 195.509062ms)","trace[394297222] 'compare' (duration: 60.387355ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:16.023563Z","caller":"traceutil/trace.go:171","msg":"trace[1815745309] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"256.295986ms","start":"2026-05-05T22:17:15.766956Z","end":"2026-05-05T22:17:16.023251Z","steps":["trace[1815745309] 'process raft request' (duration: 255.899958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:26.526735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.665553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:26.526828Z","caller":"traceutil/trace.go:171","msg":"trace[1714103897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1191; }","duration":"195.788395ms","start":"2026-05-05T22:17:26.331022Z","end":"2026-05-05T22:17:26.526811Z","steps":["trace[1714103897] 'range keys from in-memory index tree' (duration: 195.607712ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:28.242405Z","caller":"traceutil/trace.go:171","msg":"trace[1277643294] linearizableReadLoop","detail":"{readStateIndex:1239; appliedIndex:1238; }","duration":"274.806419ms","start":"2026-05-05T22:17:27.967576Z","end":"2026-05-05T22:17:28.242383Z","steps":["trace[1277643294] 'read index received' (duration: 274.666217ms)","trace[1277643294] 'applied index is now lower than readState.Index' (duration: 138.652µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:28.242567Z","caller":"traceutil/trace.go:171","msg":"trace[193260755] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"442.868435ms","start":"2026-05-05T22:17:27.799637Z","end":"2026-05-05T22:17:28.242506Z","steps":["trace[193260755] 'process raft request' (duration: 442.600571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:28.242636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.913406ms","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-05-05T22:17:28.242681Z","caller":"traceutil/trace.go:171","msg":"trace[694899293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1192; }","duration":"153.032108ms","start":"2026-05-05T22:17:28.089636Z","end":"2026-05-05T22:17:28.242668Z","steps":["trace[694899293] 'agreement among raft nodes before linearized reading' (duration: 152.891846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:28.242669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.051154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:17:28.242766Z","caller":"traceutil/trace.go:171","msg":"trace[378666987] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1192; }","duration":"275.199877ms","start":"2026-05-05T22:17:27.967541Z","end":"2026-05-05T22:17:28.242741Z","steps":["trace[378666987] 'agreement among raft nodes before linearized reading' (duration: 274.985673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:28.242841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:27.799602Z","time spent":"443.08853ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:28.371488Z","caller":"traceutil/trace.go:171","msg":"trace[2108234873] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"122.678088ms","start":"2026-05-05T22:17:28.248782Z","end":"2026-05-05T22:17:28.37146Z","steps":["trace[2108234873] 'process raft request' (duration: 121.672709ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:28.373079Z","caller":"traceutil/trace.go:171","msg":"trace[828567757] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"124.075514ms","start":"2026-05-05T22:17:28.248971Z","end":"2026-05-05T22:17:28.373047Z","steps":["trace[828567757] 'process raft request' (duration: 123.803719ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:28.37318Z","caller":"traceutil/trace.go:171","msg":"trace[766415663] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"123.611805ms","start":"2026-05-05T22:17:28.249534Z","end":"2026-05-05T22:17:28.373145Z","steps":["trace[766415663] 'process raft request' (duration: 123.275359ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:28.373222Z","caller":"traceutil/trace.go:171","msg":"trace[2027175852] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"122.768079ms","start":"2026-05-05T22:17:28.250439Z","end":"2026-05-05T22:17:28.373207Z","steps":["trace[2027175852] 'process raft request' (duration: 122.412003ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:28.373455Z","caller":"traceutil/trace.go:171","msg":"trace[1660859497] transaction","detail":"{read_only:false; response_revision:1194; number_of_response:1; }","duration":"124.535523ms","start":"2026-05-05T22:17:28.248906Z","end":"2026-05-05T22:17:28.373441Z","steps":["trace[1660859497] 'process raft request' (duration: 123.740038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.159055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.536813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:45.159207Z","caller":"traceutil/trace.go:171","msg":"trace[1613630977] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"594.62151ms","start":"2026-05-05T22:17:44.564563Z","end":"2026-05-05T22:17:45.159184Z","steps":["trace[1613630977] 'read index received' (duration: 16.73485ms)","trace[1613630977] 'applied index is now lower than readState.Index' (duration: 577.88462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:45.159381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.958591ms","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-05-05T22:17:45.159439Z","caller":"traceutil/trace.go:171","msg":"trace[651304759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1267; }","duration":"172.037602ms","start":"2026-05-05T22:17:44.987378Z","end":"2026-05-05T22:17:45.159416Z","steps":["trace[651304759] 'agreement among raft nodes before linearized reading' (duration: 171.952951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.159479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.994767ms","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-05-05T22:17:45.159455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.872794ms","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-05-05T22:17:45.159548Z","caller":"traceutil/trace.go:171","msg":"trace[505928990] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"594.083189ms","start":"2026-05-05T22:17:44.565449Z","end":"2026-05-05T22:17:45.159532Z","steps":["trace[505928990] 'agreement among raft nodes before linearized reading' (duration: 593.885406ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:45.159561Z","caller":"traceutil/trace.go:171","msg":"trace[1844861222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"595.014048ms","start":"2026-05-05T22:17:44.564527Z","end":"2026-05-05T22:17:45.159541Z","steps":["trace[1844861222] 'agreement among raft nodes before linearized reading' (duration: 594.759983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.159766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.565446Z","time spent":"594.313894ms","remote":"127.0.0.1:40184","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-05-05T22:17:45.159784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.564513Z","time spent":"595.261792ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:17:45.159801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.239463ms","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-05-05T22:17:45.15997Z","caller":"traceutil/trace.go:171","msg":"trace[2102858214] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"636.678485ms","start":"2026-05-05T22:17:44.522614Z","end":"2026-05-05T22:17:45.159293Z","steps":["trace[2102858214] 'process raft request' (duration: 58.764745ms)","trace[2102858214] 'compare' (duration: 576.963211ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:45.16002Z","caller":"traceutil/trace.go:171","msg":"trace[666709091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1267; }","duration":"593.481838ms","start":"2026-05-05T22:17:44.56651Z","end":"2026-05-05T22:17:45.159992Z","steps":["trace[666709091] 'agreement among raft nodes before linearized reading' (duration: 592.830035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.160081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.566507Z","time spent":"593.562119ms","remote":"127.0.0.1:40184","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-05-05T22:17:45.160146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:44.522593Z","time spent":"637.487451ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:45.803417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:45.315552Z","time spent":"487.852165ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T22:17:45.803772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.721371ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:45.803925Z","caller":"traceutil/trace.go:171","msg":"trace[754591364] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"639.271043ms","start":"2026-05-05T22:17:45.16463Z","end":"2026-05-05T22:17:45.803901Z","steps":["trace[754591364] 'process raft request' (duration: 207.311948ms)","trace[754591364] 'compare' (duration: 431.600909ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:45.803989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:45.164593Z","time spent":"639.367195ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:45.860466Z","caller":"traceutil/trace.go:171","msg":"trace[1673657902] linearizableReadLoop","detail":"{readStateIndex:1320; appliedIndex:1317; }","duration":"525.549998ms","start":"2026-05-05T22:17:45.334871Z","end":"2026-05-05T22:17:45.860421Z","steps":["trace[1673657902] 'read index received' (duration: 36.949377ms)","trace[1673657902] 'applied index is now lower than readState.Index' (duration: 488.598811ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:45.860661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.761172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:45.860705Z","caller":"traceutil/trace.go:171","msg":"trace[746013021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"525.883314ms","start":"2026-05-05T22:17:45.334812Z","end":"2026-05-05T22:17:45.860695Z","steps":["trace[746013021] 'agreement among raft nodes before linearized reading' (duration: 525.769842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.860748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:45.334788Z","time spent":"525.952046ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:17:45.860765Z","caller":"traceutil/trace.go:171","msg":"trace[2010059577] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"695.695264ms","start":"2026-05-05T22:17:45.165041Z","end":"2026-05-05T22:17:45.860736Z","steps":["trace[2010059577] 'process raft request' (duration: 695.054392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.860941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:45.165014Z","time spent":"695.829827ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:45.861215Z","caller":"traceutil/trace.go:171","msg":"trace[974569776] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"696.138092ms","start":"2026-05-05T22:17:45.165057Z","end":"2026-05-05T22:17:45.861195Z","steps":["trace[974569776] 'process raft request' (duration: 695.288996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:45.861349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:45.165031Z","time spent":"696.218173ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:17:46.43744Z","caller":"traceutil/trace.go:171","msg":"trace[534423890] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"124.934001ms","start":"2026-05-05T22:17:46.312483Z","end":"2026-05-05T22:17:46.437417Z","steps":["trace[534423890] 'process raft request' (duration: 46.263325ms)","trace[534423890] 'compare' (duration: 78.457502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:47.310334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.036294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:47.310416Z","caller":"traceutil/trace.go:171","msg":"trace[1370289825] linearizableReadLoop","detail":"{readStateIndex:1325; appliedIndex:1324; }","duration":"673.07206ms","start":"2026-05-05T22:17:46.63733Z","end":"2026-05-05T22:17:47.310402Z","steps":["trace[1370289825] 'read index received' (duration: 235.898334ms)","trace[1370289825] 'applied index is now lower than readState.Index' (duration: 437.172756ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:47.310493Z","caller":"traceutil/trace.go:171","msg":"trace[209315149] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"811.181263ms","start":"2026-05-05T22:17:46.499304Z","end":"2026-05-05T22:17:47.310485Z","steps":["trace[209315149] 'process raft request' (duration: 373.908615ms)","trace[209315149] 'compare' (duration: 436.785279ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:47.310531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:46.499291Z","time spent":"811.219475ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:47.310694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.375517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:47.310714Z","caller":"traceutil/trace.go:171","msg":"trace[2144249897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1274; }","duration":"673.463748ms","start":"2026-05-05T22:17:46.637243Z","end":"2026-05-05T22:17:47.310706Z","steps":["trace[2144249897] 'agreement among raft nodes before linearized reading' (duration: 673.418477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:47.310731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:46.637194Z","time spent":"673.53299ms","remote":"127.0.0.1:39932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:17:47.310831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.647692ms","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-05-05T22:17:47.310848Z","caller":"traceutil/trace.go:171","msg":"trace[132693307] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1274; }","duration":"429.708794ms","start":"2026-05-05T22:17:46.881135Z","end":"2026-05-05T22:17:47.310844Z","steps":["trace[132693307] 'agreement among raft nodes before linearized reading' (duration: 429.681543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:47.310878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:46.881111Z","time spent":"429.759754ms","remote":"127.0.0.1:40008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:17:47.311095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.80015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T22:17:47.311172Z","caller":"traceutil/trace.go:171","msg":"trace[1728023787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1274; }","duration":"146.920631ms","start":"2026-05-05T22:17:47.164232Z","end":"2026-05-05T22:17:47.311152Z","steps":["trace[1728023787] 'agreement among raft nodes before linearized reading' (duration: 146.700627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:47.953237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.310081ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:47.95333Z","caller":"traceutil/trace.go:171","msg":"trace[1536773637] linearizableReadLoop","detail":"{readStateIndex:1326; appliedIndex:1325; }","duration":"619.404044ms","start":"2026-05-05T22:17:47.333913Z","end":"2026-05-05T22:17:47.953317Z","steps":["trace[1536773637] 'read index received' (duration: 450.98897ms)","trace[1536773637] 'applied index is now lower than readState.Index' (duration: 168.413854ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:47.953399Z","caller":"traceutil/trace.go:171","msg":"trace[1782819726] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"638.51717ms","start":"2026-05-05T22:17:47.314874Z","end":"2026-05-05T22:17:47.953392Z","steps":["trace[1782819726] 'process raft request' (duration: 469.968433ms)","trace[1782819726] 'compare' (duration: 168.051586ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:47.953443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:47.314655Z","time spent":"638.768825ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:17:47.953559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.798851ms","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-05-05T22:17:47.953613Z","caller":"traceutil/trace.go:171","msg":"trace[695312163] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"144.892563ms","start":"2026-05-05T22:17:47.808704Z","end":"2026-05-05T22:17:47.953596Z","steps":["trace[695312163] 'agreement among raft nodes before linearized reading' (duration: 144.72103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:47.953676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.766091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:47.953697Z","caller":"traceutil/trace.go:171","msg":"trace[857087789] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1275; }","duration":"619.820412ms","start":"2026-05-05T22:17:47.33387Z","end":"2026-05-05T22:17:47.95369Z","steps":["trace[857087789] 'agreement among raft nodes before linearized reading' (duration: 619.785121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:47.953718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:47.333847Z","time spent":"619.864853ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:17:48.120877Z","caller":"traceutil/trace.go:171","msg":"trace[336997895] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"163.644351ms","start":"2026-05-05T22:17:47.957202Z","end":"2026-05-05T22:17:48.120846Z","steps":["trace[336997895] 'process raft request' (duration: 99.122617ms)","trace[336997895] 'compare' (duration: 64.31443ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:48.121117Z","caller":"traceutil/trace.go:171","msg":"trace[1814873549] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"163.018909ms","start":"2026-05-05T22:17:47.958085Z","end":"2026-05-05T22:17:48.121104Z","steps":["trace[1814873549] 'process raft request' (duration: 162.841666ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:48.121286Z","caller":"traceutil/trace.go:171","msg":"trace[393847203] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"163.996449ms","start":"2026-05-05T22:17:47.957278Z","end":"2026-05-05T22:17:48.121274Z","steps":["trace[393847203] 'process raft request' (duration: 163.53202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:48.466235Z","caller":"traceutil/trace.go:171","msg":"trace[2085492474] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"131.405275ms","start":"2026-05-05T22:17:48.334804Z","end":"2026-05-05T22:17:48.466209Z","steps":["trace[2085492474] 'process raft request' (duration: 131.206951ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:48.697853Z","caller":"traceutil/trace.go:171","msg":"trace[367698284] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"254.831077ms","start":"2026-05-05T22:17:48.442986Z","end":"2026-05-05T22:17:48.697817Z","steps":["trace[367698284] 'process raft request' (duration: 212.783983ms)","trace[367698284] 'compare' (duration: 41.771239ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:49.589935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.549777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:17:49.590118Z","caller":"traceutil/trace.go:171","msg":"trace[98230058] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"174.347187ms","start":"2026-05-05T22:17:49.415757Z","end":"2026-05-05T22:17:49.590104Z","steps":["trace[98230058] 'process raft request' (duration: 174.306506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:49.590454Z","caller":"traceutil/trace.go:171","msg":"trace[676368889] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"1.119682668s","start":"2026-05-05T22:17:48.470733Z","end":"2026-05-05T22:17:49.590415Z","steps":["trace[676368889] 'process raft request' (duration: 710.59592ms)","trace[676368889] 'compare' (duration: 408.233972ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:17:49.590453Z","caller":"traceutil/trace.go:171","msg":"trace[934464910] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1331; }","duration":"275.805238ms","start":"2026-05-05T22:17:49.314615Z","end":"2026-05-05T22:17:49.59042Z","steps":["trace[934464910] 'read index received' (duration: 55.161µs)","trace[934464910] 'applied index is now lower than readState.Index' (duration: 275.747017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:17:49.590499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:48.470711Z","time spent":"1.11976927s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:17:49.590599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.087591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:17:49.590629Z","caller":"traceutil/trace.go:171","msg":"trace[1777453366] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"257.148481ms","start":"2026-05-05T22:17:49.333471Z","end":"2026-05-05T22:17:49.590619Z","steps":["trace[1777453366] 'agreement among raft nodes before linearized reading' (duration: 257.08388ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:49.590619Z","caller":"traceutil/trace.go:171","msg":"trace[1596967689] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"802.365955ms","start":"2026-05-05T22:17:48.788226Z","end":"2026-05-05T22:17:49.590592Z","steps":["trace[1596967689] 'process raft request' (duration: 801.795554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:49.59067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.047373ms","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-05-05T22:17:49.590704Z","caller":"traceutil/trace.go:171","msg":"trace[1161480976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1283; }","duration":"276.134784ms","start":"2026-05-05T22:17:49.31456Z","end":"2026-05-05T22:17:49.590694Z","steps":["trace[1161480976] 'agreement among raft nodes before linearized reading' (duration: 275.986321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:49.590753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:17:48.788199Z","time spent":"802.481897ms","remote":"127.0.0.1:39996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:17:49.729895Z","caller":"traceutil/trace.go:171","msg":"trace[1872481142] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"134.378822ms","start":"2026-05-05T22:17:49.59549Z","end":"2026-05-05T22:17:49.729869Z","steps":["trace[1872481142] 'process raft request' (duration: 134.158298ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:17:49.730069Z","caller":"traceutil/trace.go:171","msg":"trace[1214027837] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"133.107119ms","start":"2026-05-05T22:17:49.59692Z","end":"2026-05-05T22:17:49.730027Z","steps":["trace[1214027837] 'process raft request' (duration: 132.901014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:50.27952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.241562ms","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-05-05T22:17:50.279703Z","caller":"traceutil/trace.go:171","msg":"trace[240431860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"154.497746ms","start":"2026-05-05T22:17:50.125185Z","end":"2026-05-05T22:17:50.279682Z","steps":["trace[240431860] 'range keys from in-memory index tree' (duration: 154.008067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:50.27954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.889385ms","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-05-05T22:17:50.27992Z","caller":"traceutil/trace.go:171","msg":"trace[1950120856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"154.281433ms","start":"2026-05-05T22:17:50.125603Z","end":"2026-05-05T22:17:50.279885Z","steps":["trace[1950120856] 'range keys from in-memory index tree' (duration: 153.719742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:17:50.279632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.418505ms","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-05-05T22:17:50.280175Z","caller":"traceutil/trace.go:171","msg":"trace[2046225256] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"154.989145ms","start":"2026-05-05T22:17:50.12516Z","end":"2026-05-05T22:17:50.280149Z","steps":["trace[2046225256] 'range keys from in-memory index tree' (duration: 154.263301ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:00.718655Z","caller":"traceutil/trace.go:171","msg":"trace[1873220425] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"382.619344ms","start":"2026-05-05T22:18:00.33595Z","end":"2026-05-05T22:18:00.718569Z","steps":["trace[1873220425] 'read index received' (duration: 382.42959ms)","trace[1873220425] 'applied index is now lower than readState.Index' (duration: 188.314µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:00.718753Z","caller":"traceutil/trace.go:171","msg":"trace[1843954996] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"680.470112ms","start":"2026-05-05T22:18:00.03823Z","end":"2026-05-05T22:18:00.7187Z","steps":["trace[1843954996] 'process raft request' (duration: 680.172597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:00.719804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.038205Z","time spent":"681.478072ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:01.664112Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640489301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:18:02.165281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114150640489301,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:18:02.260526Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.190995973s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T22:18:02.560998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.225010962s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:02.561081Z","caller":"traceutil/trace.go:171","msg":"trace[2098758769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1362; }","duration":"2.225119114s","start":"2026-05-05T22:18:00.335938Z","end":"2026-05-05T22:18:02.561057Z","steps":["trace[2098758769] 'agreement among raft nodes before linearized reading' (duration: 382.841508ms)","trace[2098758769] 'range keys from in-memory index tree' (duration: 1.842140673s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.561114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.335807Z","time spent":"2.225299846s","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T22:18:02.561046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.112616753s","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-05-05T22:18:02.561174Z","caller":"traceutil/trace.go:171","msg":"trace[1136128683] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1362; }","duration":"1.112753845s","start":"2026-05-05T22:18:01.448388Z","end":"2026-05-05T22:18:02.561142Z","steps":["trace[1136128683] 'range keys from in-memory index tree' (duration: 1.112599092s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.561301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.166682135s","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-05-05T22:18:02.562769Z","caller":"traceutil/trace.go:171","msg":"trace[235712942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1362; }","duration":"2.168168623s","start":"2026-05-05T22:18:00.39457Z","end":"2026-05-05T22:18:02.562738Z","steps":["trace[235712942] 'agreement among raft nodes before linearized reading' (duration: 324.381989ms)","trace[235712942] 'range keys from in-memory index tree' (duration: 1.842261126s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.562833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.394545Z","time spent":"2.168272625s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:18:02.562834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.98432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-05T22:18:02.561641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160704289s","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-05-05T22:18:02.563142Z","caller":"traceutil/trace.go:171","msg":"trace[105987114] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.733666357s","start":"2026-05-05T22:18:00.829458Z","end":"2026-05-05T22:18:02.563124Z","steps":["trace[105987114] 'process raft request' (duration: 1.431313191s)","trace[105987114] 'compare' (duration: 300.894499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.561716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.165002081s","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-05-05T22:18:02.563042Z","caller":"traceutil/trace.go:171","msg":"trace[270656257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1362; }","duration":"2.162056575s","start":"2026-05-05T22:18:00.400922Z","end":"2026-05-05T22:18:02.562978Z","steps":["trace[270656257] 'agreement among raft nodes before linearized reading' (duration: 318.043766ms)","trace[270656257] 'range keys from in-memory index tree' (duration: 1.842574092s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.563239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.829423Z","time spent":"1.733750048s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:18:02.563348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.400891Z","time spent":"2.162440642s","remote":"127.0.0.1:40184","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-05-05T22:18:02.56178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.105573945s","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-05-05T22:18:02.5635Z","caller":"traceutil/trace.go:171","msg":"trace[1515496975] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1362; }","duration":"2.107309158s","start":"2026-05-05T22:18:00.456155Z","end":"2026-05-05T22:18:02.563464Z","steps":["trace[1515496975] 'agreement among raft nodes before linearized reading' (duration: 262.80611ms)","trace[1515496975] 'range keys from in-memory index tree' (duration: 1.842708904s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.563538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.456133Z","time spent":"2.107394689s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-05T22:18:02.563565Z","caller":"traceutil/trace.go:171","msg":"trace[345270071] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"2.166544711s","start":"2026-05-05T22:18:00.396679Z","end":"2026-05-05T22:18:02.563223Z","steps":["trace[345270071] 'agreement among raft nodes before linearized reading' (duration: 322.298708ms)","trace[345270071] 'range keys from in-memory index tree' (duration: 1.842657893s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:18:02.564236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:00.396663Z","time spent":"2.16753254s","remote":"127.0.0.1:40184","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-05-05T22:18:02.728563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.62927ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-05T22:18:02.728988Z","caller":"traceutil/trace.go:171","msg":"trace[1077413943] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"160.756807ms","start":"2026-05-05T22:18:02.568205Z","end":"2026-05-05T22:18:02.728962Z","steps":["trace[1077413943] 'process raft request' (duration: 160.678085ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:02.729251Z","caller":"traceutil/trace.go:171","msg":"trace[656581441] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"626.032261ms","start":"2026-05-05T22:18:02.103185Z","end":"2026-05-05T22:18:02.729217Z","steps":["trace[656581441] 'process raft request' (duration: 625.51429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.729369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.565612672s","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":"info","ts":"2026-05-05T22:18:02.729421Z","caller":"traceutil/trace.go:171","msg":"trace[30541255] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1365; }","duration":"1.565715813s","start":"2026-05-05T22:18:01.163692Z","end":"2026-05-05T22:18:02.729407Z","steps":["trace[30541255] 'agreement among raft nodes before linearized reading' (duration: 1.565619061s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.729461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:01.163637Z","time spent":"1.565810455s","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:18:02.729467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:02.103152Z","time spent":"626.178303ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:18:02.729282Z","caller":"traceutil/trace.go:171","msg":"trace[326174429] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1415; }","duration":"1.565523039s","start":"2026-05-05T22:18:01.163744Z","end":"2026-05-05T22:18:02.729267Z","steps":["trace[326174429] 'read index received' (duration: 1.097041754s)","trace[326174429] 'applied index is now lower than readState.Index' (duration: 468.479585ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:18:02.782599Z","caller":"traceutil/trace.go:171","msg":"trace[517684744] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"214.128817ms","start":"2026-05-05T22:18:02.568446Z","end":"2026-05-05T22:18:02.782575Z","steps":["trace[517684744] 'process raft request' (duration: 213.870883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:02.782667Z","caller":"traceutil/trace.go:171","msg":"trace[1736834162] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"214.132787ms","start":"2026-05-05T22:18:02.568495Z","end":"2026-05-05T22:18:02.782628Z","steps":["trace[1736834162] 'process raft request' (duration: 213.989935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.782793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.853187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:02.783238Z","caller":"traceutil/trace.go:171","msg":"trace[870139422] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1368; }","duration":"882.294104ms","start":"2026-05-05T22:18:01.900867Z","end":"2026-05-05T22:18:02.783161Z","steps":["trace[870139422] 'agreement among raft nodes before linearized reading' (duration: 881.839556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.782833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.266709ms","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-05-05T22:18:02.783341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:01.900847Z","time spent":"882.475337ms","remote":"127.0.0.1:40272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:18:02.782794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.177793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:02.783636Z","caller":"traceutil/trace.go:171","msg":"trace[1916663376] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1368; }","duration":"559.039819ms","start":"2026-05-05T22:18:02.224574Z","end":"2026-05-05T22:18:02.783614Z","steps":["trace[1916663376] 'agreement among raft nodes before linearized reading' (duration: 558.074871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.783704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:02.224485Z","time spent":"559.195992ms","remote":"127.0.0.1:40204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:18:02.782867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.566017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:02.783727Z","caller":"traceutil/trace.go:171","msg":"trace[1444298179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"981.791098ms","start":"2026-05-05T22:18:01.801548Z","end":"2026-05-05T22:18:02.783339Z","steps":["trace[1444298179] 'agreement among raft nodes before linearized reading' (duration: 981.120446ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:02.783764Z","caller":"traceutil/trace.go:171","msg":"trace[744137347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"446.521135ms","start":"2026-05-05T22:18:02.337223Z","end":"2026-05-05T22:18:02.783744Z","steps":["trace[744137347] 'agreement among raft nodes before linearized reading' (duration: 445.619047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.783798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:01.801481Z","time spent":"982.296208ms","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T22:18:02.782926Z","caller":"traceutil/trace.go:171","msg":"trace[1033919834] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"214.363032ms","start":"2026-05-05T22:18:02.568538Z","end":"2026-05-05T22:18:02.782901Z","steps":["trace[1033919834] 'process raft request' (duration: 214.005416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.782938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.241736823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-05-05T22:18:02.783807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:02.337194Z","time spent":"446.601547ms","remote":"127.0.0.1:39920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T22:18:02.784018Z","caller":"traceutil/trace.go:171","msg":"trace[1943266981] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1368; }","duration":"1.242810584s","start":"2026-05-05T22:18:01.541153Z","end":"2026-05-05T22:18:02.783964Z","steps":["trace[1943266981] 'agreement among raft nodes before linearized reading' (duration: 1.241721583s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.784193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:01.541128Z","time spent":"1.243007778s","remote":"127.0.0.1:40184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T22:18:02.7829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498098139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:18:02.784277Z","caller":"traceutil/trace.go:171","msg":"trace[681643000] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1368; }","duration":"1.49972348s","start":"2026-05-05T22:18:01.284542Z","end":"2026-05-05T22:18:02.784265Z","steps":["trace[681643000] 'agreement among raft nodes before linearized reading' (duration: 1.498062649s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:02.784325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:18:01.284517Z","time spent":"1.499789711s","remote":"127.0.0.1:40034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:18:23.205303Z","caller":"traceutil/trace.go:171","msg":"trace[1393648632] transaction","detail":"{read_only:false; response_revision:1465; number_of_response:1; }","duration":"116.799703ms","start":"2026-05-05T22:18:23.08848Z","end":"2026-05-05T22:18:23.205279Z","steps":["trace[1393648632] 'process raft request' (duration: 116.64105ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:18:29.982765Z","caller":"traceutil/trace.go:171","msg":"trace[1444452023] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"117.861932ms","start":"2026-05-05T22:18:29.864876Z","end":"2026-05-05T22:18:29.982738Z","steps":["trace[1444452023] 'process raft request' (duration: 117.674619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:18:38.434839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.614635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:18:38.434948Z","caller":"traceutil/trace.go:171","msg":"trace[212137089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1531; }","duration":"100.772908ms","start":"2026-05-05T22:18:38.33415Z","end":"2026-05-05T22:18:38.434923Z","steps":["trace[212137089] 'range keys from in-memory index tree' (duration: 100.475631ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:09.792773Z","caller":"traceutil/trace.go:171","msg":"trace[324886568] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2026; }","duration":"136.324796ms","start":"2026-05-05T22:20:09.656423Z","end":"2026-05-05T22:20:09.792748Z","steps":["trace[324886568] 'read index received' (duration: 67.295587ms)","trace[324886568] 'applied index is now lower than readState.Index' (duration: 69.026019ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:20:09.793012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.446847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T22:20:09.793054Z","caller":"traceutil/trace.go:171","msg":"trace[145715013] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1948; }","duration":"118.535669ms","start":"2026-05-05T22:20:09.674508Z","end":"2026-05-05T22:20:09.793043Z","steps":["trace[145715013] 'agreement among raft nodes before linearized reading' (duration: 118.389076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:20:09.793055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.617922ms","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-05-05T22:20:09.793203Z","caller":"traceutil/trace.go:171","msg":"trace[864683129] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1948; }","duration":"136.769385ms","start":"2026-05-05T22:20:09.656369Z","end":"2026-05-05T22:20:09.793139Z","steps":["trace[864683129] 'agreement among raft nodes before linearized reading' (duration: 136.5075ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:09.792859Z","caller":"traceutil/trace.go:171","msg":"trace[1165432917] transaction","detail":"{read_only:false; response_revision:1948; number_of_response:1; }","duration":"137.286024ms","start":"2026-05-05T22:20:09.65553Z","end":"2026-05-05T22:20:09.792816Z","steps":["trace[1165432917] 'process raft request' (duration: 68.175242ms)","trace[1165432917] 'compare' (duration: 68.918488ms)"],"step_count":2}