{"level":"warn","ts":"2026-05-29T05:53:25.253022Z","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-29T05:53:25.253174Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.207: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.19.213.207:2380","--initial-cluster=instance=https://199.19.213.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.207: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-29T05:53:25.253273Z","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-29T05:53:25.253287Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-05-29T05:53:25.253315Z","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-29T05:53:25.254123Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"]} {"level":"info","ts":"2026-05-29T05:53:25.254237Z","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.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.207: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-29T05:53:25.258563Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.996151ms"} {"level":"info","ts":"2026-05-29T05:53:25.267578Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"520ed656383c33a4","cluster-id":"21f34f1629612ac4"} {"level":"info","ts":"2026-05-29T05:53:25.267672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T05:53:25.267699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 0"} {"level":"info","ts":"2026-05-29T05:53:25.267719Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 520ed656383c33a4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T05:53:25.267729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 1"} {"level":"info","ts":"2026-05-29T05:53:25.267784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"warn","ts":"2026-05-29T05:53:25.275543Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T05:53:25.277189Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T05:53:25.281134Z","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-29T05:53:25.284195Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"520ed656383c33a4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T05:53:25.284784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"520ed656383c33a4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T05:53:25.284972Z","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-29T05:53:25.285024Z","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-29T05:53:25.285039Z","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-29T05:53:25.286325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"info","ts":"2026-05-29T05:53:25.286491Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","added-peer-id":"520ed656383c33a4","added-peer-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-05-29T05:53:25.293149Z","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-29T05:53:25.293286Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-05-29T05:53:25.293329Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-05-29T05:53:25.293649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"520ed656383c33a4","initial-advertise-peer-urls":["https://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T05:53:25.293676Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T05:53:25.670407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T05:53:25.670477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T05:53:25.670504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgPreVoteResp from 520ed656383c33a4 at term 1"} {"level":"info","ts":"2026-05-29T05:53:25.670517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became candidate at term 2"} {"level":"info","ts":"2026-05-29T05:53:25.670523Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgVoteResp from 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-05-29T05:53:25.670533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became leader at term 2"} {"level":"info","ts":"2026-05-29T05:53:25.670543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 520ed656383c33a4 elected leader 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-05-29T05:53:25.673856Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"520ed656383c33a4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.207:2379]}","request-path":"/0/members/520ed656383c33a4/attributes","cluster-id":"21f34f1629612ac4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T05:53:25.673898Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:53:25.674111Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T05:53:25.674481Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:53:25.674627Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T05:53:25.674664Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T05:53:25.676169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T05:53:25.6763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:53:25.676435Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:53:25.676507Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T05:53:25.677647Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.207:2379"} {"level":"warn","ts":"2026-05-29T05:53:27.758728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.330821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:53:27.758848Z","caller":"traceutil/trace.go:171","msg":"trace[1421183881] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:69; }","duration":"148.499494ms","start":"2026-05-29T05:53:27.610326Z","end":"2026-05-29T05:53:27.758825Z","steps":["trace[1421183881] 'range keys from in-memory index tree' (duration: 148.165276ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:28.432581Z","caller":"traceutil/trace.go:171","msg":"trace[304810803] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"203.406996ms","start":"2026-05-29T05:53:28.22915Z","end":"2026-05-29T05:53:28.432557Z","steps":["trace[304810803] 'process raft request' (duration: 203.150451ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:28.432561Z","caller":"traceutil/trace.go:171","msg":"trace[2090601162] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"202.328832ms","start":"2026-05-29T05:53:28.230201Z","end":"2026-05-29T05:53:28.43253Z","steps":["trace[2090601162] 'read index received' (duration: 202.043745ms)","trace[2090601162] 'applied index is now lower than readState.Index' (duration: 283.217µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:28.433036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.879576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:53:28.433192Z","caller":"traceutil/trace.go:171","msg":"trace[994523169] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"150.097411ms","start":"2026-05-29T05:53:28.283056Z","end":"2026-05-29T05:53:28.433153Z","steps":["trace[994523169] 'agreement among raft nodes before linearized reading' (duration: 149.720341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:28.433246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.572192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T05:53:28.433482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.209723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:53:28.433479Z","caller":"traceutil/trace.go:171","msg":"trace[1265274161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"100.898169ms","start":"2026-05-29T05:53:28.332544Z","end":"2026-05-29T05:53:28.433443Z","steps":["trace[1265274161] 'agreement among raft nodes before linearized reading' (duration: 100.257465ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:53:28.433633Z","caller":"traceutil/trace.go:171","msg":"trace[1910990461] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:71; }","duration":"203.337585ms","start":"2026-05-29T05:53:28.230198Z","end":"2026-05-29T05:53:28.433536Z","steps":["trace[1910990461] 'agreement among raft nodes before linearized reading' (duration: 202.468925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:28.939902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721273405843510236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:53:28.947188Z","caller":"traceutil/trace.go:171","msg":"trace[514396887] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"508.237894ms","start":"2026-05-29T05:53:28.438924Z","end":"2026-05-29T05:53:28.947162Z","steps":["trace[514396887] 'process raft request' (duration: 508.098051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:28.947731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:28.438905Z","time spent":"508.369257ms","remote":"127.0.0.1:37336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T05:53:29.048793Z","caller":"traceutil/trace.go:171","msg":"trace[1531351293] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:75; }","duration":"609.325577ms","start":"2026-05-29T05:53:28.439422Z","end":"2026-05-29T05:53:29.048748Z","steps":["trace[1531351293] 'read index received' (duration: 507.63726ms)","trace[1531351293] 'applied index is now lower than readState.Index' (duration: 101.686487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:53:29.04896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.506062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T05:53:29.049002Z","caller":"traceutil/trace.go:171","msg":"trace[382642948] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:72; }","duration":"609.616965ms","start":"2026-05-29T05:53:28.439372Z","end":"2026-05-29T05:53:29.048989Z","steps":["trace[382642948] 'agreement among raft nodes before linearized reading' (duration: 609.507282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:53:29.049052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:53:28.439353Z","time spent":"609.690246ms","remote":"127.0.0.1:37318","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-05-29T05:54:03.826879Z","caller":"traceutil/trace.go:171","msg":"trace[1728636275] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"144.66116ms","start":"2026-05-29T05:54:03.682191Z","end":"2026-05-29T05:54:03.826852Z","steps":["trace[1728636275] 'process raft request' (duration: 144.021116ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.404072Z","caller":"traceutil/trace.go:171","msg":"trace[637738525] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"158.008555ms","start":"2026-05-29T05:54:06.246028Z","end":"2026-05-29T05:54:06.404036Z","steps":["trace[637738525] 'process raft request' (duration: 157.833022ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.415125Z","caller":"traceutil/trace.go:171","msg":"trace[1351158896] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"113.167478ms","start":"2026-05-29T05:54:06.301931Z","end":"2026-05-29T05:54:06.415098Z","steps":["trace[1351158896] 'process raft request' (duration: 113.122067ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.415553Z","caller":"traceutil/trace.go:171","msg":"trace[135905719] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"160.560525ms","start":"2026-05-29T05:54:06.254982Z","end":"2026-05-29T05:54:06.415542Z","steps":["trace[135905719] 'process raft request' (duration: 159.524141ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.41565Z","caller":"traceutil/trace.go:171","msg":"trace[2048964457] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"158.913968ms","start":"2026-05-29T05:54:06.256729Z","end":"2026-05-29T05:54:06.415643Z","steps":["trace[2048964457] 'process raft request' (duration: 157.944786ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.415735Z","caller":"traceutil/trace.go:171","msg":"trace[395504644] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"147.455904ms","start":"2026-05-29T05:54:06.268273Z","end":"2026-05-29T05:54:06.415729Z","steps":["trace[395504644] 'process raft request' (duration: 146.492472ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.415828Z","caller":"traceutil/trace.go:171","msg":"trace[956990161] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"146.769188ms","start":"2026-05-29T05:54:06.269052Z","end":"2026-05-29T05:54:06.415821Z","steps":["trace[956990161] 'process raft request' (duration: 145.749715ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.415906Z","caller":"traceutil/trace.go:171","msg":"trace[1122987035] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"146.752388ms","start":"2026-05-29T05:54:06.269148Z","end":"2026-05-29T05:54:06.415901Z","steps":["trace[1122987035] 'process raft request' (duration: 145.684494ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.416017Z","caller":"traceutil/trace.go:171","msg":"trace[1086649109] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"143.966634ms","start":"2026-05-29T05:54:06.272044Z","end":"2026-05-29T05:54:06.416011Z","steps":["trace[1086649109] 'process raft request' (duration: 142.830708ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.416096Z","caller":"traceutil/trace.go:171","msg":"trace[181390538] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"140.049254ms","start":"2026-05-29T05:54:06.276042Z","end":"2026-05-29T05:54:06.416091Z","steps":["trace[181390538] 'process raft request' (duration: 138.869507ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.416178Z","caller":"traceutil/trace.go:171","msg":"trace[235583717] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"136.760608ms","start":"2026-05-29T05:54:06.279411Z","end":"2026-05-29T05:54:06.416171Z","steps":["trace[235583717] 'process raft request' (duration: 135.54377ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:06.416254Z","caller":"traceutil/trace.go:171","msg":"trace[1800484159] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"134.759573ms","start":"2026-05-29T05:54:06.281488Z","end":"2026-05-29T05:54:06.416247Z","steps":["trace[1800484159] 'process raft request' (duration: 133.515504ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:10.749585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.179056ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:54:10.74975Z","caller":"traceutil/trace.go:171","msg":"trace[1854497976] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"174.868142ms","start":"2026-05-29T05:54:10.574856Z","end":"2026-05-29T05:54:10.749724Z","steps":["trace[1854497976] 'process raft request' (duration: 21.243567ms)","trace[1854497976] 'compare' (duration: 152.907509ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:54:14.265737Z","caller":"traceutil/trace.go:171","msg":"trace[909590997] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"271.44697ms","start":"2026-05-29T05:54:13.994247Z","end":"2026-05-29T05:54:14.265694Z","steps":["trace[909590997] 'process raft request' (duration: 232.939247ms)","trace[909590997] 'compare' (duration: 38.263828ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:14.266026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.419444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-spdkd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-29T05:54:14.26608Z","caller":"traceutil/trace.go:171","msg":"trace[1220932690] range","detail":"{range_begin:/registry/pods/kube-system/cilium-spdkd; range_end:; response_count:1; response_revision:647; }","duration":"188.532838ms","start":"2026-05-29T05:54:14.077536Z","end":"2026-05-29T05:54:14.266068Z","steps":["trace[1220932690] 'agreement among raft nodes before linearized reading' (duration: 188.405594ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:14.26589Z","caller":"traceutil/trace.go:171","msg":"trace[1751584006] linearizableReadLoop","detail":"{readStateIndex:672; appliedIndex:671; }","duration":"188.037475ms","start":"2026-05-29T05:54:14.077599Z","end":"2026-05-29T05:54:14.265637Z","steps":["trace[1751584006] 'read index received' (duration: 149.489201ms)","trace[1751584006] 'applied index is now lower than readState.Index' (duration: 38.546984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:14.975009Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721273405843511394,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:54:15.096405Z","caller":"traceutil/trace.go:171","msg":"trace[1994661295] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"778.820563ms","start":"2026-05-29T05:54:14.317553Z","end":"2026-05-29T05:54:15.096373Z","steps":["trace[1994661295] 'process raft request' (duration: 778.573877ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:15.09651Z","caller":"traceutil/trace.go:171","msg":"trace[694807562] linearizableReadLoop","detail":"{readStateIndex:674; appliedIndex:673; }","duration":"621.976864ms","start":"2026-05-29T05:54:14.47432Z","end":"2026-05-29T05:54:15.096297Z","steps":["trace[694807562] 'read index received' (duration: 621.592865ms)","trace[694807562] 'applied index is now lower than readState.Index' (duration: 382.469µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:54:15.096604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:14.317512Z","time spent":"779.008927ms","remote":"127.0.0.1:37180","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:54:15.096649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.303161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:54:15.096751Z","caller":"traceutil/trace.go:171","msg":"trace[21040514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"622.462965ms","start":"2026-05-29T05:54:14.474265Z","end":"2026-05-29T05:54:15.096728Z","steps":["trace[21040514] 'agreement among raft nodes before linearized reading' (duration: 622.217829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:15.096796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:14.474238Z","time spent":"622.550276ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:54:15.096993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.748416ms","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-29T05:54:15.097094Z","caller":"traceutil/trace.go:171","msg":"trace[38607297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:649; }","duration":"549.90555ms","start":"2026-05-29T05:54:14.547173Z","end":"2026-05-29T05:54:15.097078Z","steps":["trace[38607297] 'agreement among raft nodes before linearized reading' (duration: 549.501731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:15.097144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:54:14.547151Z","time spent":"549.980191ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:54:32.239544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.813778ms","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-29T05:54:32.239659Z","caller":"traceutil/trace.go:171","msg":"trace[1132187858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"200.973561ms","start":"2026-05-29T05:54:32.038647Z","end":"2026-05-29T05:54:32.23962Z","steps":["trace[1132187858] 'range keys from in-memory index tree' (duration: 200.608793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:54:32.239713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.530211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-29T05:54:32.239329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.827585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:54:32.239806Z","caller":"traceutil/trace.go:171","msg":"trace[499016314] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"200.713446ms","start":"2026-05-29T05:54:32.039071Z","end":"2026-05-29T05:54:32.239784Z","steps":["trace[499016314] 'range keys from in-memory index tree' (duration: 200.277236ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:32.239865Z","caller":"traceutil/trace.go:171","msg":"trace[1523154340] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:808; }","duration":"139.45439ms","start":"2026-05-29T05:54:32.100383Z","end":"2026-05-29T05:54:32.239838Z","steps":["trace[1523154340] 'count revisions from in-memory index tree' (duration: 138.718853ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:54:32.330248Z","caller":"traceutil/trace.go:171","msg":"trace[1366500772] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"106.76758ms","start":"2026-05-29T05:54:32.223448Z","end":"2026-05-29T05:54:32.330216Z","steps":["trace[1366500772] 'process raft request' (duration: 106.538205ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:34.506982Z","caller":"traceutil/trace.go:171","msg":"trace[651653310] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"108.171912ms","start":"2026-05-29T05:55:34.398763Z","end":"2026-05-29T05:55:34.506935Z","steps":["trace[651653310] 'process raft request' (duration: 107.824124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:34.833644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.659812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:55:34.833782Z","caller":"traceutil/trace.go:171","msg":"trace[723721690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"111.863797ms","start":"2026-05-29T05:55:34.721888Z","end":"2026-05-29T05:55:34.833752Z","steps":["trace[723721690] 'range keys from in-memory index tree' (duration: 111.365174ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:35.839205Z","caller":"traceutil/trace.go:171","msg":"trace[669502278] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1166; }","duration":"176.734107ms","start":"2026-05-29T05:55:35.662438Z","end":"2026-05-29T05:55:35.839172Z","steps":["trace[669502278] 'read index received' (duration: 176.472851ms)","trace[669502278] 'applied index is now lower than readState.Index' (duration: 260.126µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:35.839302Z","caller":"traceutil/trace.go:171","msg":"trace[1055791048] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"185.770747ms","start":"2026-05-29T05:55:35.653491Z","end":"2026-05-29T05:55:35.839261Z","steps":["trace[1055791048] 'process raft request' (duration: 185.502249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:35.839469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.490839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:55:35.8395Z","caller":"traceutil/trace.go:171","msg":"trace[1818985631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1121; }","duration":"140.598122ms","start":"2026-05-29T05:55:35.698893Z","end":"2026-05-29T05:55:35.839491Z","steps":["trace[1818985631] 'agreement among raft nodes before linearized reading' (duration: 140.443478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:35.839536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.079975ms","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-29T05:55:35.83968Z","caller":"traceutil/trace.go:171","msg":"trace[1011069502] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1121; }","duration":"177.28405ms","start":"2026-05-29T05:55:35.66237Z","end":"2026-05-29T05:55:35.839654Z","steps":["trace[1011069502] 'agreement among raft nodes before linearized reading' (duration: 176.972242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:36.240359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.816458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:55:36.240639Z","caller":"traceutil/trace.go:171","msg":"trace[2130498484] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"395.886135ms","start":"2026-05-29T05:55:35.844691Z","end":"2026-05-29T05:55:36.240577Z","steps":["trace[2130498484] 'process raft request' (duration: 395.805043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:36.240719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:35.844665Z","time spent":"396.020787ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:55:36.240688Z","caller":"traceutil/trace.go:171","msg":"trace[1527409830] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"396.12153ms","start":"2026-05-29T05:55:35.844441Z","end":"2026-05-29T05:55:36.240562Z","steps":["trace[1527409830] 'process raft request' (duration: 276.80105ms)","trace[1527409830] 'compare' (duration: 118.647984ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:55:36.240923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:35.844377Z","time spent":"396.406177ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:55:36.725764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.185845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:36.725865Z","caller":"traceutil/trace.go:171","msg":"trace[1537284962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1123; }","duration":"250.303527ms","start":"2026-05-29T05:55:36.47554Z","end":"2026-05-29T05:55:36.725843Z","steps":["trace[1537284962] 'range keys from in-memory index tree' (duration: 250.051741ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:37.086844Z","caller":"traceutil/trace.go:171","msg":"trace[1547233840] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"162.825485ms","start":"2026-05-29T05:55:36.923974Z","end":"2026-05-29T05:55:37.086799Z","steps":["trace[1547233840] 'process raft request' (duration: 162.464147ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:45.339125Z","caller":"traceutil/trace.go:171","msg":"trace[597140922] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"166.875699ms","start":"2026-05-29T05:55:45.172198Z","end":"2026-05-29T05:55:45.339073Z","steps":["trace[597140922] 'process raft request' (duration: 140.201112ms)","trace[597140922] 'compare' (duration: 26.511023ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:56.240579Z","caller":"traceutil/trace.go:171","msg":"trace[2093515524] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"268.968373ms","start":"2026-05-29T05:55:55.971587Z","end":"2026-05-29T05:55:56.240555Z","steps":["trace[2093515524] 'process raft request' (duration: 268.82905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.687315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.674952687s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:55:58.687389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.709608903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:55:58.687482Z","caller":"traceutil/trace.go:171","msg":"trace[458993766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.709766856s","start":"2026-05-29T05:55:56.977693Z","end":"2026-05-29T05:55:58.68746Z","steps":["trace[458993766] 'range keys from in-memory index tree' (duration: 1.709309535s)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:58.687477Z","caller":"traceutil/trace.go:171","msg":"trace[482400459] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1238; }","duration":"1.675147852s","start":"2026-05-29T05:55:57.012302Z","end":"2026-05-29T05:55:58.68745Z","steps":["trace[482400459] 'range keys from in-memory index tree' (duration: 1.674929226s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.687516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:56.977669Z","time spent":"1.709839838s","remote":"127.0.0.1:37250","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-29T05:55:58.68759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261085422s","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-29T05:55:58.687675Z","caller":"traceutil/trace.go:171","msg":"trace[1552681257] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1238; }","duration":"1.261238596s","start":"2026-05-29T05:55:57.426416Z","end":"2026-05-29T05:55:58.687654Z","steps":["trace[1552681257] 'range keys from in-memory index tree' (duration: 1.260842977s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.687723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:57.426365Z","time spent":"1.261348938s","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:55:58.68775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.294686703s","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-29T05:55:58.687806Z","caller":"traceutil/trace.go:171","msg":"trace[1037249368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1238; }","duration":"2.294776994s","start":"2026-05-29T05:55:56.393014Z","end":"2026-05-29T05:55:58.687791Z","steps":["trace[1037249368] 'range keys from in-memory index tree' (duration: 2.294563609s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.687838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:56.392994Z","time spent":"2.294835876s","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T05:55:58.68808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.423212194s","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-29T05:55:58.68813Z","caller":"traceutil/trace.go:171","msg":"trace[505201268] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1238; }","duration":"1.423306806s","start":"2026-05-29T05:55:57.264812Z","end":"2026-05-29T05:55:58.688118Z","steps":["trace[505201268] 'range keys from in-memory index tree' (duration: 1.422905288s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.688166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:57.264791Z","time spent":"1.423366468s","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T05:55:58.688173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.216355784s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:58.688199Z","caller":"traceutil/trace.go:171","msg":"trace[934088422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"2.216410095s","start":"2026-05-29T05:55:56.471782Z","end":"2026-05-29T05:55:58.688192Z","steps":["trace[934088422] 'range keys from in-memory index tree' (duration: 2.216307292s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.688215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:56.471763Z","time spent":"2.216447716s","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:55:58.68833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.267718117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:55:58.688346Z","caller":"traceutil/trace.go:171","msg":"trace[1772148038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1238; }","duration":"2.267758137s","start":"2026-05-29T05:55:56.420581Z","end":"2026-05-29T05:55:58.688339Z","steps":["trace[1772148038] 'range keys from in-memory index tree' (duration: 2.267646506s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.68836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:56.420567Z","time spent":"2.267788689s","remote":"127.0.0.1:37250","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-29T05:55:58.772245Z","caller":"traceutil/trace.go:171","msg":"trace[365320855] linearizableReadLoop","detail":"{readStateIndex:1291; appliedIndex:1290; }","duration":"299.098242ms","start":"2026-05-29T05:55:58.473121Z","end":"2026-05-29T05:55:58.772219Z","steps":["trace[365320855] 'read index received' (duration: 299.01252ms)","trace[365320855] 'applied index is now lower than readState.Index' (duration: 84.382µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:58.772283Z","caller":"traceutil/trace.go:171","msg":"trace[423270757] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"525.893507ms","start":"2026-05-29T05:55:58.246364Z","end":"2026-05-29T05:55:58.772258Z","steps":["trace[423270757] 'process raft request' (duration: 525.700173ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.772413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.293897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:58.772455Z","caller":"traceutil/trace.go:171","msg":"trace[1031263533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1239; }","duration":"299.39244ms","start":"2026-05-29T05:55:58.473051Z","end":"2026-05-29T05:55:58.772444Z","steps":["trace[1031263533] 'agreement among raft nodes before linearized reading' (duration: 299.311188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:58.773014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.246338Z","time spent":"526.011869ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:55:59.272801Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721273405843512767,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T05:55:59.649231Z","caller":"traceutil/trace.go:171","msg":"trace[1362440328] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"954.635528ms","start":"2026-05-29T05:55:58.694568Z","end":"2026-05-29T05:55:59.649204Z","steps":["trace[1362440328] 'process raft request' (duration: 954.431543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.649396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.694526Z","time spent":"954.783742ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:55:59.649682Z","caller":"traceutil/trace.go:171","msg":"trace[172902943] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"954.555756ms","start":"2026-05-29T05:55:58.695095Z","end":"2026-05-29T05:55:59.649651Z","steps":["trace[172902943] 'process raft request' (duration: 954.506105ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:59.64979Z","caller":"traceutil/trace.go:171","msg":"trace[793212918] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"955.108369ms","start":"2026-05-29T05:55:58.69463Z","end":"2026-05-29T05:55:59.649739Z","steps":["trace[793212918] 'process raft request' (duration: 954.531726ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:59.649882Z","caller":"traceutil/trace.go:171","msg":"trace[1945557745] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"955.15701ms","start":"2026-05-29T05:55:58.694708Z","end":"2026-05-29T05:55:59.649865Z","steps":["trace[1945557745] 'process raft request' (duration: 954.739751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.649887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.695073Z","time spent":"954.70739ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:55:59.649977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.694703Z","time spent":"955.225402ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:55:59.649971Z","caller":"traceutil/trace.go:171","msg":"trace[1726177227] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"954.854803ms","start":"2026-05-29T05:55:58.695065Z","end":"2026-05-29T05:55:59.649919Z","steps":["trace[1726177227] 'process raft request' (duration: 954.502795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.650045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.694605Z","time spent":"955.307484ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:55:59.65021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.69505Z","time spent":"955.038177ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:55:59.653487Z","caller":"traceutil/trace.go:171","msg":"trace[275452136] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1291; }","duration":"881.137582ms","start":"2026-05-29T05:55:58.772333Z","end":"2026-05-29T05:55:59.653471Z","steps":["trace[275452136] 'read index received' (duration: 876.578556ms)","trace[275452136] 'applied index is now lower than readState.Index' (duration: 4.558626ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:55:59.653559Z","caller":"traceutil/trace.go:171","msg":"trace[644460766] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"874.067769ms","start":"2026-05-29T05:55:58.779482Z","end":"2026-05-29T05:55:59.65355Z","steps":["trace[644460766] 'process raft request' (duration: 873.929845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.653605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.779463Z","time spent":"874.110669ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:55:59.653828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.043255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:55:59.653822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"872.989674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:55:59.653901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.406268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:55:59.653925Z","caller":"traceutil/trace.go:171","msg":"trace[1802857694] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1245; }","duration":"124.47055ms","start":"2026-05-29T05:55:59.529448Z","end":"2026-05-29T05:55:59.653918Z","steps":["trace[1802857694] 'agreement among raft nodes before linearized reading' (duration: 124.437949ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:55:59.653918Z","caller":"traceutil/trace.go:171","msg":"trace[1825975729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"873.157987ms","start":"2026-05-29T05:55:58.780739Z","end":"2026-05-29T05:55:59.653897Z","steps":["trace[1825975729] 'agreement among raft nodes before linearized reading' (duration: 872.87539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.654029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.780703Z","time spent":"873.312011ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:55:59.653862Z","caller":"traceutil/trace.go:171","msg":"trace[1631763046] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1245; }","duration":"901.079645ms","start":"2026-05-29T05:55:58.752773Z","end":"2026-05-29T05:55:59.653853Z","steps":["trace[1631763046] 'agreement among raft nodes before linearized reading' (duration: 901.014524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:55:59.654112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:55:58.75272Z","time spent":"901.384042ms","remote":"127.0.0.1:48320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:56:15.209473Z","caller":"traceutil/trace.go:171","msg":"trace[1464137760] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"370.115641ms","start":"2026-05-29T05:56:14.839324Z","end":"2026-05-29T05:56:15.20944Z","steps":["trace[1464137760] 'process raft request' (duration: 369.844235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:15.209692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:14.839287Z","time spent":"370.279955ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:15.72066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.009904ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-29T05:56:15.721Z","caller":"traceutil/trace.go:171","msg":"trace[1713913085] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1348; }","duration":"247.425463ms","start":"2026-05-29T05:56:15.473553Z","end":"2026-05-29T05:56:15.720979Z","steps":["trace[1713913085] 'read index received' (duration: 97.614766ms)","trace[1713913085] 'applied index is now lower than readState.Index' (duration: 149.809267ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:15.721127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.573456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:15.721155Z","caller":"traceutil/trace.go:171","msg":"trace[858440646] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"247.669418ms","start":"2026-05-29T05:56:15.473475Z","end":"2026-05-29T05:56:15.721145Z","steps":["trace[858440646] 'agreement among raft nodes before linearized reading' (duration: 247.560726ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:15.721253Z","caller":"traceutil/trace.go:171","msg":"trace[971378676] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"378.314671ms","start":"2026-05-29T05:56:15.342891Z","end":"2026-05-29T05:56:15.721205Z","steps":["trace[971378676] 'process raft request' (duration: 377.916572ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:15.721465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:15.342859Z","time spent":"378.488135ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T05:56:16.001245Z","caller":"traceutil/trace.go:171","msg":"trace[1467856588] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1353; }","duration":"153.274238ms","start":"2026-05-29T05:56:15.847936Z","end":"2026-05-29T05:56:16.001211Z","steps":["trace[1467856588] 'read index received' (duration: 153.017352ms)","trace[1467856588] 'applied index is now lower than readState.Index' (duration: 255.036µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:16.001283Z","caller":"traceutil/trace.go:171","msg":"trace[1187834706] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"209.544024ms","start":"2026-05-29T05:56:15.791692Z","end":"2026-05-29T05:56:16.001236Z","steps":["trace[1187834706] 'process raft request' (duration: 209.303448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:16.001554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.586585ms","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-29T05:56:16.001611Z","caller":"traceutil/trace.go:171","msg":"trace[965023795] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1298; }","duration":"153.722848ms","start":"2026-05-29T05:56:15.847871Z","end":"2026-05-29T05:56:16.001594Z","steps":["trace[965023795] 'agreement among raft nodes before linearized reading' (duration: 153.472492ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:21.13598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.972826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:21.136127Z","caller":"traceutil/trace.go:171","msg":"trace[497188506] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1332; }","duration":"659.18219ms","start":"2026-05-29T05:56:20.476917Z","end":"2026-05-29T05:56:21.136099Z","steps":["trace[497188506] 'count revisions from in-memory index tree' (duration: 658.831832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:21.136206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:20.476848Z","time spent":"659.331103ms","remote":"127.0.0.1:37442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:21.135939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"658.970156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:21.136636Z","caller":"traceutil/trace.go:171","msg":"trace[1046291011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"659.742643ms","start":"2026-05-29T05:56:20.47688Z","end":"2026-05-29T05:56:21.136622Z","steps":["trace[1046291011] 'range keys from in-memory index tree' (duration: 658.856703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:21.136679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:20.476848Z","time spent":"659.820464ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T05:56:22.021754Z","caller":"traceutil/trace.go:171","msg":"trace[418841722] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"186.102459ms","start":"2026-05-29T05:56:21.835615Z","end":"2026-05-29T05:56:22.021717Z","steps":["trace[418841722] 'read index received' (duration: 175.712058ms)","trace[418841722] 'applied index is now lower than readState.Index' (duration: 10.388581ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:22.022084Z","caller":"traceutil/trace.go:171","msg":"trace[1699336814] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"210.015175ms","start":"2026-05-29T05:56:21.812053Z","end":"2026-05-29T05:56:22.022068Z","steps":["trace[1699336814] 'process raft request' (duration: 199.304366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.022174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.040569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:22.022277Z","caller":"traceutil/trace.go:171","msg":"trace[935904645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1337; }","duration":"177.203232ms","start":"2026-05-29T05:56:21.845051Z","end":"2026-05-29T05:56:22.022254Z","steps":["trace[935904645] 'agreement among raft nodes before linearized reading' (duration: 177.032678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.022371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.765489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T05:56:22.022406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.789065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:56:22.022423Z","caller":"traceutil/trace.go:171","msg":"trace[1919549049] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1337; }","duration":"175.870671ms","start":"2026-05-29T05:56:21.846541Z","end":"2026-05-29T05:56:22.022411Z","steps":["trace[1919549049] 'agreement among raft nodes before linearized reading' (duration: 175.761018ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:22.022449Z","caller":"traceutil/trace.go:171","msg":"trace[84897115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1337; }","duration":"186.873107ms","start":"2026-05-29T05:56:21.835565Z","end":"2026-05-29T05:56:22.022438Z","steps":["trace[84897115] 'agreement among raft nodes before linearized reading' (duration: 186.699613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.872228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.527123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:22.872419Z","caller":"traceutil/trace.go:171","msg":"trace[1642738182] linearizableReadLoop","detail":"{readStateIndex:1398; appliedIndex:1396; }","duration":"732.540854ms","start":"2026-05-29T05:56:22.139859Z","end":"2026-05-29T05:56:22.872399Z","steps":["trace[1642738182] 'read index received' (duration: 435.779115ms)","trace[1642738182] 'applied index is now lower than readState.Index' (duration: 296.760509ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:22.872506Z","caller":"traceutil/trace.go:171","msg":"trace[955465766] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"811.116857ms","start":"2026-05-29T05:56:22.061378Z","end":"2026-05-29T05:56:22.872495Z","steps":["trace[955465766] 'process raft request' (duration: 810.963823ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:22.872465Z","caller":"traceutil/trace.go:171","msg":"trace[808387680] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"815.394506ms","start":"2026-05-29T05:56:22.057026Z","end":"2026-05-29T05:56:22.872421Z","steps":["trace[808387680] 'process raft request' (duration: 518.590757ms)","trace[808387680] 'compare' (duration: 296.243216ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:22.87256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:22.061346Z","time spent":"811.188019ms","remote":"127.0.0.1:37054","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:56:22.872674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:22.056995Z","time spent":"815.570059ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T05:56:22.872784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.768517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:56:22.872831Z","caller":"traceutil/trace.go:171","msg":"trace[1188875143] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1341; }","duration":"177.91956ms","start":"2026-05-29T05:56:22.694901Z","end":"2026-05-29T05:56:22.872821Z","steps":["trace[1188875143] 'agreement among raft nodes before linearized reading' (duration: 177.785367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.872836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.654367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-29T05:56:22.87291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.063976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:22.872938Z","caller":"traceutil/trace.go:171","msg":"trace[870966690] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1341; }","duration":"400.138078ms","start":"2026-05-29T05:56:22.472791Z","end":"2026-05-29T05:56:22.872929Z","steps":["trace[870966690] 'agreement among raft nodes before linearized reading' (duration: 400.078446ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:22.872938Z","caller":"traceutil/trace.go:171","msg":"trace[1802963206] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1341; }","duration":"701.827141ms","start":"2026-05-29T05:56:22.171089Z","end":"2026-05-29T05:56:22.872916Z","steps":["trace[1802963206] 'agreement among raft nodes before linearized reading' (duration: 701.512653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.872969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:22.472768Z","time spent":"400.196669ms","remote":"127.0.0.1:37004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T05:56:22.872989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:22.171035Z","time spent":"701.942643ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T05:56:22.873097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.232339ms","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-29T05:56:22.873155Z","caller":"traceutil/trace.go:171","msg":"trace[2132883033] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1341; }","duration":"733.329172ms","start":"2026-05-29T05:56:22.139812Z","end":"2026-05-29T05:56:22.873141Z","steps":["trace[2132883033] 'agreement among raft nodes before linearized reading' (duration: 733.141637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:22.873196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:22.139788Z","time spent":"733.400473ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T05:56:23.031619Z","caller":"traceutil/trace.go:171","msg":"trace[474477065] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"153.896022ms","start":"2026-05-29T05:56:22.87768Z","end":"2026-05-29T05:56:23.031576Z","steps":["trace[474477065] 'process raft request' (duration: 101.727481ms)","trace[474477065] 'compare' (duration: 52.009547ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:23.088752Z","caller":"traceutil/trace.go:171","msg":"trace[267993089] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"211.001247ms","start":"2026-05-29T05:56:22.877694Z","end":"2026-05-29T05:56:23.088695Z","steps":["trace[267993089] 'process raft request' (duration: 210.542177ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:23.088965Z","caller":"traceutil/trace.go:171","msg":"trace[1033275617] linearizableReadLoop","detail":"{readStateIndex:1402; appliedIndex:1398; }","duration":"210.828853ms","start":"2026-05-29T05:56:22.878079Z","end":"2026-05-29T05:56:23.088908Z","steps":["trace[1033275617] 'read index received' (duration: 101.348092ms)","trace[1033275617] 'applied index is now lower than readState.Index' (duration: 109.479691ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:23.089023Z","caller":"traceutil/trace.go:171","msg":"trace[1780030779] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"211.043848ms","start":"2026-05-29T05:56:22.87794Z","end":"2026-05-29T05:56:23.088984Z","steps":["trace[1780030779] 'process raft request' (duration: 210.905395ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:23.089027Z","caller":"traceutil/trace.go:171","msg":"trace[1134766539] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"211.099389ms","start":"2026-05-29T05:56:22.877865Z","end":"2026-05-29T05:56:23.088965Z","steps":["trace[1134766539] 'process raft request' (duration: 210.738021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:23.089156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.050538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-05-29T05:56:23.089197Z","caller":"traceutil/trace.go:171","msg":"trace[1369210252] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1345; }","duration":"211.12818ms","start":"2026-05-29T05:56:22.878056Z","end":"2026-05-29T05:56:23.089184Z","steps":["trace[1369210252] 'agreement among raft nodes before linearized reading' (duration: 210.978937ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:24.461955Z","caller":"traceutil/trace.go:171","msg":"trace[2102005131] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"403.663809ms","start":"2026-05-29T05:56:24.058254Z","end":"2026-05-29T05:56:24.461918Z","steps":["trace[2102005131] 'read index received' (duration: 403.426974ms)","trace[2102005131] 'applied index is now lower than readState.Index' (duration: 235.265µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:24.462211Z","caller":"traceutil/trace.go:171","msg":"trace[110636005] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"430.326108ms","start":"2026-05-29T05:56:24.031792Z","end":"2026-05-29T05:56:24.462118Z","steps":["trace[110636005] 'process raft request' (duration: 429.873717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:24.4625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.335502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-29T05:56:24.462559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:24.031774Z","time spent":"430.633784ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:24.462325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.051888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:56:24.462621Z","caller":"traceutil/trace.go:171","msg":"trace[1155562136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1348; }","duration":"403.496935ms","start":"2026-05-29T05:56:24.059095Z","end":"2026-05-29T05:56:24.462592Z","steps":["trace[1155562136] 'agreement among raft nodes before linearized reading' (duration: 403.117797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:24.462683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:24.059083Z","time spent":"403.589317ms","remote":"127.0.0.1:37250","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-29T05:56:24.462863Z","caller":"traceutil/trace.go:171","msg":"trace[575166742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1348; }","duration":"404.643603ms","start":"2026-05-29T05:56:24.058171Z","end":"2026-05-29T05:56:24.462815Z","steps":["trace[575166742] 'agreement among raft nodes before linearized reading' (duration: 403.929495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:24.463021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:24.058143Z","time spent":"404.858408ms","remote":"127.0.0.1:37250","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-29T05:56:24.598167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.640781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:24.5985Z","caller":"traceutil/trace.go:171","msg":"trace[508749179] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"129.908345ms","start":"2026-05-29T05:56:24.468565Z","end":"2026-05-29T05:56:24.598474Z","steps":["trace[508749179] 'process raft request' (duration: 129.803913ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T05:56:24.59854Z","caller":"traceutil/trace.go:171","msg":"trace[2134057034] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"130.451988ms","start":"2026-05-29T05:56:24.468027Z","end":"2026-05-29T05:56:24.598479Z","steps":["trace[2134057034] 'process raft request' (duration: 12.363827ms)","trace[2134057034] 'compare' (duration: 117.434746ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:26.780228Z","caller":"traceutil/trace.go:171","msg":"trace[499537371] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"144.609837ms","start":"2026-05-29T05:56:26.635569Z","end":"2026-05-29T05:56:26.780179Z","steps":["trace[499537371] 'process raft request' (duration: 105.884198ms)","trace[499537371] 'compare' (duration: 38.570305ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:28.621466Z","caller":"traceutil/trace.go:171","msg":"trace[217417872] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"434.596227ms","start":"2026-05-29T05:56:28.186837Z","end":"2026-05-29T05:56:28.621433Z","steps":["trace[217417872] 'read index received' (duration: 434.357502ms)","trace[217417872] 'applied index is now lower than readState.Index' (duration: 237.565µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:28.621575Z","caller":"traceutil/trace.go:171","msg":"trace[219766091] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"481.841384ms","start":"2026-05-29T05:56:28.139679Z","end":"2026-05-29T05:56:28.62152Z","steps":["trace[219766091] 'process raft request' (duration: 481.518527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.621704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.832143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:56:28.621807Z","caller":"traceutil/trace.go:171","msg":"trace[1196540088] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1368; }","duration":"434.998466ms","start":"2026-05-29T05:56:28.186786Z","end":"2026-05-29T05:56:28.621784Z","steps":["trace[1196540088] 'agreement among raft nodes before linearized reading' (duration: 434.790561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.621831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.139651Z","time spent":"482.038899ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:28.621836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.820731ms","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":"warn","ts":"2026-05-29T05:56:28.62185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.186765Z","time spent":"435.073958ms","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:56:28.621887Z","caller":"traceutil/trace.go:171","msg":"trace[691079139] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1368; }","duration":"412.953775ms","start":"2026-05-29T05:56:28.208915Z","end":"2026-05-29T05:56:28.621869Z","steps":["trace[691079139] 'agreement among raft nodes before linearized reading' (duration: 412.72965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.621862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.800013ms","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-29T05:56:28.621935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.208888Z","time spent":"413.033646ms","remote":"127.0.0.1:44084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"info","ts":"2026-05-29T05:56:28.62194Z","caller":"traceutil/trace.go:171","msg":"trace[1694147291] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1368; }","duration":"120.887285ms","start":"2026-05-29T05:56:28.501035Z","end":"2026-05-29T05:56:28.621922Z","steps":["trace[1694147291] 'agreement among raft nodes before linearized reading' (duration: 120.67182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:28.621922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.717891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T05:56:28.622021Z","caller":"traceutil/trace.go:171","msg":"trace[1433985309] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"148.873405ms","start":"2026-05-29T05:56:28.473121Z","end":"2026-05-29T05:56:28.621994Z","steps":["trace[1433985309] 'agreement among raft nodes before linearized reading' (duration: 148.66783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.393774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.133305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T05:56:29.393909Z","caller":"traceutil/trace.go:171","msg":"trace[360742733] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"744.236605ms","start":"2026-05-29T05:56:28.649649Z","end":"2026-05-29T05:56:29.393885Z","steps":["trace[360742733] 'read index received' (duration: 431.857874ms)","trace[360742733] 'applied index is now lower than readState.Index' (duration: 312.37676ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T05:56:29.393982Z","caller":"traceutil/trace.go:171","msg":"trace[217895671] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"767.259329ms","start":"2026-05-29T05:56:28.626674Z","end":"2026-05-29T05:56:29.393933Z","steps":["trace[217895671] 'process raft request' (duration: 454.839127ms)","trace[217895671] 'compare' (duration: 311.994362ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T05:56:29.394076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.432159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T05:56:29.394127Z","caller":"traceutil/trace.go:171","msg":"trace[709561442] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1369; }","duration":"744.541902ms","start":"2026-05-29T05:56:28.649564Z","end":"2026-05-29T05:56:29.394106Z","steps":["trace[709561442] 'agreement among raft nodes before linearized reading' (duration: 744.439359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.394155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.626652Z","time spent":"767.407263ms","remote":"127.0.0.1:37250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T05:56:29.394175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.649534Z","time spent":"744.626134ms","remote":"127.0.0.1:37466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-29T05:56:29.394334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.812479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-29T05:56:29.394433Z","caller":"traceutil/trace.go:171","msg":"trace[220960614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1369; }","duration":"682.988884ms","start":"2026-05-29T05:56:28.71142Z","end":"2026-05-29T05:56:29.394409Z","steps":["trace[220960614] 'agreement among raft nodes before linearized reading' (duration: 682.701007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.394495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.711386Z","time spent":"683.093845ms","remote":"127.0.0.1:37250","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-29T05:56:29.394508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.161771ms","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-29T05:56:29.394541Z","caller":"traceutil/trace.go:171","msg":"trace[1661666327] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1369; }","duration":"239.230162ms","start":"2026-05-29T05:56:29.1553Z","end":"2026-05-29T05:56:29.39453Z","steps":["trace[1661666327] 'agreement among raft nodes before linearized reading' (duration: 239.101409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.39465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.802043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-29T05:56:29.394667Z","caller":"traceutil/trace.go:171","msg":"trace[1864545428] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1369; }","duration":"235.862724ms","start":"2026-05-29T05:56:29.158799Z","end":"2026-05-29T05:56:29.394662Z","steps":["trace[1864545428] 'agreement among raft nodes before linearized reading' (duration: 235.807083ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.394794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.897647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-29T05:56:29.394851Z","caller":"traceutil/trace.go:171","msg":"trace[1581616874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1369; }","duration":"609.958738ms","start":"2026-05-29T05:56:28.784876Z","end":"2026-05-29T05:56:29.394835Z","steps":["trace[1581616874] 'agreement among raft nodes before linearized reading' (duration: 609.743753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T05:56:29.394905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T05:56:28.784815Z","time spent":"610.079041ms","remote":"127.0.0.1:37250","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-29T06:00:31.00946Z","caller":"traceutil/trace.go:171","msg":"trace[1061009803] transaction","detail":"{read_only:false; response_revision:2530; number_of_response:1; }","duration":"107.464608ms","start":"2026-05-29T06:00:30.90197Z","end":"2026-05-29T06:00:31.009435Z","steps":["trace[1061009803] 'process raft request' (duration: 107.371556ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T06:00:31.009539Z","caller":"traceutil/trace.go:171","msg":"trace[70749308] transaction","detail":"{read_only:false; response_revision:2529; number_of_response:1; }","duration":"107.522169ms","start":"2026-05-29T06:00:30.90197Z","end":"2026-05-29T06:00:31.009492Z","steps":["trace[70749308] 'process raft request' (duration: 16.166701ms)","trace[70749308] 'compare' (duration: 91.01ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T06:00:31.009794Z","caller":"traceutil/trace.go:171","msg":"trace[1327721693] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"107.723024ms","start":"2026-05-29T06:00:30.902057Z","end":"2026-05-29T06:00:31.00978Z","steps":["trace[1327721693] 'process raft request' (duration: 107.57347ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T06:00:31.009806Z","caller":"traceutil/trace.go:171","msg":"trace[904351627] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"107.700964ms","start":"2026-05-29T06:00:30.902081Z","end":"2026-05-29T06:00:31.009782Z","steps":["trace[904351627] 'process raft request' (duration: 107.637392ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T06:00:31.009794Z","caller":"traceutil/trace.go:171","msg":"trace[1104707099] transaction","detail":"{read_only:false; response_revision:2531; number_of_response:1; }","duration":"107.695273ms","start":"2026-05-29T06:00:30.902057Z","end":"2026-05-29T06:00:31.009752Z","steps":["trace[1104707099] 'process raft request' (duration: 107.339205ms)"],"step_count":1}