{"level":"warn","ts":"2026-05-06T00:47:31.620107Z","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-06T00:47:31.620224Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.162: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.162:2380","--initial-cluster=instance=https://199.19.213.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.162: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-06T00:47:31.620309Z","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-06T00:47:31.620326Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-05-06T00:47:31.620353Z","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-06T00:47:31.621241Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"]} {"level":"info","ts":"2026-05-06T00:47:31.621349Z","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.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.162: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-06T00:47:31.62563Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.01036ms"} {"level":"info","ts":"2026-05-06T00:47:31.633106Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8c0a14645b8502c5","cluster-id":"49fe27222e1c6db4"} {"level":"info","ts":"2026-05-06T00:47:31.633221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T00:47:31.633251Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 0"} {"level":"info","ts":"2026-05-06T00:47:31.633276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8c0a14645b8502c5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T00:47:31.633301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became follower at term 1"} {"level":"info","ts":"2026-05-06T00:47:31.633354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"warn","ts":"2026-05-06T00:47:31.646797Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T00:47:31.648152Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T00:47:31.649609Z","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-06T00:47:31.655778Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8c0a14645b8502c5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T00:47:31.656171Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8c0a14645b8502c5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T00:47:31.656325Z","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-06T00:47:31.656501Z","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-06T00:47:31.656528Z","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-06T00:47:31.659311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 switched to configuration voters=(10090900336341746373)"} {"level":"info","ts":"2026-05-06T00:47:31.659501Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","added-peer-id":"8c0a14645b8502c5","added-peer-peer-urls":["https://199.19.213.162:2380"]} {"level":"info","ts":"2026-05-06T00:47:31.667193Z","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-06T00:47:31.667312Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-05-06T00:47:31.66737Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.162:2380"} {"level":"info","ts":"2026-05-06T00:47:31.667521Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8c0a14645b8502c5","initial-advertise-peer-urls":["https://199.19.213.162:2380"],"listen-peer-urls":["https://199.19.213.162:2380"],"advertise-client-urls":["https://199.19.213.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T00:47:31.667579Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T00:47:32.333687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T00:47:32.333849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T00:47:32.333891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgPreVoteResp from 8c0a14645b8502c5 at term 1"} {"level":"info","ts":"2026-05-06T00:47:32.333918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became candidate at term 2"} {"level":"info","ts":"2026-05-06T00:47:32.33393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 received MsgVoteResp from 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-05-06T00:47:32.334044Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8c0a14645b8502c5 became leader at term 2"} {"level":"info","ts":"2026-05-06T00:47:32.334066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8c0a14645b8502c5 elected leader 8c0a14645b8502c5 at term 2"} {"level":"info","ts":"2026-05-06T00:47:32.335877Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8c0a14645b8502c5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.162:2379]}","request-path":"/0/members/8c0a14645b8502c5/attributes","cluster-id":"49fe27222e1c6db4","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T00:47:32.33612Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:32.336782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:32.337124Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T00:47:32.33906Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T00:47:32.339974Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T00:47:32.340025Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T00:47:32.340554Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49fe27222e1c6db4","local-member-id":"8c0a14645b8502c5","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:32.34067Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:32.340678Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.162:2379"} {"level":"info","ts":"2026-05-06T00:47:32.340702Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T00:47:41.266854Z","caller":"traceutil/trace.go:171","msg":"trace[848428025] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"106.502447ms","start":"2026-05-06T00:47:41.160322Z","end":"2026-05-06T00:47:41.266824Z","steps":["trace[848428025] 'process raft request' (duration: 106.298592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:41.686004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.687462ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:47:41.686204Z","caller":"traceutil/trace.go:171","msg":"trace[860266482] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:360; }","duration":"277.313814ms","start":"2026-05-06T00:47:41.408828Z","end":"2026-05-06T00:47:41.686142Z","steps":["trace[860266482] 'read index received' (duration: 20.384982ms)","trace[860266482] 'applied index is now lower than readState.Index' (duration: 256.927502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:41.686352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.513459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-06T00:47:41.686339Z","caller":"traceutil/trace.go:171","msg":"trace[1174974472] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"290.178884ms","start":"2026-05-06T00:47:41.396091Z","end":"2026-05-06T00:47:41.68627Z","steps":["trace[1174974472] 'process raft request' (duration: 33.190091ms)","trace[1174974472] 'compare' (duration: 255.541559ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:41.686407Z","caller":"traceutil/trace.go:171","msg":"trace[1059015489] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:348; }","duration":"277.585172ms","start":"2026-05-06T00:47:41.408797Z","end":"2026-05-06T00:47:41.686382Z","steps":["trace[1059015489] 'agreement among raft nodes before linearized reading' (duration: 277.478489ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:41.68656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.866087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-06T00:47:41.686716Z","caller":"traceutil/trace.go:171","msg":"trace[553396665] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:348; }","duration":"234.058532ms","start":"2026-05-06T00:47:41.452633Z","end":"2026-05-06T00:47:41.686692Z","steps":["trace[553396665] 'agreement among raft nodes before linearized reading' (duration: 233.737225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:41.686743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.832087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-06T00:47:41.686815Z","caller":"traceutil/trace.go:171","msg":"trace[1291967406] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:348; }","duration":"131.93278ms","start":"2026-05-06T00:47:41.55486Z","end":"2026-05-06T00:47:41.686793Z","steps":["trace[1291967406] 'agreement among raft nodes before linearized reading' (duration: 131.821457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:41.686917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.846968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-06T00:47:41.686947Z","caller":"traceutil/trace.go:171","msg":"trace[331968779] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:348; }","duration":"182.899489ms","start":"2026-05-06T00:47:41.50404Z","end":"2026-05-06T00:47:41.68694Z","steps":["trace[331968779] 'agreement among raft nodes before linearized reading' (duration: 182.849428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.037659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.857609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:47:42.037774Z","caller":"traceutil/trace.go:171","msg":"trace[1758858997] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"344.270678ms","start":"2026-05-06T00:47:41.693483Z","end":"2026-05-06T00:47:42.037753Z","steps":["trace[1758858997] 'process raft request' (duration: 173.237836ms)","trace[1758858997] 'compare' (duration: 170.681524ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:42.037837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.693462Z","time spent":"344.353681ms","remote":"127.0.0.1:47516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:42.038649Z","caller":"traceutil/trace.go:171","msg":"trace[1016518192] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"343.505929ms","start":"2026-05-06T00:47:41.695127Z","end":"2026-05-06T00:47:42.038633Z","steps":["trace[1016518192] 'process raft request' (duration: 343.11936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.03872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.695119Z","time spent":"343.573051ms","remote":"127.0.0.1:47950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:42.038902Z","caller":"traceutil/trace.go:171","msg":"trace[850729322] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"342.875504ms","start":"2026-05-06T00:47:41.69601Z","end":"2026-05-06T00:47:42.038886Z","steps":["trace[850729322] 'process raft request' (duration: 342.380602ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.039103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.696001Z","time spent":"343.067188ms","remote":"127.0.0.1:47700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:42.03939Z","caller":"traceutil/trace.go:171","msg":"trace[1005217044] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:362; }","duration":"103.433744ms","start":"2026-05-06T00:47:41.935933Z","end":"2026-05-06T00:47:42.039366Z","steps":["trace[1005217044] 'read index received' (duration: 102.189973ms)","trace[1005217044] 'applied index is now lower than readState.Index' (duration: 1.242721ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:42.039439Z","caller":"traceutil/trace.go:171","msg":"trace[1684245977] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"343.190103ms","start":"2026-05-06T00:47:41.696236Z","end":"2026-05-06T00:47:42.039426Z","steps":["trace[1684245977] 'process raft request' (duration: 342.381393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:42.039859Z","caller":"traceutil/trace.go:171","msg":"trace[22079718] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"342.371933ms","start":"2026-05-06T00:47:41.697476Z","end":"2026-05-06T00:47:42.039848Z","steps":["trace[22079718] 'process raft request' (duration: 341.84954ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:42.039874Z","caller":"traceutil/trace.go:171","msg":"trace[278710723] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"342.391734ms","start":"2026-05-06T00:47:41.697458Z","end":"2026-05-06T00:47:42.03985Z","steps":["trace[278710723] 'process raft request' (duration: 341.646665ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:42.039959Z","caller":"traceutil/trace.go:171","msg":"trace[1869934453] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"342.837574ms","start":"2026-05-06T00:47:41.697114Z","end":"2026-05-06T00:47:42.039951Z","steps":["trace[1869934453] 'process raft request' (duration: 341.809919ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:42.039476Z","caller":"traceutil/trace.go:171","msg":"trace[1525691203] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"343.334886ms","start":"2026-05-06T00:47:41.696133Z","end":"2026-05-06T00:47:42.039468Z","steps":["trace[1525691203] 'process raft request' (duration: 342.332701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.040114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.15645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:47:42.040241Z","caller":"traceutil/trace.go:171","msg":"trace[1125540658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:357; }","duration":"104.360825ms","start":"2026-05-06T00:47:41.935861Z","end":"2026-05-06T00:47:42.040222Z","steps":["trace[1125540658] 'agreement among raft nodes before linearized reading' (duration: 104.102729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.040316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.697457Z","time spent":"342.447575ms","remote":"127.0.0.1:47780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:47:42.040432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.696229Z","time spent":"343.422848ms","remote":"127.0.0.1:47780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:47:42.040181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.696126Z","time spent":"344.033732ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:47:42.039585Z","caller":"traceutil/trace.go:171","msg":"trace[158184268] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"343.421209ms","start":"2026-05-06T00:47:41.696156Z","end":"2026-05-06T00:47:42.039577Z","steps":["trace[158184268] 'process raft request' (duration: 342.385653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:42.04049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.697105Z","time spent":"342.881485ms","remote":"127.0.0.1:47780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:47:42.040746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.696135Z","time spent":"344.586646ms","remote":"127.0.0.1:47934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:47:42.040537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:41.69745Z","time spent":"342.490986ms","remote":"127.0.0.1:47590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:47:50.563338Z","caller":"traceutil/trace.go:171","msg":"trace[80533933] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"237.610978ms","start":"2026-05-06T00:47:50.32569Z","end":"2026-05-06T00:47:50.563301Z","steps":["trace[80533933] 'process raft request' (duration: 237.352741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:51.327474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.208019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:47:51.327679Z","caller":"traceutil/trace.go:171","msg":"trace[217014940] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"758.843321ms","start":"2026-05-06T00:47:50.568779Z","end":"2026-05-06T00:47:51.327623Z","steps":["trace[217014940] 'process raft request' (duration: 528.394256ms)","trace[217014940] 'compare' (duration: 230.041995ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:51.327711Z","caller":"traceutil/trace.go:171","msg":"trace[854238150] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"729.218336ms","start":"2026-05-06T00:47:50.598463Z","end":"2026-05-06T00:47:51.327681Z","steps":["trace[854238150] 'read index received' (duration: 498.653229ms)","trace[854238150] 'applied index is now lower than readState.Index' (duration: 230.562947ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:51.327962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:50.568736Z","time spent":"759.062477ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:47:52.523725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925248066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:52.523816Z","caller":"traceutil/trace.go:171","msg":"trace[1875924600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:418; }","duration":"1.925390939s","start":"2026-05-06T00:47:50.5984Z","end":"2026-05-06T00:47:52.523791Z","steps":["trace[1875924600] 'agreement among raft nodes before linearized reading' (duration: 729.36595ms)","trace[1875924600] 'range keys from in-memory index tree' (duration: 1.195899507s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:52.523832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196250855s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-05-06T00:47:52.523861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:50.598369Z","time spent":"1.925480302s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:47:52.523912Z","caller":"traceutil/trace.go:171","msg":"trace[2058026753] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"1.196031889s","start":"2026-05-06T00:47:51.327867Z","end":"2026-05-06T00:47:52.523899Z","steps":["trace[2058026753] 'read index received' (duration: 51.161µs)","trace[2058026753] 'applied index is now lower than readState.Index' (duration: 1.195979718s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:47:52.524039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210753854s","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-06T00:47:52.524066Z","caller":"traceutil/trace.go:171","msg":"trace[1889388427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"1.210821115s","start":"2026-05-06T00:47:51.313235Z","end":"2026-05-06T00:47:52.524056Z","steps":["trace[1889388427] 'agreement among raft nodes before linearized reading' (duration: 1.210740943s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.524095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:51.313212Z","time spent":"1.210876666s","remote":"127.0.0.1:47692","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-06T00:47:52.524189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.297609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-05-06T00:47:52.524193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.851598851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-06T00:47:52.524277Z","caller":"traceutil/trace.go:171","msg":"trace[1700290313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:418; }","duration":"339.453642ms","start":"2026-05-06T00:47:52.184801Z","end":"2026-05-06T00:47:52.524254Z","steps":["trace[1700290313] 'agreement among raft nodes before linearized reading' (duration: 339.253498ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:52.524305Z","caller":"traceutil/trace.go:171","msg":"trace[777403543] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:418; }","duration":"1.851786595s","start":"2026-05-06T00:47:50.672491Z","end":"2026-05-06T00:47:52.524277Z","steps":["trace[777403543] 'agreement among raft nodes before linearized reading' (duration: 1.851535959s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.524336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:52.184766Z","time spent":"339.561195ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:47:52.524375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:47:50.672463Z","time spent":"1.851902578s","remote":"127.0.0.1:47908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4079,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-05-06T00:47:52.727Z","caller":"traceutil/trace.go:171","msg":"trace[1219582601] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"195.647826ms","start":"2026-05-06T00:47:52.531322Z","end":"2026-05-06T00:47:52.72697Z","steps":["trace[1219582601] 'process raft request' (duration: 195.3982ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:52.729925Z","caller":"traceutil/trace.go:171","msg":"trace[839079604] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"192.000749ms","start":"2026-05-06T00:47:52.537899Z","end":"2026-05-06T00:47:52.729899Z","steps":["trace[839079604] 'read index received' (duration: 188.874604ms)","trace[839079604] 'applied index is now lower than readState.Index' (duration: 3.124935ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:47:52.730043Z","caller":"traceutil/trace.go:171","msg":"trace[1537643655] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"198.376642ms","start":"2026-05-06T00:47:52.531656Z","end":"2026-05-06T00:47:52.730032Z","steps":["trace[1537643655] 'process raft request' (duration: 198.027434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.730121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.192343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:47:52.730177Z","caller":"traceutil/trace.go:171","msg":"trace[1080185344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"192.296205ms","start":"2026-05-06T00:47:52.537866Z","end":"2026-05-06T00:47:52.730162Z","steps":["trace[1080185344] 'agreement among raft nodes before linearized reading' (duration: 192.193373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:47:52.730344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.085173ms","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-06T00:47:52.730455Z","caller":"traceutil/trace.go:171","msg":"trace[931847260] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:421; }","duration":"161.220627ms","start":"2026-05-06T00:47:52.56922Z","end":"2026-05-06T00:47:52.730441Z","steps":["trace[931847260] 'agreement among raft nodes before linearized reading' (duration: 161.013942ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:47:52.730554Z","caller":"traceutil/trace.go:171","msg":"trace[1891827773] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"186.378803ms","start":"2026-05-06T00:47:52.544161Z","end":"2026-05-06T00:47:52.730539Z","steps":["trace[1891827773] 'process raft request' (duration: 185.662476ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.521886Z","caller":"traceutil/trace.go:171","msg":"trace[1314699894] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"133.044867ms","start":"2026-05-06T00:48:05.388823Z","end":"2026-05-06T00:48:05.521868Z","steps":["trace[1314699894] 'process raft request' (duration: 132.938364ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.890084Z","caller":"traceutil/trace.go:171","msg":"trace[1549893002] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"146.251375ms","start":"2026-05-06T00:48:05.743806Z","end":"2026-05-06T00:48:05.890057Z","steps":["trace[1549893002] 'process raft request' (duration: 124.44993ms)","trace[1549893002] 'compare' (duration: 21.671902ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:05.890647Z","caller":"traceutil/trace.go:171","msg":"trace[1461807078] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"125.456254ms","start":"2026-05-06T00:48:05.765176Z","end":"2026-05-06T00:48:05.890632Z","steps":["trace[1461807078] 'process raft request' (duration: 125.162827ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.890816Z","caller":"traceutil/trace.go:171","msg":"trace[815965288] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"113.354283ms","start":"2026-05-06T00:48:05.777373Z","end":"2026-05-06T00:48:05.890727Z","steps":["trace[815965288] 'process raft request' (duration: 113.206129ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.891467Z","caller":"traceutil/trace.go:171","msg":"trace[331340338] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"122.005891ms","start":"2026-05-06T00:48:05.769437Z","end":"2026-05-06T00:48:05.891443Z","steps":["trace[331340338] 'read index received' (duration: 98.919295ms)","trace[331340338] 'applied index is now lower than readState.Index' (duration: 23.085576ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:05.891583Z","caller":"traceutil/trace.go:171","msg":"trace[486070669] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"123.071796ms","start":"2026-05-06T00:48:05.768131Z","end":"2026-05-06T00:48:05.891203Z","steps":["trace[486070669] 'process raft request' (duration: 122.38372ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.891693Z","caller":"traceutil/trace.go:171","msg":"trace[58386912] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"110.104784ms","start":"2026-05-06T00:48:05.781582Z","end":"2026-05-06T00:48:05.891686Z","steps":["trace[58386912] 'process raft request' (duration: 109.033738ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.891837Z","caller":"traceutil/trace.go:171","msg":"trace[1368157908] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"110.194796ms","start":"2026-05-06T00:48:05.781634Z","end":"2026-05-06T00:48:05.891828Z","steps":["trace[1368157908] 'process raft request' (duration: 109.032498ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.891942Z","caller":"traceutil/trace.go:171","msg":"trace[1397140839] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"102.655495ms","start":"2026-05-06T00:48:05.789279Z","end":"2026-05-06T00:48:05.891934Z","steps":["trace[1397140839] 'process raft request' (duration: 101.63502ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:05.891666Z","caller":"traceutil/trace.go:171","msg":"trace[22298151] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"100.677327ms","start":"2026-05-06T00:48:05.79098Z","end":"2026-05-06T00:48:05.891657Z","steps":["trace[22298151] 'process raft request' (duration: 100.160745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:05.892341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.857392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:6903"} {"level":"info","ts":"2026-05-06T00:48:05.892384Z","caller":"traceutil/trace.go:171","msg":"trace[1176284470] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:536; }","duration":"122.970134ms","start":"2026-05-06T00:48:05.769401Z","end":"2026-05-06T00:48:05.892372Z","steps":["trace[1176284470] 'agreement among raft nodes before linearized reading' (duration: 122.80826ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:12.061058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.753581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:12.06115Z","caller":"traceutil/trace.go:171","msg":"trace[858811448] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:621; }","duration":"130.910755ms","start":"2026-05-06T00:48:11.930219Z","end":"2026-05-06T00:48:12.06113Z","steps":["trace[858811448] 'count revisions from in-memory index tree' (duration: 130.69577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:12.061339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.017257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:12.061376Z","caller":"traceutil/trace.go:171","msg":"trace[1427669607] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:621; }","duration":"131.082829ms","start":"2026-05-06T00:48:11.930284Z","end":"2026-05-06T00:48:12.061367Z","steps":["trace[1427669607] 'range keys from in-memory index tree' (duration: 130.935996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:12.061498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.184039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:12.061532Z","caller":"traceutil/trace.go:171","msg":"trace[2025050186] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"106.22119ms","start":"2026-05-06T00:48:11.955302Z","end":"2026-05-06T00:48:12.061523Z","steps":["trace[2025050186] 'range keys from in-memory index tree' (duration: 106.130518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:12.061668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.24196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:12.0617Z","caller":"traceutil/trace.go:171","msg":"trace[679085812] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:621; }","duration":"106.283612ms","start":"2026-05-06T00:48:11.955409Z","end":"2026-05-06T00:48:12.061693Z","steps":["trace[679085812] 'count revisions from in-memory index tree' (duration: 106.21884ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:12.445293Z","caller":"traceutil/trace.go:171","msg":"trace[651792082] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"194.251883ms","start":"2026-05-06T00:48:12.251Z","end":"2026-05-06T00:48:12.445252Z","steps":["trace[651792082] 'process raft request' (duration: 188.497974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.507864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036976046s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-864sp\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-06T00:48:13.507946Z","caller":"traceutil/trace.go:171","msg":"trace[290851592] range","detail":"{range_begin:/registry/pods/kube-system/cilium-864sp; range_end:; response_count:1; response_revision:622; }","duration":"1.037102029s","start":"2026-05-06T00:48:12.470824Z","end":"2026-05-06T00:48:13.507926Z","steps":["trace[290851592] 'range keys from in-memory index tree' (duration: 1.036799772s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.507984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.470804Z","time spent":"1.037172031s","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-864sp\" "} {"level":"warn","ts":"2026-05-06T00:48:13.50855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.028595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:48:13.508586Z","caller":"traceutil/trace.go:171","msg":"trace[1515597600] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"908.732665ms","start":"2026-05-06T00:48:12.599846Z","end":"2026-05-06T00:48:13.508579Z","steps":["trace[1515597600] 'read index received' (duration: 215.532215ms)","trace[1515597600] 'applied index is now lower than readState.Index' (duration: 693.19963ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:13.508709Z","caller":"traceutil/trace.go:171","msg":"trace[1655483762] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"1.003438047s","start":"2026-05-06T00:48:12.505263Z","end":"2026-05-06T00:48:13.508701Z","steps":["trace[1655483762] 'process raft request' (duration: 310.224198ms)","trace[1655483762] 'compare' (duration: 692.648776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:13.50874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.505226Z","time spent":"1.003498999s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:48:13.508842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.999731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:13.508858Z","caller":"traceutil/trace.go:171","msg":"trace[699195365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"909.058662ms","start":"2026-05-06T00:48:12.599794Z","end":"2026-05-06T00:48:13.508853Z","steps":["trace[699195365] 'agreement among raft nodes before linearized reading' (duration: 909.011561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.508873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.599768Z","time spent":"909.101923ms","remote":"127.0.0.1:47428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:48:13.508992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.97172ms","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-06T00:48:13.509008Z","caller":"traceutil/trace.go:171","msg":"trace[1711964222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"675.031682ms","start":"2026-05-06T00:48:12.833971Z","end":"2026-05-06T00:48:13.509003Z","steps":["trace[1711964222] 'agreement among raft nodes before linearized reading' (duration: 674.97823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.509024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.83395Z","time spent":"675.069673ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:48:13.509345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.789064ms","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-06T00:48:13.509373Z","caller":"traceutil/trace.go:171","msg":"trace[1158876311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"659.838635ms","start":"2026-05-06T00:48:12.849526Z","end":"2026-05-06T00:48:13.509365Z","steps":["trace[1158876311] 'agreement among raft nodes before linearized reading' (duration: 659.760763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.509398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.84951Z","time spent":"659.878696ms","remote":"127.0.0.1:47692","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-06T00:48:13.50954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.113691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:48:13.509563Z","caller":"traceutil/trace.go:171","msg":"trace[754213511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"672.137272ms","start":"2026-05-06T00:48:12.837418Z","end":"2026-05-06T00:48:13.509555Z","steps":["trace[754213511] 'agreement among raft nodes before linearized reading' (duration: 672.06639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:13.509585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:12.837356Z","time spent":"672.223304ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:48:14.355025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.42602ms","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-06T00:48:14.355225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.2372ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:48:14.35533Z","caller":"traceutil/trace.go:171","msg":"trace[24666955] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"840.861381ms","start":"2026-05-06T00:48:13.514444Z","end":"2026-05-06T00:48:14.355306Z","steps":["trace[24666955] 'read index received' (duration: 330.498847ms)","trace[24666955] 'applied index is now lower than readState.Index' (duration: 510.361144ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:14.355315Z","caller":"traceutil/trace.go:171","msg":"trace[2007301478] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:623; }","duration":"684.762268ms","start":"2026-05-06T00:48:13.670512Z","end":"2026-05-06T00:48:14.355274Z","steps":["trace[2007301478] 'range keys from in-memory index tree' (duration: 684.359978ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:14.355376Z","caller":"traceutil/trace.go:171","msg":"trace[1351826475] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"840.993505ms","start":"2026-05-06T00:48:13.514349Z","end":"2026-05-06T00:48:14.355342Z","steps":["trace[1351826475] 'process raft request' (duration: 330.542669ms)","trace[1351826475] 'compare' (duration: 509.934853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:14.355451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.001434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-06T00:48:14.355484Z","caller":"traceutil/trace.go:171","msg":"trace[1213189300] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:624; }","duration":"841.050706ms","start":"2026-05-06T00:48:13.514422Z","end":"2026-05-06T00:48:14.355473Z","steps":["trace[1213189300] 'agreement among raft nodes before linearized reading' (duration: 840.971574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.355517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.514413Z","time spent":"841.096397ms","remote":"127.0.0.1:47624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-06T00:48:14.355534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.51433Z","time spent":"841.123997ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:14.360312Z","caller":"traceutil/trace.go:171","msg":"trace[1485733870] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"844.734055ms","start":"2026-05-06T00:48:13.515562Z","end":"2026-05-06T00:48:14.360296Z","steps":["trace[1485733870] 'process raft request' (duration: 844.622303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.360434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.515552Z","time spent":"844.814517ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:14.360316Z","caller":"traceutil/trace.go:171","msg":"trace[852408181] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"845.626466ms","start":"2026-05-06T00:48:13.514664Z","end":"2026-05-06T00:48:14.36029Z","steps":["trace[852408181] 'process raft request' (duration: 845.407281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.360522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.514639Z","time spent":"845.854332ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:14.360637Z","caller":"traceutil/trace.go:171","msg":"trace[835671718] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"833.924904ms","start":"2026-05-06T00:48:13.526701Z","end":"2026-05-06T00:48:14.360625Z","steps":["trace[835671718] 'process raft request' (duration: 833.522455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.360716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.750014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:48:14.36075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.52669Z","time spent":"834.006506ms","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:14.360767Z","caller":"traceutil/trace.go:171","msg":"trace[741913666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:627; }","duration":"761.831776ms","start":"2026-05-06T00:48:13.59892Z","end":"2026-05-06T00:48:14.360751Z","steps":["trace[741913666] 'agreement among raft nodes before linearized reading' (duration: 761.741064ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.360798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.598907Z","time spent":"761.882897ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:48:14.360832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.965759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:48:14.360967Z","caller":"traceutil/trace.go:171","msg":"trace[873807242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:627; }","duration":"806.185646ms","start":"2026-05-06T00:48:13.554754Z","end":"2026-05-06T00:48:14.36094Z","steps":["trace[873807242] 'agreement among raft nodes before linearized reading' (duration: 805.817067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:14.36102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:13.554703Z","time spent":"806.307758ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:48:15.768364Z","caller":"traceutil/trace.go:171","msg":"trace[1393095010] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"167.744125ms","start":"2026-05-06T00:48:15.600578Z","end":"2026-05-06T00:48:15.768322Z","steps":["trace[1393095010] 'read index received' (duration: 167.310724ms)","trace[1393095010] 'applied index is now lower than readState.Index' (duration: 431.971µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:15.768455Z","caller":"traceutil/trace.go:171","msg":"trace[1080466721] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"256.639608ms","start":"2026-05-06T00:48:15.511763Z","end":"2026-05-06T00:48:15.768403Z","steps":["trace[1080466721] 'process raft request' (duration: 256.268109ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:15.768616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.015081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:15.768706Z","caller":"traceutil/trace.go:171","msg":"trace[859485337] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"168.166505ms","start":"2026-05-06T00:48:15.600519Z","end":"2026-05-06T00:48:15.768685Z","steps":["trace[859485337] 'agreement among raft nodes before linearized reading' (duration: 167.996281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:15.768711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.690052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T00:48:15.768756Z","caller":"traceutil/trace.go:171","msg":"trace[1268441349] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:640; }","duration":"106.807595ms","start":"2026-05-06T00:48:15.661934Z","end":"2026-05-06T00:48:15.768741Z","steps":["trace[1268441349] 'agreement among raft nodes before linearized reading' (duration: 106.58606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:18.208998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.849389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:18.209094Z","caller":"traceutil/trace.go:171","msg":"trace[1292339233] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:0; response_revision:669; }","duration":"232.094045ms","start":"2026-05-06T00:48:17.97698Z","end":"2026-05-06T00:48:18.209074Z","steps":["trace[1292339233] 'range keys from in-memory index tree' (duration: 231.693196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:18.209178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.770188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:18.209304Z","caller":"traceutil/trace.go:171","msg":"trace[1612350105] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:669; }","duration":"431.916921ms","start":"2026-05-06T00:48:17.777347Z","end":"2026-05-06T00:48:18.209264Z","steps":["trace[1612350105] 'range keys from in-memory index tree' (duration: 431.619525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:18.209415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:17.777258Z","time spent":"432.132496ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"info","ts":"2026-05-06T00:48:19.726183Z","caller":"traceutil/trace.go:171","msg":"trace[1848409388] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"162.987359ms","start":"2026-05-06T00:48:19.56314Z","end":"2026-05-06T00:48:19.726127Z","steps":["trace[1848409388] 'process raft request' (duration: 134.321548ms)","trace[1848409388] 'compare' (duration: 28.419325ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:19.72605Z","caller":"traceutil/trace.go:171","msg":"trace[647660028] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:716; }","duration":"149.075173ms","start":"2026-05-06T00:48:19.576944Z","end":"2026-05-06T00:48:19.726019Z","steps":["trace[647660028] 'read index received' (duration: 2.750857ms)","trace[647660028] 'applied index is now lower than readState.Index' (duration: 146.322666ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:19.726402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.431053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-06T00:48:19.72649Z","caller":"traceutil/trace.go:171","msg":"trace[428214140] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:695; }","duration":"149.537395ms","start":"2026-05-06T00:48:19.576932Z","end":"2026-05-06T00:48:19.726469Z","steps":["trace[428214140] 'agreement among raft nodes before linearized reading' (duration: 149.247687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:19.726731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.994566ms","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-06T00:48:19.726777Z","caller":"traceutil/trace.go:171","msg":"trace[1558915281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:695; }","duration":"145.138269ms","start":"2026-05-06T00:48:19.581627Z","end":"2026-05-06T00:48:19.726765Z","steps":["trace[1558915281] 'agreement among raft nodes before linearized reading' (duration: 144.896863ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:20.16511Z","caller":"traceutil/trace.go:171","msg":"trace[95487483] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"171.853762ms","start":"2026-05-06T00:48:19.993221Z","end":"2026-05-06T00:48:20.165075Z","steps":["trace[95487483] 'process raft request' (duration: 171.673607ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:20.722975Z","caller":"traceutil/trace.go:171","msg":"trace[1989984862] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"544.018653ms","start":"2026-05-06T00:48:20.178928Z","end":"2026-05-06T00:48:20.722947Z","steps":["trace[1989984862] 'process raft request' (duration: 543.849379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.723307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.178895Z","time spent":"544.165077ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:48:20.768336Z","caller":"traceutil/trace.go:171","msg":"trace[1627436300] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:723; }","duration":"391.150028ms","start":"2026-05-06T00:48:20.377158Z","end":"2026-05-06T00:48:20.768308Z","steps":["trace[1627436300] 'read index received' (duration: 345.606621ms)","trace[1627436300] 'applied index is now lower than readState.Index' (duration: 45.541807ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:20.76837Z","caller":"traceutil/trace.go:171","msg":"trace[366299079] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"515.155756ms","start":"2026-05-06T00:48:20.253172Z","end":"2026-05-06T00:48:20.768328Z","steps":["trace[366299079] 'process raft request' (duration: 514.976372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.768645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.253156Z","time spent":"515.370363ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:48:20.768776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.591629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-06T00:48:20.768836Z","caller":"traceutil/trace.go:171","msg":"trace[974557722] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:702; }","duration":"391.722342ms","start":"2026-05-06T00:48:20.377096Z","end":"2026-05-06T00:48:20.768818Z","steps":["trace[974557722] 'agreement among raft nodes before linearized reading' (duration: 391.349633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.768898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.377074Z","time spent":"391.808475ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-05-06T00:48:20.923197Z","caller":"traceutil/trace.go:171","msg":"trace[1582129303] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:725; }","duration":"154.76382ms","start":"2026-05-06T00:48:20.7684Z","end":"2026-05-06T00:48:20.923164Z","steps":["trace[1582129303] 'read index received' (duration: 145.015285ms)","trace[1582129303] 'applied index is now lower than readState.Index' (duration: 9.746745ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:20.92347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.003758ms","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-06T00:48:20.923513Z","caller":"traceutil/trace.go:171","msg":"trace[1175212162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"455.10457ms","start":"2026-05-06T00:48:20.468395Z","end":"2026-05-06T00:48:20.923499Z","steps":["trace[1175212162] 'agreement among raft nodes before linearized reading' (duration: 454.907006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.923561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.468373Z","time spent":"455.178483ms","remote":"127.0.0.1:47692","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-06T00:48:20.923559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.98663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-864sp\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-05-06T00:48:20.92368Z","caller":"traceutil/trace.go:171","msg":"trace[2115233015] range","detail":"{range_begin:/registry/pods/kube-system/cilium-864sp; range_end:; response_count:1; response_revision:703; }","duration":"373.154654ms","start":"2026-05-06T00:48:20.550502Z","end":"2026-05-06T00:48:20.923657Z","steps":["trace[2115233015] 'agreement among raft nodes before linearized reading' (duration: 372.861027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.923731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.550483Z","time spent":"373.239327ms","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-864sp\" "} {"level":"warn","ts":"2026-05-06T00:48:20.923788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.61819ms","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-06T00:48:20.923839Z","caller":"traceutil/trace.go:171","msg":"trace[2043702988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"447.690162ms","start":"2026-05-06T00:48:20.476135Z","end":"2026-05-06T00:48:20.923825Z","steps":["trace[2043702988] 'agreement among raft nodes before linearized reading' (duration: 447.546509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.923816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.789729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"warn","ts":"2026-05-06T00:48:20.924033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.237479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T00:48:20.92394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.927389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:48:20.925217Z","caller":"traceutil/trace.go:171","msg":"trace[356075433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:703; }","duration":"450.460807ms","start":"2026-05-06T00:48:20.474733Z","end":"2026-05-06T00:48:20.925194Z","steps":["trace[356075433] 'agreement among raft nodes before linearized reading' (duration: 449.215318ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:20.925265Z","caller":"traceutil/trace.go:171","msg":"trace[80229408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:703; }","duration":"447.347542ms","start":"2026-05-06T00:48:20.47788Z","end":"2026-05-06T00:48:20.925228Z","steps":["trace[80229408] 'agreement among raft nodes before linearized reading' (duration: 445.339515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.925401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.477872Z","time spent":"447.458126ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:48:20.925289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.474707Z","time spent":"450.54739ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:48:20.924016Z","caller":"traceutil/trace.go:171","msg":"trace[1679211003] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:703; }","duration":"347.089846ms","start":"2026-05-06T00:48:20.576902Z","end":"2026-05-06T00:48:20.923991Z","steps":["trace[1679211003] 'agreement among raft nodes before linearized reading' (duration: 346.285307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.925507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.576874Z","time spent":"348.597283ms","remote":"127.0.0.1:47448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":24267,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" "} {"level":"info","ts":"2026-05-06T00:48:20.925928Z","caller":"traceutil/trace.go:171","msg":"trace[579101377] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"197.348117ms","start":"2026-05-06T00:48:20.728554Z","end":"2026-05-06T00:48:20.925902Z","steps":["trace[579101377] 'process raft request' (duration: 185.212694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.926306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.990435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:20.926365Z","caller":"traceutil/trace.go:171","msg":"trace[484173845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"330.058376ms","start":"2026-05-06T00:48:20.596286Z","end":"2026-05-06T00:48:20.926344Z","steps":["trace[484173845] 'agreement among raft nodes before linearized reading' (duration: 329.82766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:20.926457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.596274Z","time spent":"330.171548ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:48:20.923886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:20.476124Z","time spent":"447.751953ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:48:20.98621Z","caller":"traceutil/trace.go:171","msg":"trace[2076860119] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"149.424722ms","start":"2026-05-06T00:48:20.836758Z","end":"2026-05-06T00:48:20.986183Z","steps":["trace[2076860119] 'process raft request' (duration: 149.083304ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:21.285584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.46605ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T00:48:21.286146Z","caller":"traceutil/trace.go:171","msg":"trace[240127966] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"215.811892ms","start":"2026-05-06T00:48:21.070304Z","end":"2026-05-06T00:48:21.286116Z","steps":["trace[240127966] 'process raft request' (duration: 215.490885ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:21.353484Z","caller":"traceutil/trace.go:171","msg":"trace[1883841833] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"146.585273ms","start":"2026-05-06T00:48:21.206864Z","end":"2026-05-06T00:48:21.353449Z","steps":["trace[1883841833] 'process raft request' (duration: 146.287256ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:29.395768Z","caller":"traceutil/trace.go:171","msg":"trace[1362363391] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"336.148603ms","start":"2026-05-06T00:48:29.059592Z","end":"2026-05-06T00:48:29.395741Z","steps":["trace[1362363391] 'read index received' (duration: 335.977269ms)","trace[1362363391] 'applied index is now lower than readState.Index' (duration: 170.034µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:29.395911Z","caller":"traceutil/trace.go:171","msg":"trace[1243550762] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"336.354088ms","start":"2026-05-06T00:48:29.05952Z","end":"2026-05-06T00:48:29.395874Z","steps":["trace[1243550762] 'process raft request' (duration: 336.03451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.396025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.401919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T00:48:29.396072Z","caller":"traceutil/trace.go:171","msg":"trace[1173595512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:776; }","duration":"336.513191ms","start":"2026-05-06T00:48:29.059546Z","end":"2026-05-06T00:48:29.396059Z","steps":["trace[1173595512] 'agreement among raft nodes before linearized reading' (duration: 336.305616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.396115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.059522Z","time spent":"336.585374ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:48:29.396213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.059507Z","time spent":"336.544492ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:29.473818Z","caller":"traceutil/trace.go:171","msg":"trace[2093303348] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"411.388396ms","start":"2026-05-06T00:48:29.062362Z","end":"2026-05-06T00:48:29.47375Z","steps":["trace[2093303348] 'process raft request' (duration: 411.324565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.474051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.062349Z","time spent":"411.579882ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:29.47402Z","caller":"traceutil/trace.go:171","msg":"trace[1254849964] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"412.076523ms","start":"2026-05-06T00:48:29.061821Z","end":"2026-05-06T00:48:29.473897Z","steps":["trace[1254849964] 'process raft request' (duration: 411.683203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:29.474437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:29.061803Z","time spent":"412.478333ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:29.552895Z","caller":"traceutil/trace.go:171","msg":"trace[63979558] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"151.650615ms","start":"2026-05-06T00:48:29.401225Z","end":"2026-05-06T00:48:29.552875Z","steps":["trace[63979558] 'process raft request' (duration: 151.495782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.148039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.590284523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-06T00:48:33.148195Z","caller":"traceutil/trace.go:171","msg":"trace[1436108912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.590427616s","start":"2026-05-06T00:48:31.557679Z","end":"2026-05-06T00:48:33.148107Z","steps":["trace[1436108912] 'range keys from in-memory index tree' (duration: 1.590093448s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.148245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:31.557657Z","time spent":"1.590575869s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:48:33.148463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.547824069s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:33.148508Z","caller":"traceutil/trace.go:171","msg":"trace[1317200391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"1.547912721s","start":"2026-05-06T00:48:31.60058Z","end":"2026-05-06T00:48:33.148493Z","steps":["trace[1317200391] 'range keys from in-memory index tree' (duration: 1.547667515s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.148571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:31.600556Z","time spent":"1.548008044s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:48:33.148659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528899273s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xp74x\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-06T00:48:33.14871Z","caller":"traceutil/trace.go:171","msg":"trace[1175610080] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xp74x; range_end:; response_count:1; response_revision:791; }","duration":"1.529009136s","start":"2026-05-06T00:48:31.619685Z","end":"2026-05-06T00:48:33.148694Z","steps":["trace[1175610080] 'range keys from in-memory index tree' (duration: 1.528732819s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.148748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:31.619662Z","time spent":"1.529073107s","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-xp74x\" "} {"level":"warn","ts":"2026-05-06T00:48:33.148856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.264838258s","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-06T00:48:33.148942Z","caller":"traceutil/trace.go:171","msg":"trace[1531405073] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"1.264986201s","start":"2026-05-06T00:48:31.883935Z","end":"2026-05-06T00:48:33.148921Z","steps":["trace[1531405073] 'range keys from in-memory index tree' (duration: 1.264636933s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.148985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:31.88391Z","time spent":"1.265066034s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T00:48:33.462321Z","caller":"traceutil/trace.go:171","msg":"trace[442966516] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"303.628899ms","start":"2026-05-06T00:48:33.158656Z","end":"2026-05-06T00:48:33.462285Z","steps":["trace[442966516] 'process raft request' (duration: 303.462435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.462492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.15863Z","time spent":"303.772323ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:33.470723Z","caller":"traceutil/trace.go:171","msg":"trace[1285756997] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"311.59626ms","start":"2026-05-06T00:48:33.159075Z","end":"2026-05-06T00:48:33.470671Z","steps":["trace[1285756997] 'process raft request' (duration: 311.304613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.470977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.159054Z","time spent":"311.770995ms","remote":"127.0.0.1:47606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:48:33.470993Z","caller":"traceutil/trace.go:171","msg":"trace[123916311] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:818; }","duration":"311.728904ms","start":"2026-05-06T00:48:33.159171Z","end":"2026-05-06T00:48:33.4709Z","steps":["trace[123916311] 'read index received' (duration: 303.039815ms)","trace[123916311] 'applied index is now lower than readState.Index' (duration: 8.687549ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:33.471062Z","caller":"traceutil/trace.go:171","msg":"trace[1540895664] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"311.876397ms","start":"2026-05-06T00:48:33.159139Z","end":"2026-05-06T00:48:33.471016Z","steps":["trace[1540895664] 'process raft request' (duration: 311.440477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.471174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.00271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:33.471218Z","caller":"traceutil/trace.go:171","msg":"trace[1781209761] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"312.071232ms","start":"2026-05-06T00:48:33.159134Z","end":"2026-05-06T00:48:33.471205Z","steps":["trace[1781209761] 'agreement among raft nodes before linearized reading' (duration: 311.958039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:33.471253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.159116Z","time spent":"312.130313ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:48:33.471248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:48:33.159119Z","time spent":"312.027931ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:48:33.471237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.139862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:33.47141Z","caller":"traceutil/trace.go:171","msg":"trace[461639183] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:794; }","duration":"107.300496ms","start":"2026-05-06T00:48:33.36402Z","end":"2026-05-06T00:48:33.47132Z","steps":["trace[461639183] 'agreement among raft nodes before linearized reading' (duration: 107.110891ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:36.896252Z","caller":"traceutil/trace.go:171","msg":"trace[984601459] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"114.465939ms","start":"2026-05-06T00:48:36.781756Z","end":"2026-05-06T00:48:36.896222Z","steps":["trace[984601459] 'process raft request' (duration: 114.403577ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:36.89642Z","caller":"traceutil/trace.go:171","msg":"trace[1530788104] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"120.017293ms","start":"2026-05-06T00:48:36.776366Z","end":"2026-05-06T00:48:36.896383Z","steps":["trace[1530788104] 'process raft request' (duration: 94.051996ms)","trace[1530788104] 'compare' (duration: 25.543007ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:37.454072Z","caller":"traceutil/trace.go:171","msg":"trace[574537726] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"132.417152ms","start":"2026-05-06T00:48:37.321613Z","end":"2026-05-06T00:48:37.45403Z","steps":["trace[574537726] 'process raft request' (duration: 128.61614ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.454226Z","caller":"traceutil/trace.go:171","msg":"trace[1760491656] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"132.477532ms","start":"2026-05-06T00:48:37.321712Z","end":"2026-05-06T00:48:37.454189Z","steps":["trace[1760491656] 'process raft request' (duration: 132.238576ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.656006Z","caller":"traceutil/trace.go:171","msg":"trace[1454697898] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"125.106436ms","start":"2026-05-06T00:48:37.53087Z","end":"2026-05-06T00:48:37.655977Z","steps":["trace[1454697898] 'process raft request' (duration: 124.795249ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.656326Z","caller":"traceutil/trace.go:171","msg":"trace[1850310861] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"125.318861ms","start":"2026-05-06T00:48:37.530977Z","end":"2026-05-06T00:48:37.656296Z","steps":["trace[1850310861] 'process raft request' (duration: 124.951432ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.656787Z","caller":"traceutil/trace.go:171","msg":"trace[567446086] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"123.129668ms","start":"2026-05-06T00:48:37.533639Z","end":"2026-05-06T00:48:37.656769Z","steps":["trace[567446086] 'process raft request' (duration: 122.553134ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.65688Z","caller":"traceutil/trace.go:171","msg":"trace[858802450] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"122.657886ms","start":"2026-05-06T00:48:37.534206Z","end":"2026-05-06T00:48:37.656864Z","steps":["trace[858802450] 'process raft request' (duration: 122.449361ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.656996Z","caller":"traceutil/trace.go:171","msg":"trace[327079174] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"122.473632ms","start":"2026-05-06T00:48:37.534512Z","end":"2026-05-06T00:48:37.656986Z","steps":["trace[327079174] 'process raft request' (duration: 122.248047ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.657026Z","caller":"traceutil/trace.go:171","msg":"trace[1886586795] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"121.913718ms","start":"2026-05-06T00:48:37.535095Z","end":"2026-05-06T00:48:37.657009Z","steps":["trace[1886586795] 'process raft request' (duration: 121.724433ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:37.65707Z","caller":"traceutil/trace.go:171","msg":"trace[172160531] linearizableReadLoop","detail":"{readStateIndex:867; appliedIndex:860; }","duration":"120.3073ms","start":"2026-05-06T00:48:37.5367Z","end":"2026-05-06T00:48:37.657008Z","steps":["trace[172160531] 'read index received' (duration: 63.03425ms)","trace[172160531] 'applied index is now lower than readState.Index' (duration: 57.27172ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:37.657197Z","caller":"traceutil/trace.go:171","msg":"trace[1427316656] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"119.837368ms","start":"2026-05-06T00:48:37.537331Z","end":"2026-05-06T00:48:37.657169Z","steps":["trace[1427316656] 'process raft request' (duration: 119.605513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:37.657353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.625047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9\" ","response":"range_response_count:1 size:5114"} {"level":"info","ts":"2026-05-06T00:48:37.657399Z","caller":"traceutil/trace.go:171","msg":"trace[689052194] range","detail":"{range_begin:/registry/replicasets/envoy-gateway-system/envoy-gateway-78446f96c9; range_end:; response_count:1; response_revision:839; }","duration":"120.690869ms","start":"2026-05-06T00:48:37.536693Z","end":"2026-05-06T00:48:37.657384Z","steps":["trace[689052194] 'agreement among raft nodes before linearized reading' (duration: 120.501564ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:42.840254Z","caller":"traceutil/trace.go:171","msg":"trace[2125947259] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"122.134593ms","start":"2026-05-06T00:48:42.718093Z","end":"2026-05-06T00:48:42.840227Z","steps":["trace[2125947259] 'read index received' (duration: 121.839226ms)","trace[2125947259] 'applied index is now lower than readState.Index' (duration: 294.137µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:42.840372Z","caller":"traceutil/trace.go:171","msg":"trace[135949831] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"159.160486ms","start":"2026-05-06T00:48:42.681175Z","end":"2026-05-06T00:48:42.840336Z","steps":["trace[135949831] 'process raft request' (duration: 158.861969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:48:42.840453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.326878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:48:42.8405Z","caller":"traceutil/trace.go:171","msg":"trace[946468169] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:886; }","duration":"122.435562ms","start":"2026-05-06T00:48:42.718053Z","end":"2026-05-06T00:48:42.840488Z","steps":["trace[946468169] 'agreement among raft nodes before linearized reading' (duration: 122.321258ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:43.029494Z","caller":"traceutil/trace.go:171","msg":"trace[1423485100] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"181.174817ms","start":"2026-05-06T00:48:42.848294Z","end":"2026-05-06T00:48:43.029469Z","steps":["trace[1423485100] 'process raft request' (duration: 181.005533ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:43.115809Z","caller":"traceutil/trace.go:171","msg":"trace[1166085188] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"267.476868ms","start":"2026-05-06T00:48:42.84831Z","end":"2026-05-06T00:48:43.115787Z","steps":["trace[1166085188] 'process raft request' (duration: 237.507005ms)","trace[1166085188] 'compare' (duration: 29.810029ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:43.116114Z","caller":"traceutil/trace.go:171","msg":"trace[2024447126] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"262.672552ms","start":"2026-05-06T00:48:42.853399Z","end":"2026-05-06T00:48:43.116071Z","steps":["trace[2024447126] 'process raft request' (duration: 262.342774ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:48:52.014849Z","caller":"traceutil/trace.go:171","msg":"trace[1848772320] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"131.921658ms","start":"2026-05-06T00:48:51.882899Z","end":"2026-05-06T00:48:52.014821Z","steps":["trace[1848772320] 'read index received' (duration: 43.228302ms)","trace[1848772320] 'applied index is now lower than readState.Index' (duration: 88.692246ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:48:52.01496Z","caller":"traceutil/trace.go:171","msg":"trace[1467662009] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"133.321062ms","start":"2026-05-06T00:48:51.881578Z","end":"2026-05-06T00:48:52.014899Z","steps":["trace[1467662009] 'process raft request' (duration: 44.537013ms)","trace[1467662009] 'compare' (duration: 88.545103ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:48:52.015229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.277597ms","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-06T00:48:52.015265Z","caller":"traceutil/trace.go:171","msg":"trace[334718567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:949; }","duration":"132.41704ms","start":"2026-05-06T00:48:51.88284Z","end":"2026-05-06T00:48:52.015257Z","steps":["trace[334718567] 'agreement among raft nodes before linearized reading' (duration: 132.080552ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:09.768776Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.049790438s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:49:09.769229Z","caller":"traceutil/trace.go:171","msg":"trace[1130631813] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.050353992s","start":"2026-05-06T00:49:08.718847Z","end":"2026-05-06T00:49:09.769201Z","steps":["trace[1130631813] 'process raft request' (duration: 1.050141066s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:09.769381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:08.718814Z","time spent":"1.050477085s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:10.314222Z","caller":"traceutil/trace.go:171","msg":"trace[485468680] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"1.045086774s","start":"2026-05-06T00:49:09.269103Z","end":"2026-05-06T00:49:10.31419Z","steps":["trace[485468680] 'read index received' (duration: 499.857315ms)","trace[485468680] 'applied index is now lower than readState.Index' (duration: 545.227809ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:10.314367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045244987s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:10.314434Z","caller":"traceutil/trace.go:171","msg":"trace[2009467898] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1009; }","duration":"1.045406671s","start":"2026-05-06T00:49:09.269017Z","end":"2026-05-06T00:49:10.314424Z","steps":["trace[2009467898] 'agreement among raft nodes before linearized reading' (duration: 1.045292199s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.314429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.880724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:49:10.314468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.269Z","time spent":"1.045460693s","remote":"127.0.0.1:47638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:49:10.314504Z","caller":"traceutil/trace.go:171","msg":"trace[1182269343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"715.006577ms","start":"2026-05-06T00:49:09.599478Z","end":"2026-05-06T00:49:10.314485Z","steps":["trace[1182269343] 'agreement among raft nodes before linearized reading' (duration: 714.881194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.314535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.010743ms","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-06T00:49:10.314548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.599458Z","time spent":"715.081729ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:49:10.314569Z","caller":"traceutil/trace.go:171","msg":"trace[912293195] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"172.128567ms","start":"2026-05-06T00:49:10.142431Z","end":"2026-05-06T00:49:10.31456Z","steps":["trace[912293195] 'agreement among raft nodes before linearized reading' (duration: 171.925142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.314866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.493477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T00:49:10.314897Z","caller":"traceutil/trace.go:171","msg":"trace[1017044802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"214.551347ms","start":"2026-05-06T00:49:10.100336Z","end":"2026-05-06T00:49:10.314887Z","steps":["trace[1017044802] 'agreement among raft nodes before linearized reading' (duration: 214.409124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.314925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.375701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T00:49:10.314941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.308799ms","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-06T00:49:10.314953Z","caller":"traceutil/trace.go:171","msg":"trace[738481625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"429.411802ms","start":"2026-05-06T00:49:09.885532Z","end":"2026-05-06T00:49:10.314944Z","steps":["trace[738481625] 'agreement among raft nodes before linearized reading' (duration: 429.299429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.314981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.885446Z","time spent":"429.528705ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:49:10.31498Z","caller":"traceutil/trace.go:171","msg":"trace[1020532287] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"343.413851ms","start":"2026-05-06T00:49:09.971556Z","end":"2026-05-06T00:49:10.31497Z","steps":["trace[1020532287] 'agreement among raft nodes before linearized reading' (duration: 343.267238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.315016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.971535Z","time spent":"343.470052ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:49:10.315075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.667949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:49:10.315127Z","caller":"traceutil/trace.go:171","msg":"trace[1729943965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"341.776641ms","start":"2026-05-06T00:49:09.973335Z","end":"2026-05-06T00:49:10.315111Z","steps":["trace[1729943965] 'agreement among raft nodes before linearized reading' (duration: 341.620477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:10.315159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.973309Z","time spent":"341.843353ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:49:10.31429Z","caller":"traceutil/trace.go:171","msg":"trace[285415337] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.047937083s","start":"2026-05-06T00:49:09.266311Z","end":"2026-05-06T00:49:10.314248Z","steps":["trace[285415337] 'process raft request' (duration: 997.263761ms)","trace[285415337] 'compare' (duration: 50.374035ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:10.315464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:09.266281Z","time spent":"1.049021959s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:11.007562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.271215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:11.007691Z","caller":"traceutil/trace.go:171","msg":"trace[566055259] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"688.40721ms","start":"2026-05-06T00:49:10.319261Z","end":"2026-05-06T00:49:11.007668Z","steps":["trace[566055259] 'process raft request' (duration: 143.93198ms)","trace[566055259] 'compare' (duration: 544.115541ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:11.00779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:10.319238Z","time spent":"688.522252ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:12.307789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.590999ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:12.308312Z","caller":"traceutil/trace.go:171","msg":"trace[2095312570] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.988454885s","start":"2026-05-06T00:49:10.319827Z","end":"2026-05-06T00:49:12.308282Z","steps":["trace[2095312570] 'process raft request' (duration: 1.988072194s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:12.308427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:10.319807Z","time spent":"1.988562688s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:12.308497Z","caller":"traceutil/trace.go:171","msg":"trace[255726454] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.986835766s","start":"2026-05-06T00:49:10.321627Z","end":"2026-05-06T00:49:12.308463Z","steps":["trace[255726454] 'process raft request' (duration: 1.986422975s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:12.308477Z","caller":"traceutil/trace.go:171","msg":"trace[1506460760] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.988597318s","start":"2026-05-06T00:49:10.319836Z","end":"2026-05-06T00:49:12.308433Z","steps":["trace[1506460760] 'process raft request' (duration: 1.988156627s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:12.308545Z","caller":"traceutil/trace.go:171","msg":"trace[847278164] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.989204952s","start":"2026-05-06T00:49:10.319316Z","end":"2026-05-06T00:49:12.308521Z","steps":["trace[847278164] 'process raft request' (duration: 990.773822ms)","trace[847278164] 'compare' (duration: 996.80172ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:12.308591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:10.321618Z","time spent":"1.986924188s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:12.308648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:10.319288Z","time spent":"1.989304515s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:12.308738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:10.319824Z","time spent":"1.988749032s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:12.635609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.478163ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T00:49:12.635868Z","caller":"traceutil/trace.go:171","msg":"trace[851395099] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1046; }","duration":"1.037231293s","start":"2026-05-06T00:49:11.598619Z","end":"2026-05-06T00:49:12.63585Z","steps":["trace[851395099] 'read index received' (duration: 80.602µs)","trace[851395099] 'applied index is now lower than readState.Index' (duration: 1.037146311s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:12.636018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037390008s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:12.636085Z","caller":"traceutil/trace.go:171","msg":"trace[27199439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"1.037513001s","start":"2026-05-06T00:49:11.598555Z","end":"2026-05-06T00:49:12.636068Z","steps":["trace[27199439] 'agreement among raft nodes before linearized reading' (duration: 1.037399358s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:12.636115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:11.598526Z","time spent":"1.037584193s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:49:12.63622Z","caller":"traceutil/trace.go:171","msg":"trace[2093848982] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"861.342577ms","start":"2026-05-06T00:49:11.774826Z","end":"2026-05-06T00:49:12.636168Z","steps":["trace[2093848982] 'process raft request' (duration: 860.909146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:12.636393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:11.774802Z","time spent":"861.48316ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:12.763452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.754368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:12.763547Z","caller":"traceutil/trace.go:171","msg":"trace[986889107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"125.912872ms","start":"2026-05-06T00:49:12.637605Z","end":"2026-05-06T00:49:12.763517Z","steps":["trace[986889107] 'range keys from in-memory index tree' (duration: 125.667166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:12.763587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.836181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:49:12.76375Z","caller":"traceutil/trace.go:171","msg":"trace[497436975] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1015; }","duration":"533.991465ms","start":"2026-05-06T00:49:12.229653Z","end":"2026-05-06T00:49:12.763645Z","steps":["trace[497436975] 'agreement among raft nodes before linearized reading' (duration: 406.482035ms)","trace[497436975] 'count revisions from in-memory index tree' (duration: 127.383927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:12.763802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:12.22963Z","time spent":"534.16017ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:49:24.541177Z","caller":"traceutil/trace.go:171","msg":"trace[449860070] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"143.358015ms","start":"2026-05-06T00:49:24.397783Z","end":"2026-05-06T00:49:24.541141Z","steps":["trace[449860070] 'process raft request' (duration: 53.06851ms)","trace[449860070] 'compare' (duration: 90.122131ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:25.09991Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794764768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:49:25.190324Z","caller":"traceutil/trace.go:171","msg":"trace[332361004] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"791.601041ms","start":"2026-05-06T00:49:24.398694Z","end":"2026-05-06T00:49:25.190295Z","steps":["trace[332361004] 'process raft request' (duration: 791.381205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:25.190521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.398678Z","time spent":"791.736693ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:25.201373Z","caller":"traceutil/trace.go:171","msg":"trace[1548408218] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1091; }","duration":"601.784734ms","start":"2026-05-06T00:49:24.599566Z","end":"2026-05-06T00:49:25.201351Z","steps":["trace[1548408218] 'read index received' (duration: 590.607023ms)","trace[1548408218] 'applied index is now lower than readState.Index' (duration: 11.176801ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:25.201499Z","caller":"traceutil/trace.go:171","msg":"trace[865475380] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"522.598428ms","start":"2026-05-06T00:49:24.678879Z","end":"2026-05-06T00:49:25.201478Z","steps":["trace[865475380] 'process raft request' (duration: 519.320279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:25.201553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.970739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:25.201587Z","caller":"traceutil/trace.go:171","msg":"trace[2060715122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"602.078501ms","start":"2026-05-06T00:49:24.599499Z","end":"2026-05-06T00:49:25.201577Z","steps":["trace[2060715122] 'agreement among raft nodes before linearized reading' (duration: 601.972638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:25.201633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.678857Z","time spent":"522.693351ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:25.201644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.391885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:49:25.201747Z","caller":"traceutil/trace.go:171","msg":"trace[1982639137] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1054; }","duration":"506.568258ms","start":"2026-05-06T00:49:24.695161Z","end":"2026-05-06T00:49:25.201729Z","steps":["trace[1982639137] 'agreement among raft nodes before linearized reading' (duration: 506.387224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:25.201793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.69513Z","time spent":"506.652211ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":16,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:25.201828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.707927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:49:25.201924Z","caller":"traceutil/trace.go:171","msg":"trace[364904754] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1054; }","duration":"505.849751ms","start":"2026-05-06T00:49:24.696049Z","end":"2026-05-06T00:49:25.201899Z","steps":["trace[364904754] 'agreement among raft nodes before linearized reading' (duration: 505.626005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:25.201927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.599476Z","time spent":"602.135053ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:49:25.20198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:24.696041Z","time spent":"505.924772ms","remote":"127.0.0.1:47786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:49:33.396889Z","caller":"traceutil/trace.go:171","msg":"trace[1350288694] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"109.188705ms","start":"2026-05-06T00:49:33.287665Z","end":"2026-05-06T00:49:33.396854Z","steps":["trace[1350288694] 'process raft request' (duration: 108.735794ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:35.880605Z","caller":"traceutil/trace.go:171","msg":"trace[1888585550] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"567.414618ms","start":"2026-05-06T00:49:35.313166Z","end":"2026-05-06T00:49:35.88058Z","steps":["trace[1888585550] 'process raft request' (duration: 567.244784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:35.880781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.313125Z","time spent":"567.562542ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:36.395598Z","caller":"traceutil/trace.go:171","msg":"trace[1258639398] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1126; }","duration":"985.784482ms","start":"2026-05-06T00:49:35.409758Z","end":"2026-05-06T00:49:36.395543Z","steps":["trace[1258639398] 'read index received' (duration: 470.698366ms)","trace[1258639398] 'applied index is now lower than readState.Index' (duration: 515.085086ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:36.395694Z","caller":"traceutil/trace.go:171","msg":"trace[1426741673] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"991.536152ms","start":"2026-05-06T00:49:35.404127Z","end":"2026-05-06T00:49:36.395663Z","steps":["trace[1426741673] 'process raft request' (duration: 991.264185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:36.395787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.888204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:36.395834Z","caller":"traceutil/trace.go:171","msg":"trace[1028505318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"796.006166ms","start":"2026-05-06T00:49:35.599816Z","end":"2026-05-06T00:49:36.395822Z","steps":["trace[1028505318] 'agreement among raft nodes before linearized reading' (duration: 795.917234ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:36.395833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.070871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:49:36.395869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.404091Z","time spent":"991.684985ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:36.39592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.615576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:36.395929Z","caller":"traceutil/trace.go:171","msg":"trace[875559687] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1087; }","duration":"331.234654ms","start":"2026-05-06T00:49:36.064672Z","end":"2026-05-06T00:49:36.395907Z","steps":["trace[875559687] 'agreement among raft nodes before linearized reading' (duration: 331.078871ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:36.395955Z","caller":"traceutil/trace.go:171","msg":"trace[959113997] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1087; }","duration":"554.697208ms","start":"2026-05-06T00:49:35.841246Z","end":"2026-05-06T00:49:36.395943Z","steps":["trace[959113997] 'agreement among raft nodes before linearized reading' (duration: 554.646067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:36.395987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.841221Z","time spent":"554.75738ms","remote":"127.0.0.1:49022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:36.396009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:36.064645Z","time spent":"331.350448ms","remote":"127.0.0.1:49096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:36.395868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.59979Z","time spent":"796.069168ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:49:36.396169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.788845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.162\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-05-06T00:49:36.396181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.372706ms","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-06T00:49:36.396261Z","caller":"traceutil/trace.go:171","msg":"trace[283134776] range","detail":"{range_begin:/registry/masterleases/199.19.213.162; range_end:; response_count:1; response_revision:1087; }","duration":"731.947298ms","start":"2026-05-06T00:49:35.66429Z","end":"2026-05-06T00:49:36.396237Z","steps":["trace[283134776] 'agreement among raft nodes before linearized reading' (duration: 731.725513ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:36.396261Z","caller":"traceutil/trace.go:171","msg":"trace[939049995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"986.51894ms","start":"2026-05-06T00:49:35.409726Z","end":"2026-05-06T00:49:36.396245Z","steps":["trace[939049995] 'agreement among raft nodes before linearized reading' (duration: 986.004307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:36.39632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.664264Z","time spent":"732.042801ms","remote":"127.0.0.1:47444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.162\" "} {"level":"warn","ts":"2026-05-06T00:49:36.39632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:35.409714Z","time spent":"986.593361ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T00:49:36.696003Z","caller":"traceutil/trace.go:171","msg":"trace[1093145454] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"189.417125ms","start":"2026-05-06T00:49:36.506561Z","end":"2026-05-06T00:49:36.695978Z","steps":["trace[1093145454] 'read index received' (duration: 122.756794ms)","trace[1093145454] 'applied index is now lower than readState.Index' (duration: 66.659201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:36.696271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.62651ms","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-06T00:49:36.696688Z","caller":"traceutil/trace.go:171","msg":"trace[1227017689] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1088; }","duration":"190.168494ms","start":"2026-05-06T00:49:36.506504Z","end":"2026-05-06T00:49:36.696672Z","steps":["trace[1227017689] 'agreement among raft nodes before linearized reading' (duration: 189.644841ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:36.696352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.422477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:49:36.696764Z","caller":"traceutil/trace.go:171","msg":"trace[858748530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1088; }","duration":"150.880789ms","start":"2026-05-06T00:49:36.545857Z","end":"2026-05-06T00:49:36.696738Z","steps":["trace[858748530] 'agreement among raft nodes before linearized reading' (duration: 150.334675ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:36.696374Z","caller":"traceutil/trace.go:171","msg":"trace[291292149] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"294.772708ms","start":"2026-05-06T00:49:36.401575Z","end":"2026-05-06T00:49:36.696347Z","steps":["trace[291292149] 'process raft request' (duration: 294.231185ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:36.895146Z","caller":"traceutil/trace.go:171","msg":"trace[507204394] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"135.022163ms","start":"2026-05-06T00:49:36.760096Z","end":"2026-05-06T00:49:36.895118Z","steps":["trace[507204394] 'read index received' (duration: 114.437613ms)","trace[507204394] 'applied index is now lower than readState.Index' (duration: 20.58332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:36.895355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.900592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-06T00:49:36.895391Z","caller":"traceutil/trace.go:171","msg":"trace[1779851684] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1092; }","duration":"160.995064ms","start":"2026-05-06T00:49:36.734384Z","end":"2026-05-06T00:49:36.895379Z","steps":["trace[1779851684] 'agreement among raft nodes before linearized reading' (duration: 160.876511ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:36.89601Z","caller":"traceutil/trace.go:171","msg":"trace[1334554664] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"155.463721ms","start":"2026-05-06T00:49:36.740506Z","end":"2026-05-06T00:49:36.89597Z","steps":["trace[1334554664] 'process raft request' (duration: 134.011929ms)","trace[1334554664] 'compare' (duration: 20.357175ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:36.913866Z","caller":"traceutil/trace.go:171","msg":"trace[547179383] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"149.993658ms","start":"2026-05-06T00:49:36.763837Z","end":"2026-05-06T00:49:36.91383Z","steps":["trace[547179383] 'process raft request' (duration: 149.821304ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:38.094345Z","caller":"traceutil/trace.go:171","msg":"trace[1816379547] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"204.078543ms","start":"2026-05-06T00:49:37.890239Z","end":"2026-05-06T00:49:38.094318Z","steps":["trace[1816379547] 'process raft request' (duration: 203.913869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:39.201418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794764880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:39.701756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794764880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:40.202852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794764880,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:49:40.242088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.832888641s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-06T00:49:40.444741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204287963s","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-06T00:49:40.44497Z","caller":"traceutil/trace.go:171","msg":"trace[2138824224] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1094; }","duration":"2.20457516s","start":"2026-05-06T00:49:38.240372Z","end":"2026-05-06T00:49:40.444947Z","steps":["trace[2138824224] 'count revisions from in-memory index tree' (duration: 2.204218151s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:40.445097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.240352Z","time spent":"2.204728694s","remote":"127.0.0.1:49050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:40.446686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.619312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:40.446911Z","caller":"traceutil/trace.go:171","msg":"trace[1582721220] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"2.037801454s","start":"2026-05-06T00:49:38.409086Z","end":"2026-05-06T00:49:40.446887Z","steps":["trace[1582721220] 'process raft request' (duration: 1.833925996s)","trace[1582721220] 'compare' (duration: 202.638959ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:40.448317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.409055Z","time spent":"2.039044645s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:40.446874Z","caller":"traceutil/trace.go:171","msg":"trace[1047815500] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1136; }","duration":"1.745730362s","start":"2026-05-06T00:49:38.70112Z","end":"2026-05-06T00:49:40.446851Z","steps":["trace[1047815500] 'read index received' (duration: 1.541188028s)","trace[1047815500] 'applied index is now lower than readState.Index' (duration: 204.540644ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:40.449209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.748078989s","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-06T00:49:40.449198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.656359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:49:40.449274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685871187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T00:49:40.449288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.433858ms","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-06T00:49:40.449292Z","caller":"traceutil/trace.go:171","msg":"trace[2062156063] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1095; }","duration":"1.748216353s","start":"2026-05-06T00:49:38.701063Z","end":"2026-05-06T00:49:40.449279Z","steps":["trace[2062156063] 'agreement among raft nodes before linearized reading' (duration: 1.747960997s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.449338Z","caller":"traceutil/trace.go:171","msg":"trace[88172279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1095; }","duration":"1.68599027s","start":"2026-05-06T00:49:38.76333Z","end":"2026-05-06T00:49:40.449321Z","steps":["trace[88172279] 'agreement among raft nodes before linearized reading' (duration: 1.685751734s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:40.44933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530624801s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T00:49:40.449394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.76331Z","time spent":"1.686075991s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T00:49:40.449412Z","caller":"traceutil/trace.go:171","msg":"trace[780728752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1095; }","duration":"1.530764844s","start":"2026-05-06T00:49:38.918622Z","end":"2026-05-06T00:49:40.449387Z","steps":["trace[780728752] 'agreement among raft nodes before linearized reading' (duration: 1.530484148s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:40.449411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.700999Z","time spent":"1.748403547s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:49:40.449454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.518054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:49:40.449427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.685356424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:49:40.449545Z","caller":"traceutil/trace.go:171","msg":"trace[294406926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"850.620766ms","start":"2026-05-06T00:49:39.598889Z","end":"2026-05-06T00:49:40.44951Z","steps":["trace[294406926] 'agreement among raft nodes before linearized reading' (duration: 850.530854ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.449339Z","caller":"traceutil/trace.go:171","msg":"trace[2127300736] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1095; }","duration":"869.850594ms","start":"2026-05-06T00:49:39.579466Z","end":"2026-05-06T00:49:40.449317Z","steps":["trace[2127300736] 'agreement among raft nodes before linearized reading' (duration: 869.610928ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:40.449605Z","caller":"traceutil/trace.go:171","msg":"trace[1076574477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1095; }","duration":"1.685641292s","start":"2026-05-06T00:49:38.763925Z","end":"2026-05-06T00:49:40.449566Z","steps":["trace[1076574477] 'agreement among raft nodes before linearized reading' (duration: 1.685206271s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:40.449587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:39.598865Z","time spent":"850.712488ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:49:40.449643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:39.579446Z","time spent":"870.177691ms","remote":"127.0.0.1:49042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:40.44967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.763872Z","time spent":"1.685788235s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T00:49:40.449447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:38.918596Z","time spent":"1.530845707s","remote":"127.0.0.1:47692","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-06T00:49:40.449347Z","caller":"traceutil/trace.go:171","msg":"trace[731551721] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1095; }","duration":"349.53123ms","start":"2026-05-06T00:49:40.099796Z","end":"2026-05-06T00:49:40.449328Z","steps":["trace[731551721] 'agreement among raft nodes before linearized reading' (duration: 349.312025ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:40.449837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.099759Z","time spent":"350.067543ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:49:41.097591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.358392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:41.097747Z","caller":"traceutil/trace.go:171","msg":"trace[362796343] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"641.575632ms","start":"2026-05-06T00:49:40.456146Z","end":"2026-05-06T00:49:41.097722Z","steps":["trace[362796343] 'process raft request' (duration: 375.952583ms)","trace[362796343] 'compare' (duration: 265.162998ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:41.097811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.456128Z","time spent":"641.658613ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:41.694815Z","caller":"traceutil/trace.go:171","msg":"trace[988790800] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"1.237892742s","start":"2026-05-06T00:49:40.456885Z","end":"2026-05-06T00:49:41.694777Z","steps":["trace[988790800] 'process raft request' (duration: 1.23777696s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:41.695075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.45685Z","time spent":"1.238077847s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:41.695393Z","caller":"traceutil/trace.go:171","msg":"trace[695750975] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"1.238712692s","start":"2026-05-06T00:49:40.456619Z","end":"2026-05-06T00:49:41.695331Z","steps":["trace[695750975] 'process raft request' (duration: 1.237953544s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:41.695416Z","caller":"traceutil/trace.go:171","msg":"trace[1292224495] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"1.238407865s","start":"2026-05-06T00:49:40.45698Z","end":"2026-05-06T00:49:41.695388Z","steps":["trace[1292224495] 'process raft request' (duration: 1.237740639s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:41.694896Z","caller":"traceutil/trace.go:171","msg":"trace[317676716] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"1.23819445s","start":"2026-05-06T00:49:40.456639Z","end":"2026-05-06T00:49:41.694834Z","steps":["trace[317676716] 'process raft request' (duration: 1.157794154s)","trace[317676716] 'compare' (duration: 79.302639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:41.695647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.456588Z","time spent":"1.238911307s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:41.69575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.45663Z","time spent":"1.23904732s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:41.695762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.45697Z","time spent":"1.238653021s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:42.218719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.822808ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T00:49:42.219093Z","caller":"traceutil/trace.go:171","msg":"trace[1100158331] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"1.073125416s","start":"2026-05-06T00:49:41.14595Z","end":"2026-05-06T00:49:42.219075Z","steps":["trace[1100158331] 'process raft request' (duration: 1.072987153s)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:42.219091Z","caller":"traceutil/trace.go:171","msg":"trace[816077559] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1137; }","duration":"1.618926079s","start":"2026-05-06T00:49:40.600137Z","end":"2026-05-06T00:49:42.219063Z","steps":["trace[816077559] 'read index received' (duration: 231.979172ms)","trace[816077559] 'applied index is now lower than readState.Index' (duration: 1.386940156s)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:42.219201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.619062692s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:49:42.219198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:41.145922Z","time spent":"1.073208107s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:42.219326Z","caller":"traceutil/trace.go:171","msg":"trace[871098947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"1.619187985s","start":"2026-05-06T00:49:40.600121Z","end":"2026-05-06T00:49:42.219309Z","steps":["trace[871098947] 'agreement among raft nodes before linearized reading' (duration: 1.619035681s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:42.219338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28918565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T00:49:42.219384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.600046Z","time spent":"1.619325188s","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:49:42.219388Z","caller":"traceutil/trace.go:171","msg":"trace[270324071] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1101; }","duration":"1.289356844s","start":"2026-05-06T00:49:40.930014Z","end":"2026-05-06T00:49:42.219371Z","steps":["trace[270324071] 'agreement among raft nodes before linearized reading' (duration: 1.28919032s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:42.219426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:40.929981Z","time spent":"1.289435915s","remote":"127.0.0.1:47894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:42.219255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.894558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:42.219709Z","caller":"traceutil/trace.go:171","msg":"trace[887189546] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1101; }","duration":"850.38777ms","start":"2026-05-06T00:49:41.369298Z","end":"2026-05-06T00:49:42.219686Z","steps":["trace[887189546] 'agreement among raft nodes before linearized reading' (duration: 849.913538ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:42.219769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:41.369271Z","time spent":"850.480662ms","remote":"127.0.0.1:49036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:49:43.840989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.137421ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:43.841134Z","caller":"traceutil/trace.go:171","msg":"trace[278722457] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1101; }","duration":"171.294125ms","start":"2026-05-06T00:49:43.669823Z","end":"2026-05-06T00:49:43.841117Z","steps":["trace[278722457] 'range keys from in-memory index tree' (duration: 171.119201ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.841986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.35712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:43.842087Z","caller":"traceutil/trace.go:171","msg":"trace[1086942759] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"738.456738ms","start":"2026-05-06T00:49:43.103612Z","end":"2026-05-06T00:49:43.842069Z","steps":["trace[1086942759] 'read index received' (duration: 48.382µs)","trace[1086942759] 'applied index is now lower than readState.Index' (duration: 738.406706ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:49:43.842108Z","caller":"traceutil/trace.go:171","msg":"trace[1356054021] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"1.385520512s","start":"2026-05-06T00:49:42.456571Z","end":"2026-05-06T00:49:43.842091Z","steps":["trace[1356054021] 'process raft request' (duration: 492.976917ms)","trace[1356054021] 'compare' (duration: 892.073544ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:43.842208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:42.456533Z","time spent":"1.385610843s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:43.842237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.745058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:49:43.842269Z","caller":"traceutil/trace.go:171","msg":"trace[208903399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1102; }","duration":"241.8309ms","start":"2026-05-06T00:49:43.600426Z","end":"2026-05-06T00:49:43.842257Z","steps":["trace[208903399] 'agreement among raft nodes before linearized reading' (duration: 241.746498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.842408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.520591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T00:49:43.842414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.767555ms","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-06T00:49:43.842473Z","caller":"traceutil/trace.go:171","msg":"trace[744813815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1102; }","duration":"141.616873ms","start":"2026-05-06T00:49:43.700834Z","end":"2026-05-06T00:49:43.842451Z","steps":["trace[744813815] 'agreement among raft nodes before linearized reading' (duration: 141.372707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.842503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.348042ms","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-06T00:49:43.842521Z","caller":"traceutil/trace.go:171","msg":"trace[554539800] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1102; }","duration":"738.907269ms","start":"2026-05-06T00:49:43.103578Z","end":"2026-05-06T00:49:43.842485Z","steps":["trace[554539800] 'agreement among raft nodes before linearized reading' (duration: 738.55577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.842581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.906495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T00:49:43.842409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.230189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:49:43.842587Z","caller":"traceutil/trace.go:171","msg":"trace[1886987818] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1102; }","duration":"142.443474ms","start":"2026-05-06T00:49:43.700095Z","end":"2026-05-06T00:49:43.842539Z","steps":["trace[1886987818] 'agreement among raft nodes before linearized reading' (duration: 142.348052ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:43.842615Z","caller":"traceutil/trace.go:171","msg":"trace[631976118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1102; }","duration":"142.995768ms","start":"2026-05-06T00:49:43.69961Z","end":"2026-05-06T00:49:43.842605Z","steps":["trace[631976118] 'agreement among raft nodes before linearized reading' (duration: 142.831244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:43.842611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.103561Z","time spent":"739.036271ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T00:49:43.842629Z","caller":"traceutil/trace.go:171","msg":"trace[1155909515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1102; }","duration":"142.487385ms","start":"2026-05-06T00:49:43.700127Z","end":"2026-05-06T00:49:43.842614Z","steps":["trace[1155909515] 'agreement among raft nodes before linearized reading' (duration: 142.098205ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:44.354472Z","caller":"traceutil/trace.go:171","msg":"trace[1434565553] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"506.62677ms","start":"2026-05-06T00:49:43.847764Z","end":"2026-05-06T00:49:44.354391Z","steps":["trace[1434565553] 'process raft request' (duration: 506.296372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:44.354642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.847744Z","time spent":"506.810764ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:44.354749Z","caller":"traceutil/trace.go:171","msg":"trace[2139082831] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"506.859766ms","start":"2026-05-06T00:49:43.847839Z","end":"2026-05-06T00:49:44.354698Z","steps":["trace[2139082831] 'process raft request' (duration: 506.426415ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:44.354894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.84783Z","time spent":"506.986198ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:44.35538Z","caller":"traceutil/trace.go:171","msg":"trace[2073816893] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"506.816644ms","start":"2026-05-06T00:49:43.8485Z","end":"2026-05-06T00:49:44.355316Z","steps":["trace[2073816893] 'process raft request' (duration: 505.81741ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:44.355464Z","caller":"traceutil/trace.go:171","msg":"trace[399666508] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"506.075266ms","start":"2026-05-06T00:49:43.849336Z","end":"2026-05-06T00:49:44.355411Z","steps":["trace[399666508] 'process raft request' (duration: 505.713887ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:44.35557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.849323Z","time spent":"506.199169ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:49:44.355907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:49:43.848483Z","time spent":"506.986269ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:49:45.990228Z","caller":"traceutil/trace.go:171","msg":"trace[626308729] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"141.362868ms","start":"2026-05-06T00:49:45.848841Z","end":"2026-05-06T00:49:45.990203Z","steps":["trace[626308729] 'process raft request' (duration: 140.59228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:46.232483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.526882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:49:46.232744Z","caller":"traceutil/trace.go:171","msg":"trace[1176036238] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"237.184917ms","start":"2026-05-06T00:49:45.995538Z","end":"2026-05-06T00:49:46.232723Z","steps":["trace[1176036238] 'process raft request' (duration: 237.120275ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:46.232987Z","caller":"traceutil/trace.go:171","msg":"trace[277904225] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"269.112315ms","start":"2026-05-06T00:49:45.963795Z","end":"2026-05-06T00:49:46.232908Z","steps":["trace[277904225] 'process raft request' (duration: 113.06906ms)","trace[277904225] 'compare' (duration: 155.355248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:49:46.58795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.470881ms","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-06T00:49:46.588095Z","caller":"traceutil/trace.go:171","msg":"trace[1093669741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1110; }","duration":"227.713707ms","start":"2026-05-06T00:49:46.360353Z","end":"2026-05-06T00:49:46.588067Z","steps":["trace[1093669741] 'range keys from in-memory index tree' (duration: 227.185005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:46.588154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.766297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:49:46.588231Z","caller":"traceutil/trace.go:171","msg":"trace[1701830905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1110; }","duration":"229.89679ms","start":"2026-05-06T00:49:46.35831Z","end":"2026-05-06T00:49:46.588207Z","steps":["trace[1701830905] 'range keys from in-memory index tree' (duration: 229.492441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:46.587995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.338352ms","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-06T00:49:46.588407Z","caller":"traceutil/trace.go:171","msg":"trace[550777883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1110; }","duration":"228.841814ms","start":"2026-05-06T00:49:46.359556Z","end":"2026-05-06T00:49:46.588398Z","steps":["trace[550777883] 'range keys from in-memory index tree' (duration: 228.061326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:49:46.588633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.937258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:49:46.588722Z","caller":"traceutil/trace.go:171","msg":"trace[1083267856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1110; }","duration":"229.121542ms","start":"2026-05-06T00:49:46.359579Z","end":"2026-05-06T00:49:46.588701Z","steps":["trace[1083267856] 'range keys from in-memory index tree' (duration: 228.722851ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:47.145672Z","caller":"traceutil/trace.go:171","msg":"trace[1708060048] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"136.698173ms","start":"2026-05-06T00:49:47.008946Z","end":"2026-05-06T00:49:47.145645Z","steps":["trace[1708060048] 'process raft request' (duration: 136.508369ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:49:58.664607Z","caller":"traceutil/trace.go:171","msg":"trace[1602687765] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"286.244044ms","start":"2026-05-06T00:49:58.378309Z","end":"2026-05-06T00:49:58.664553Z","steps":["trace[1602687765] 'process raft request' (duration: 286.012658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:00.699107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794765121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:01.19976Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794765121,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T00:50:01.363118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.302389459s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T00:50:01.363553Z","caller":"traceutil/trace.go:171","msg":"trace[1212550676] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"1.164835147s","start":"2026-05-06T00:50:00.198689Z","end":"2026-05-06T00:50:01.363524Z","steps":["trace[1212550676] 'read index received' (duration: 1.164676603s)","trace[1212550676] 'applied index is now lower than readState.Index' (duration: 156.914µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:01.363998Z","caller":"traceutil/trace.go:171","msg":"trace[494390354] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"1.303404873s","start":"2026-05-06T00:50:00.060578Z","end":"2026-05-06T00:50:01.363983Z","steps":["trace[494390354] 'process raft request' (duration: 1.302757637s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.364132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.060542Z","time spent":"1.303499015s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:01.364356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165657776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:50:01.364402Z","caller":"traceutil/trace.go:171","msg":"trace[1551382650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1206; }","duration":"1.165753229s","start":"2026-05-06T00:50:00.198637Z","end":"2026-05-06T00:50:01.36439Z","steps":["trace[1551382650] 'agreement among raft nodes before linearized reading' (duration: 1.165681367s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.364439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.198608Z","time spent":"1.165823441s","remote":"127.0.0.1:49142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:50:01.545691Z","caller":"traceutil/trace.go:171","msg":"trace[1860162968] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"182.030639ms","start":"2026-05-06T00:50:01.363631Z","end":"2026-05-06T00:50:01.545662Z","steps":["trace[1860162968] 'read index received' (duration: 175.363997ms)","trace[1860162968] 'applied index is now lower than readState.Index' (duration: 6.664142ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:01.545838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.146735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:01.545879Z","caller":"traceutil/trace.go:171","msg":"trace[630692452] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"945.259709ms","start":"2026-05-06T00:50:00.600606Z","end":"2026-05-06T00:50:01.545866Z","steps":["trace[630692452] 'agreement among raft nodes before linearized reading' (duration: 945.170837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.54589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.79167ms","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-06T00:50:01.545921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.60056Z","time spent":"945.350801ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:50:01.545942Z","caller":"traceutil/trace.go:171","msg":"trace[992989380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1206; }","duration":"805.892882ms","start":"2026-05-06T00:50:00.740034Z","end":"2026-05-06T00:50:01.545927Z","steps":["trace[992989380] 'agreement among raft nodes before linearized reading' (duration: 805.666827ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.545981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.73999Z","time spent":"805.979724ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:50:01.546066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.673765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T00:50:01.546121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.251206ms","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-06T00:50:01.546256Z","caller":"traceutil/trace.go:171","msg":"trace[1776144298] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1206; }","duration":"876.455001ms","start":"2026-05-06T00:50:00.669757Z","end":"2026-05-06T00:50:01.546212Z","steps":["trace[1776144298] 'agreement among raft nodes before linearized reading' (duration: 876.091022ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:01.546162Z","caller":"traceutil/trace.go:171","msg":"trace[490616355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1206; }","duration":"807.819958ms","start":"2026-05-06T00:50:00.738322Z","end":"2026-05-06T00:50:01.546141Z","steps":["trace[490616355] 'agreement among raft nodes before linearized reading' (duration: 807.512771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.546307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.66973Z","time spent":"876.566624ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T00:50:01.54631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.738317Z","time spent":"807.983172ms","remote":"127.0.0.1:47692","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-06T00:50:01.546292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.896434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:50:01.546581Z","caller":"traceutil/trace.go:171","msg":"trace[1845858356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1206; }","duration":"809.240513ms","start":"2026-05-06T00:50:00.737307Z","end":"2026-05-06T00:50:01.546547Z","steps":["trace[1845858356] 'agreement among raft nodes before linearized reading' (duration: 808.497175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.546665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.737288Z","time spent":"809.362856ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:50:01.546806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.272379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:50:01.546918Z","caller":"traceutil/trace.go:171","msg":"trace[1584378617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"803.453633ms","start":"2026-05-06T00:50:00.743436Z","end":"2026-05-06T00:50:01.54689Z","steps":["trace[1584378617] 'agreement among raft nodes before linearized reading' (duration: 802.347427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:01.546993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:00.743427Z","time spent":"803.554685ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:50:03.721717Z","caller":"traceutil/trace.go:171","msg":"trace[185903911] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"146.432387ms","start":"2026-05-06T00:50:03.575262Z","end":"2026-05-06T00:50:03.721694Z","steps":["trace[185903911] 'process raft request' (duration: 146.248462ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:03.72211Z","caller":"traceutil/trace.go:171","msg":"trace[1094375162] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"146.734455ms","start":"2026-05-06T00:50:03.575356Z","end":"2026-05-06T00:50:03.72209Z","steps":["trace[1094375162] 'process raft request' (duration: 146.298614ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:03.722098Z","caller":"traceutil/trace.go:171","msg":"trace[340814945] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"146.576571ms","start":"2026-05-06T00:50:03.575488Z","end":"2026-05-06T00:50:03.722065Z","steps":["trace[340814945] 'process raft request' (duration: 146.420617ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:03.722157Z","caller":"traceutil/trace.go:171","msg":"trace[261812600] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1263; }","duration":"122.326513ms","start":"2026-05-06T00:50:03.599807Z","end":"2026-05-06T00:50:03.722134Z","steps":["trace[261812600] 'read index received' (duration: 13.489027ms)","trace[261812600] 'applied index is now lower than readState.Index' (duration: 108.835896ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:03.722203Z","caller":"traceutil/trace.go:171","msg":"trace[1441110022] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"144.720066ms","start":"2026-05-06T00:50:03.577472Z","end":"2026-05-06T00:50:03.722192Z","steps":["trace[1441110022] 'process raft request' (duration: 144.547862ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:03.72232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.499427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:03.722365Z","caller":"traceutil/trace.go:171","msg":"trace[607414882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"122.592ms","start":"2026-05-06T00:50:03.599762Z","end":"2026-05-06T00:50:03.722354Z","steps":["trace[607414882] 'agreement among raft nodes before linearized reading' (duration: 122.465047ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:16.38973Z","caller":"traceutil/trace.go:171","msg":"trace[1602827637] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"319.825288ms","start":"2026-05-06T00:50:16.069878Z","end":"2026-05-06T00:50:16.389703Z","steps":["trace[1602827637] 'process raft request' (duration: 284.83499ms)","trace[1602827637] 'compare' (duration: 34.352311ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:16.389906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:16.069852Z","time spent":"319.958431ms","remote":"127.0.0.1:47574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:16.390087Z","caller":"traceutil/trace.go:171","msg":"trace[1724564443] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"317.87654ms","start":"2026-05-06T00:50:16.072174Z","end":"2026-05-06T00:50:16.39005Z","steps":["trace[1724564443] 'process raft request' (duration: 317.4548ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:16.390297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:16.072146Z","time spent":"318.031164ms","remote":"127.0.0.1:47922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:16.723924Z","caller":"traceutil/trace.go:171","msg":"trace[237530170] linearizableReadLoop","detail":"{readStateIndex:1329; appliedIndex:1328; }","duration":"122.330333ms","start":"2026-05-06T00:50:16.60157Z","end":"2026-05-06T00:50:16.7239Z","steps":["trace[237530170] 'read index received' (duration: 122.112408ms)","trace[237530170] 'applied index is now lower than readState.Index' (duration: 216.945µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:16.724051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.465997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:16.724082Z","caller":"traceutil/trace.go:171","msg":"trace[706751118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"122.664281ms","start":"2026-05-06T00:50:16.601409Z","end":"2026-05-06T00:50:16.724073Z","steps":["trace[706751118] 'agreement among raft nodes before linearized reading' (duration: 122.58851ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:16.724218Z","caller":"traceutil/trace.go:171","msg":"trace[2134429084] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"156.730677ms","start":"2026-05-06T00:50:16.567456Z","end":"2026-05-06T00:50:16.724186Z","steps":["trace[2134429084] 'process raft request' (duration: 156.284836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.052618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":199739458794765284,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T00:50:18.22534Z","caller":"traceutil/trace.go:171","msg":"trace[621754794] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"744.817132ms","start":"2026-05-06T00:50:17.480495Z","end":"2026-05-06T00:50:18.225313Z","steps":["trace[621754794] 'process raft request' (duration: 744.601277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.225551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.480475Z","time spent":"744.952056ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:18.256518Z","caller":"traceutil/trace.go:171","msg":"trace[735231925] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"704.638699ms","start":"2026-05-06T00:50:17.551853Z","end":"2026-05-06T00:50:18.256492Z","steps":["trace[735231925] 'read index received' (duration: 673.27025ms)","trace[735231925] 'applied index is now lower than readState.Index' (duration: 31.367229ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:18.25664Z","caller":"traceutil/trace.go:171","msg":"trace[247792837] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"753.953174ms","start":"2026-05-06T00:50:17.502672Z","end":"2026-05-06T00:50:18.256625Z","steps":["trace[247792837] 'process raft request' (duration: 753.667737ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.25674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.502658Z","time spent":"754.008435ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:18.256855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.867857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T00:50:18.256902Z","caller":"traceutil/trace.go:171","msg":"trace[1470307302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1280; }","duration":"572.956439ms","start":"2026-05-06T00:50:17.683932Z","end":"2026-05-06T00:50:18.256889Z","steps":["trace[1470307302] 'agreement among raft nodes before linearized reading' (duration: 572.816786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.256937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.683909Z","time spent":"573.02144ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T00:50:18.257026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.181803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:18.257086Z","caller":"traceutil/trace.go:171","msg":"trace[1818312939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1280; }","duration":"705.274674ms","start":"2026-05-06T00:50:17.551802Z","end":"2026-05-06T00:50:18.257077Z","steps":["trace[1818312939] 'agreement among raft nodes before linearized reading' (duration: 705.204003ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.257112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.551782Z","time spent":"705.324176ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:50:18.257326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.680551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-06T00:50:18.257343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.597736ms","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-06T00:50:18.257366Z","caller":"traceutil/trace.go:171","msg":"trace[881738164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1280; }","duration":"458.720812ms","start":"2026-05-06T00:50:17.798635Z","end":"2026-05-06T00:50:18.257356Z","steps":["trace[881738164] 'agreement among raft nodes before linearized reading' (duration: 458.6171ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:18.25738Z","caller":"traceutil/trace.go:171","msg":"trace[1080664407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1280; }","duration":"429.680128ms","start":"2026-05-06T00:50:17.827688Z","end":"2026-05-06T00:50:18.257369Z","steps":["trace[1080664407] 'agreement among raft nodes before linearized reading' (duration: 429.574166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.257394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.798611Z","time spent":"458.776594ms","remote":"127.0.0.1:47692","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-06T00:50:18.257413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.827677Z","time spent":"429.727429ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-06T00:50:18.257475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.643902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-06T00:50:18.257371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.767381ms","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-06T00:50:18.257533Z","caller":"traceutil/trace.go:171","msg":"trace[687511482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1280; }","duration":"437.711973ms","start":"2026-05-06T00:50:17.819803Z","end":"2026-05-06T00:50:18.257515Z","steps":["trace[687511482] 'agreement among raft nodes before linearized reading' (duration: 437.614811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.257569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.927168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:50:18.257585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.819767Z","time spent":"437.807345ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T00:50:18.257612Z","caller":"traceutil/trace.go:171","msg":"trace[1841879182] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1280; }","duration":"549.005109ms","start":"2026-05-06T00:50:17.708596Z","end":"2026-05-06T00:50:18.257601Z","steps":["trace[1841879182] 'agreement among raft nodes before linearized reading' (duration: 548.942347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.257659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.708575Z","time spent":"549.065611ms","remote":"127.0.0.1:48988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-06T00:50:18.25757Z","caller":"traceutil/trace.go:171","msg":"trace[1134105325] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1280; }","duration":"442.026817ms","start":"2026-05-06T00:50:17.815532Z","end":"2026-05-06T00:50:18.257559Z","steps":["trace[1134105325] 'agreement among raft nodes before linearized reading' (duration: 441.649598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:18.257871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:17.815513Z","time spent":"442.347325ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-06T00:50:18.422984Z","caller":"traceutil/trace.go:171","msg":"trace[1766914672] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"160.927458ms","start":"2026-05-06T00:50:18.262016Z","end":"2026-05-06T00:50:18.422943Z","steps":["trace[1766914672] 'process raft request' (duration: 160.59531ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:18.423169Z","caller":"traceutil/trace.go:171","msg":"trace[2011233266] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"159.675828ms","start":"2026-05-06T00:50:18.263469Z","end":"2026-05-06T00:50:18.423145Z","steps":["trace[2011233266] 'process raft request' (duration: 159.636917ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:18.42334Z","caller":"traceutil/trace.go:171","msg":"trace[2005990634] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"160.427506ms","start":"2026-05-06T00:50:18.2629Z","end":"2026-05-06T00:50:18.423327Z","steps":["trace[2005990634] 'process raft request' (duration: 160.16133ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:18.423348Z","caller":"traceutil/trace.go:171","msg":"trace[509341218] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"161.210695ms","start":"2026-05-06T00:50:18.262113Z","end":"2026-05-06T00:50:18.423324Z","steps":["trace[509341218] 'process raft request' (duration: 160.647252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.427373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.815356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:20.427471Z","caller":"traceutil/trace.go:171","msg":"trace[999422631] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"149.655646ms","start":"2026-05-06T00:50:20.277801Z","end":"2026-05-06T00:50:20.427457Z","steps":["trace[999422631] 'read index received' (duration: 30.411µs)","trace[999422631] 'applied index is now lower than readState.Index' (duration: 149.624215ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:20.427527Z","caller":"traceutil/trace.go:171","msg":"trace[7911472] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"196.92916ms","start":"2026-05-06T00:50:20.230589Z","end":"2026-05-06T00:50:20.427518Z","steps":["trace[7911472] 'process raft request' (duration: 42.902479ms)","trace[7911472] 'compare' (duration: 153.700703ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:20.42758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.777559ms","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":"info","ts":"2026-05-06T00:50:20.42762Z","caller":"traceutil/trace.go:171","msg":"trace[313715396] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1288; }","duration":"149.81655ms","start":"2026-05-06T00:50:20.27779Z","end":"2026-05-06T00:50:20.427607Z","steps":["trace[313715396] 'agreement among raft nodes before linearized reading' (duration: 149.754968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.427647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.377763ms","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-06T00:50:20.427677Z","caller":"traceutil/trace.go:171","msg":"trace[420275984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"143.424954ms","start":"2026-05-06T00:50:20.284244Z","end":"2026-05-06T00:50:20.427669Z","steps":["trace[420275984] 'agreement among raft nodes before linearized reading' (duration: 143.325792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.784811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.179027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:20.784973Z","caller":"traceutil/trace.go:171","msg":"trace[1930029243] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"351.839063ms","start":"2026-05-06T00:50:20.433123Z","end":"2026-05-06T00:50:20.784962Z","steps":["trace[1930029243] 'process raft request' (duration: 351.816412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.785013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.433118Z","time spent":"351.876154ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:20.785173Z","caller":"traceutil/trace.go:171","msg":"trace[1462155252] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"353.911614ms","start":"2026-05-06T00:50:20.431255Z","end":"2026-05-06T00:50:20.785166Z","steps":["trace[1462155252] 'process raft request' (duration: 182.324117ms)","trace[1462155252] 'compare' (duration: 171.104835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:20.785207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.431243Z","time spent":"353.944505ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:50:20.785294Z","caller":"traceutil/trace.go:171","msg":"trace[1483988997] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"352.350535ms","start":"2026-05-06T00:50:20.432907Z","end":"2026-05-06T00:50:20.785258Z","steps":["trace[1483988997] 'process raft request' (duration: 352.014926ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:20.785337Z","caller":"traceutil/trace.go:171","msg":"trace[1458488679] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"352.839638ms","start":"2026-05-06T00:50:20.432472Z","end":"2026-05-06T00:50:20.785312Z","steps":["trace[1458488679] 'process raft request' (duration: 352.396286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.785426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.432901Z","time spent":"352.459958ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T00:50:20.785446Z","caller":"traceutil/trace.go:171","msg":"trace[1952250862] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"352.93125ms","start":"2026-05-06T00:50:20.432508Z","end":"2026-05-06T00:50:20.785439Z","steps":["trace[1952250862] 'process raft request' (duration: 352.395736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:20.785466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.432465Z","time spent":"352.93139ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:20.785483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.432504Z","time spent":"352.9599ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:21.49996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.588187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:21.500164Z","caller":"traceutil/trace.go:171","msg":"trace[644761745] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"998.922549ms","start":"2026-05-06T00:50:20.501201Z","end":"2026-05-06T00:50:21.500124Z","steps":["trace[644761745] 'process raft request' (duration: 479.084636ms)","trace[644761745] 'compare' (duration: 519.375252ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:21.500218Z","caller":"traceutil/trace.go:171","msg":"trace[2053930792] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1341; }","duration":"900.820002ms","start":"2026-05-06T00:50:20.599379Z","end":"2026-05-06T00:50:21.500199Z","steps":["trace[2053930792] 'read index received' (duration: 13.858395ms)","trace[2053930792] 'applied index is now lower than readState.Index' (duration: 886.959526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:21.500272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.501178Z","time spent":"999.039261ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:21.524155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.760331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:21.524302Z","caller":"traceutil/trace.go:171","msg":"trace[1158602425] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"733.119498ms","start":"2026-05-06T00:50:20.791168Z","end":"2026-05-06T00:50:21.524287Z","steps":["trace[1158602425] 'process raft request' (duration: 732.894023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.524365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.791151Z","time spent":"733.178789ms","remote":"127.0.0.1:47496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T00:50:21.524467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.564366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:21.524497Z","caller":"traceutil/trace.go:171","msg":"trace[1272838464] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1297; }","duration":"358.634937ms","start":"2026-05-06T00:50:21.165853Z","end":"2026-05-06T00:50:21.524488Z","steps":["trace[1272838464] 'agreement among raft nodes before linearized reading' (duration: 358.579036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.524521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:21.165837Z","time spent":"358.677499ms","remote":"127.0.0.1:49032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:50:21.524539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.68919ms","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-06T00:50:21.524618Z","caller":"traceutil/trace.go:171","msg":"trace[35608065] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1297; }","duration":"735.806614ms","start":"2026-05-06T00:50:20.788793Z","end":"2026-05-06T00:50:21.524599Z","steps":["trace[35608065] 'agreement among raft nodes before linearized reading' (duration: 735.527576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:21.524664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.78878Z","time spent":"735.874856ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-06T00:50:21.525226Z","caller":"traceutil/trace.go:171","msg":"trace[37724625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1295; }","duration":"924.881983ms","start":"2026-05-06T00:50:20.599329Z","end":"2026-05-06T00:50:21.524211Z","steps":["trace[37724625] 'agreement among raft nodes before linearized reading' (duration: 901.050367ms)","trace[37724625] 'range keys from in-memory index tree' (duration: 23.719754ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:50:21.525333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:20.599308Z","time spent":"926.011512ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T00:50:28.668207Z","caller":"traceutil/trace.go:171","msg":"trace[115536505] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"218.187715ms","start":"2026-05-06T00:50:28.449996Z","end":"2026-05-06T00:50:28.668184Z","steps":["trace[115536505] 'process raft request' (duration: 218.00796ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:28.669502Z","caller":"traceutil/trace.go:171","msg":"trace[2024738081] linearizableReadLoop","detail":"{readStateIndex:1410; appliedIndex:1408; }","duration":"117.006664ms","start":"2026-05-06T00:50:28.552469Z","end":"2026-05-06T00:50:28.669475Z","steps":["trace[2024738081] 'read index received' (duration: 115.556048ms)","trace[2024738081] 'applied index is now lower than readState.Index' (duration: 1.449046ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:28.669653Z","caller":"traceutil/trace.go:171","msg":"trace[1269467218] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"184.013448ms","start":"2026-05-06T00:50:28.485608Z","end":"2026-05-06T00:50:28.669621Z","steps":["trace[1269467218] 'process raft request' (duration: 183.71194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:28.669754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.269771ms","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-06T00:50:28.669852Z","caller":"traceutil/trace.go:171","msg":"trace[4555719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"117.433645ms","start":"2026-05-06T00:50:28.552407Z","end":"2026-05-06T00:50:28.669841Z","steps":["trace[4555719] 'agreement among raft nodes before linearized reading' (duration: 117.204279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:28.888753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.444951ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:50:28.888949Z","caller":"traceutil/trace.go:171","msg":"trace[53802020] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"214.390583ms","start":"2026-05-06T00:50:28.674536Z","end":"2026-05-06T00:50:28.888927Z","steps":["trace[53802020] 'process raft request' (duration: 47.505121ms)","trace[53802020] 'compare' (duration: 166.308578ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:31.887132Z","caller":"traceutil/trace.go:171","msg":"trace[970553383] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"296.141154ms","start":"2026-05-06T00:50:31.590956Z","end":"2026-05-06T00:50:31.887097Z","steps":["trace[970553383] 'process raft request' (duration: 295.901618ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:33.09341Z","caller":"traceutil/trace.go:171","msg":"trace[1584304849] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"493.73467ms","start":"2026-05-06T00:50:32.599643Z","end":"2026-05-06T00:50:33.093378Z","steps":["trace[1584304849] 'read index received' (duration: 493.647738ms)","trace[1584304849] 'applied index is now lower than readState.Index' (duration: 85.422µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:33.093555Z","caller":"traceutil/trace.go:171","msg":"trace[526643286] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.014881214s","start":"2026-05-06T00:50:32.078655Z","end":"2026-05-06T00:50:33.093537Z","steps":["trace[526643286] 'process raft request' (duration: 1.014523495s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.093715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:32.078642Z","time spent":"1.014960366s","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:33.093727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.072168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:50:33.093782Z","caller":"traceutil/trace.go:171","msg":"trace[209130921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1372; }","duration":"494.16881ms","start":"2026-05-06T00:50:32.599598Z","end":"2026-05-06T00:50:33.093766Z","steps":["trace[209130921] 'agreement among raft nodes before linearized reading' (duration: 494.034927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.093845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:32.599578Z","time spent":"494.259392ms","remote":"127.0.0.1:47414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T00:50:33.119396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.52222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T00:50:33.119389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.603514ms","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-06T00:50:33.119458Z","caller":"traceutil/trace.go:171","msg":"trace[1867443496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1373; }","duration":"125.583302ms","start":"2026-05-06T00:50:32.993856Z","end":"2026-05-06T00:50:33.11944Z","steps":["trace[1867443496] 'agreement among raft nodes before linearized reading' (duration: 125.438318ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:33.119483Z","caller":"traceutil/trace.go:171","msg":"trace[934277947] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1373; }","duration":"123.840281ms","start":"2026-05-06T00:50:32.995622Z","end":"2026-05-06T00:50:33.119462Z","steps":["trace[934277947] 'agreement among raft nodes before linearized reading' (duration: 123.561313ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:33.119432Z","caller":"traceutil/trace.go:171","msg":"trace[1416646771] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"439.69841ms","start":"2026-05-06T00:50:32.679691Z","end":"2026-05-06T00:50:33.11939Z","steps":["trace[1416646771] 'process raft request' (duration: 439.303071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.120608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:50:32.679665Z","time spent":"440.871149ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:50:33.119682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.303084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:50:33.120975Z","caller":"traceutil/trace.go:171","msg":"trace[182994820] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1373; }","duration":"121.612045ms","start":"2026-05-06T00:50:32.999344Z","end":"2026-05-06T00:50:33.120956Z","steps":["trace[182994820] 'agreement among raft nodes before linearized reading' (duration: 120.012426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.119709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.452527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T00:50:33.121243Z","caller":"traceutil/trace.go:171","msg":"trace[939417501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1373; }","duration":"176.004674ms","start":"2026-05-06T00:50:32.945224Z","end":"2026-05-06T00:50:33.121229Z","steps":["trace[939417501] 'agreement among raft nodes before linearized reading' (duration: 174.422336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.119947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.076599ms","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-06T00:50:33.121436Z","caller":"traceutil/trace.go:171","msg":"trace[1106070277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1373; }","duration":"218.643437ms","start":"2026-05-06T00:50:32.902782Z","end":"2026-05-06T00:50:33.121425Z","steps":["trace[1106070277] 'agreement among raft nodes before linearized reading' (duration: 216.477724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:33.120438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.508527ms","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-06T00:50:33.121512Z","caller":"traceutil/trace.go:171","msg":"trace[191793981] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1373; }","duration":"125.622533ms","start":"2026-05-06T00:50:32.995882Z","end":"2026-05-06T00:50:33.121505Z","steps":["trace[191793981] 'agreement among raft nodes before linearized reading' (duration: 123.572715ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:33.355182Z","caller":"traceutil/trace.go:171","msg":"trace[317373991] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"121.239197ms","start":"2026-05-06T00:50:33.233895Z","end":"2026-05-06T00:50:33.355134Z","steps":["trace[317373991] 'process raft request' (duration: 45.686477ms)","trace[317373991] 'compare' (duration: 75.326405ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:50:45.324141Z","caller":"traceutil/trace.go:171","msg":"trace[967250270] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"107.208817ms","start":"2026-05-06T00:50:45.216902Z","end":"2026-05-06T00:50:45.32411Z","steps":["trace[967250270] 'process raft request' (duration: 105.904656ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:50:45.324215Z","caller":"traceutil/trace.go:171","msg":"trace[539690434] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"105.70091ms","start":"2026-05-06T00:50:45.218463Z","end":"2026-05-06T00:50:45.324164Z","steps":["trace[539690434] 'process raft request' (duration: 105.530396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:50:45.324546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.275295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-06T00:50:45.324633Z","caller":"traceutil/trace.go:171","msg":"trace[1501970715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1434; }","duration":"111.392437ms","start":"2026-05-06T00:50:45.21322Z","end":"2026-05-06T00:50:45.324612Z","steps":["trace[1501970715] 'agreement among raft nodes before linearized reading' (duration: 111.005678ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:51:46.111387Z","caller":"traceutil/trace.go:171","msg":"trace[841230667] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"115.512698ms","start":"2026-05-06T00:51:45.995841Z","end":"2026-05-06T00:51:46.111354Z","steps":["trace[841230667] 'process raft request' (duration: 78.701723ms)","trace[841230667] 'compare' (duration: 36.689272ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:56.111075Z","caller":"traceutil/trace.go:171","msg":"trace[324164407] transaction","detail":"{read_only:false; response_revision:2028; number_of_response:1; }","duration":"110.629648ms","start":"2026-05-06T00:52:56.000408Z","end":"2026-05-06T00:52:56.111038Z","steps":["trace[324164407] 'process raft request' (duration: 110.432433ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:56.418032Z","caller":"traceutil/trace.go:171","msg":"trace[2020008382] transaction","detail":"{read_only:false; response_revision:2031; number_of_response:1; }","duration":"100.148476ms","start":"2026-05-06T00:52:56.317841Z","end":"2026-05-06T00:52:56.41799Z","steps":["trace[2020008382] 'process raft request' (duration: 47.602039ms)","trace[2020008382] 'compare' (duration: 52.356712ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:56.632577Z","caller":"traceutil/trace.go:171","msg":"trace[540793481] transaction","detail":"{read_only:false; response_revision:2033; number_of_response:1; }","duration":"164.166819ms","start":"2026-05-06T00:52:56.468372Z","end":"2026-05-06T00:52:56.632539Z","steps":["trace[540793481] 'process raft request' (duration: 105.438304ms)","trace[540793481] 'compare' (duration: 58.435568ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:56.632664Z","caller":"traceutil/trace.go:171","msg":"trace[286464334] linearizableReadLoop","detail":"{readStateIndex:2118; appliedIndex:2116; }","duration":"126.880276ms","start":"2026-05-06T00:52:56.505752Z","end":"2026-05-06T00:52:56.632632Z","steps":["trace[286464334] 'read index received' (duration: 68.13035ms)","trace[286464334] 'applied index is now lower than readState.Index' (duration: 58.745125ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:56.632671Z","caller":"traceutil/trace.go:171","msg":"trace[1029948469] transaction","detail":"{read_only:false; response_revision:2034; number_of_response:1; }","duration":"163.793079ms","start":"2026-05-06T00:52:56.468822Z","end":"2026-05-06T00:52:56.632615Z","steps":["trace[1029948469] 'process raft request' (duration: 163.623065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:56.632804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.03168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:52:56.632861Z","caller":"traceutil/trace.go:171","msg":"trace[20886177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2035; }","duration":"127.122792ms","start":"2026-05-06T00:52:56.505723Z","end":"2026-05-06T00:52:56.632846Z","steps":["trace[20886177] 'agreement among raft nodes before linearized reading' (duration: 126.987698ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:58.379263Z","caller":"traceutil/trace.go:171","msg":"trace[1847601819] transaction","detail":"{read_only:false; response_revision:2039; number_of_response:1; }","duration":"104.062881ms","start":"2026-05-06T00:52:58.275174Z","end":"2026-05-06T00:52:58.379237Z","steps":["trace[1847601819] 'process raft request' (duration: 103.893526ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:58.381064Z","caller":"traceutil/trace.go:171","msg":"trace[326128008] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"102.812231ms","start":"2026-05-06T00:52:58.278192Z","end":"2026-05-06T00:52:58.381004Z","steps":["trace[326128008] 'process raft request' (duration: 102.645997ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:58.561625Z","caller":"traceutil/trace.go:171","msg":"trace[567926715] transaction","detail":"{read_only:false; response_revision:2041; number_of_response:1; }","duration":"132.909292ms","start":"2026-05-06T00:52:58.428677Z","end":"2026-05-06T00:52:58.561587Z","steps":["trace[567926715] 'process raft request' (duration: 50.898718ms)","trace[567926715] 'compare' (duration: 81.816259ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:58.785731Z","caller":"traceutil/trace.go:171","msg":"trace[646320323] linearizableReadLoop","detail":"{readStateIndex:2127; appliedIndex:2125; }","duration":"121.424836ms","start":"2026-05-06T00:52:58.664281Z","end":"2026-05-06T00:52:58.785706Z","steps":["trace[646320323] 'read index received' (duration: 66.319777ms)","trace[646320323] 'applied index is now lower than readState.Index' (duration: 55.103609ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:58.785864Z","caller":"traceutil/trace.go:171","msg":"trace[636665450] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"144.09942ms","start":"2026-05-06T00:52:58.641751Z","end":"2026-05-06T00:52:58.78585Z","steps":["trace[636665450] 'process raft request' (duration: 88.696073ms)","trace[636665450] 'compare' (duration: 55.092449ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:52:58.786032Z","caller":"traceutil/trace.go:171","msg":"trace[144760037] transaction","detail":"{read_only:false; response_revision:2043; number_of_response:1; }","duration":"143.626589ms","start":"2026-05-06T00:52:58.642351Z","end":"2026-05-06T00:52:58.785978Z","steps":["trace[144760037] 'process raft request' (duration: 143.304641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:58.786143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.831096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-06T00:52:58.786164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.995547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:52:58.786287Z","caller":"traceutil/trace.go:171","msg":"trace[720820959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2043; }","duration":"101.162571ms","start":"2026-05-06T00:52:58.685099Z","end":"2026-05-06T00:52:58.786262Z","steps":["trace[720820959] 'agreement among raft nodes before linearized reading' (duration: 100.876144ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:58.786289Z","caller":"traceutil/trace.go:171","msg":"trace[194858647] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2043; }","duration":"122.01986ms","start":"2026-05-06T00:52:58.664239Z","end":"2026-05-06T00:52:58.786259Z","steps":["trace[194858647] 'agreement among raft nodes before linearized reading' (duration: 121.658702ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:52:59.253396Z","caller":"traceutil/trace.go:171","msg":"trace[1637840654] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"462.435048ms","start":"2026-05-06T00:52:58.790935Z","end":"2026-05-06T00:52:59.25337Z","steps":["trace[1637840654] 'process raft request' (duration: 462.251123ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:52:59.253539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:52:58.790926Z","time spent":"462.54056ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:53:04.4196Z","caller":"traceutil/trace.go:171","msg":"trace[394389223] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"154.50322ms","start":"2026-05-06T00:53:04.265066Z","end":"2026-05-06T00:53:04.419569Z","steps":["trace[394389223] 'process raft request' (duration: 83.317302ms)","trace[394389223] 'compare' (duration: 70.972383ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:04.747812Z","caller":"traceutil/trace.go:171","msg":"trace[1812689529] linearizableReadLoop","detail":"{readStateIndex:2152; appliedIndex:2150; }","duration":"283.710283ms","start":"2026-05-06T00:53:04.464071Z","end":"2026-05-06T00:53:04.747782Z","steps":["trace[1812689529] 'read index received' (duration: 39.917391ms)","trace[1812689529] 'applied index is now lower than readState.Index' (duration: 243.791662ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:04.747947Z","caller":"traceutil/trace.go:171","msg":"trace[1613847126] transaction","detail":"{read_only:false; response_revision:2067; number_of_response:1; }","duration":"321.992116ms","start":"2026-05-06T00:53:04.425911Z","end":"2026-05-06T00:53:04.747903Z","steps":["trace[1613847126] 'process raft request' (duration: 321.672819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:04.748101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.00059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T00:53:04.748174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:04.425873Z","time spent":"322.184201ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:53:04.7482Z","caller":"traceutil/trace.go:171","msg":"trace[613885876] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:2067; }","duration":"284.155603ms","start":"2026-05-06T00:53:04.464021Z","end":"2026-05-06T00:53:04.748177Z","steps":["trace[613885876] 'agreement among raft nodes before linearized reading' (duration: 283.872617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:04.748194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.453035ms","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-06T00:53:04.748276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.704954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:53:04.748449Z","caller":"traceutil/trace.go:171","msg":"trace[532110307] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2067; }","duration":"160.714341ms","start":"2026-05-06T00:53:04.587648Z","end":"2026-05-06T00:53:04.748363Z","steps":["trace[532110307] 'agreement among raft nodes before linearized reading' (duration: 160.288351ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:04.748495Z","caller":"traceutil/trace.go:171","msg":"trace[1566849002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2067; }","duration":"150.953029ms","start":"2026-05-06T00:53:04.597527Z","end":"2026-05-06T00:53:04.74848Z","steps":["trace[1566849002] 'agreement among raft nodes before linearized reading' (duration: 150.696743ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:04.928914Z","caller":"traceutil/trace.go:171","msg":"trace[625722683] transaction","detail":"{read_only:false; response_revision:2068; number_of_response:1; }","duration":"176.018347ms","start":"2026-05-06T00:53:04.752848Z","end":"2026-05-06T00:53:04.928866Z","steps":["trace[625722683] 'process raft request' (duration: 84.354691ms)","trace[625722683] 'compare' (duration: 91.428569ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:09.087462Z","caller":"traceutil/trace.go:171","msg":"trace[1706797144] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2173; }","duration":"106.520549ms","start":"2026-05-06T00:53:08.98092Z","end":"2026-05-06T00:53:09.08744Z","steps":["trace[1706797144] 'read index received' (duration: 48.722401ms)","trace[1706797144] 'applied index is now lower than readState.Index' (duration: 57.796928ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:09.08753Z","caller":"traceutil/trace.go:171","msg":"trace[465188452] transaction","detail":"{read_only:false; response_revision:2088; number_of_response:1; }","duration":"113.021284ms","start":"2026-05-06T00:53:08.974491Z","end":"2026-05-06T00:53:09.087513Z","steps":["trace[465188452] 'process raft request' (duration: 55.106003ms)","trace[465188452] 'compare' (duration: 57.662295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:53:09.087658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.725014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T00:53:09.087691Z","caller":"traceutil/trace.go:171","msg":"trace[489302377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2089; }","duration":"106.787646ms","start":"2026-05-06T00:53:08.980894Z","end":"2026-05-06T00:53:09.087681Z","steps":["trace[489302377] 'agreement among raft nodes before linearized reading' (duration: 106.636273ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:09.0878Z","caller":"traceutil/trace.go:171","msg":"trace[788703933] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"112.964112ms","start":"2026-05-06T00:53:08.974815Z","end":"2026-05-06T00:53:09.087779Z","steps":["trace[788703933] 'process raft request' (duration: 112.581443ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:09.416114Z","caller":"traceutil/trace.go:171","msg":"trace[1321072084] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"323.916702ms","start":"2026-05-06T00:53:09.092162Z","end":"2026-05-06T00:53:09.416079Z","steps":["trace[1321072084] 'process raft request' (duration: 303.081236ms)","trace[1321072084] 'compare' (duration: 20.510888ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:53:09.416312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:09.092144Z","time spent":"324.062585ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:53:09.555742Z","caller":"traceutil/trace.go:171","msg":"trace[125835798] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"126.485935ms","start":"2026-05-06T00:53:09.429222Z","end":"2026-05-06T00:53:09.555708Z","steps":["trace[125835798] 'process raft request' (duration: 106.320334ms)","trace[125835798] 'compare' (duration: 20.003077ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:10.282183Z","caller":"traceutil/trace.go:171","msg":"trace[1482960263] transaction","detail":"{read_only:false; response_revision:2092; number_of_response:1; }","duration":"112.4169ms","start":"2026-05-06T00:53:10.169658Z","end":"2026-05-06T00:53:10.282075Z","steps":["trace[1482960263] 'process raft request' (duration: 111.639312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:11.679053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.579401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-06T00:53:11.67917Z","caller":"traceutil/trace.go:171","msg":"trace[1327240257] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2100; }","duration":"187.755416ms","start":"2026-05-06T00:53:11.491381Z","end":"2026-05-06T00:53:11.679137Z","steps":["trace[1327240257] 'range keys from in-memory index tree' (duration: 187.340706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:11.679052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.370102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T00:53:11.679322Z","caller":"traceutil/trace.go:171","msg":"trace[399676201] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2100; }","duration":"186.67212ms","start":"2026-05-06T00:53:11.492619Z","end":"2026-05-06T00:53:11.679291Z","steps":["trace[399676201] 'count revisions from in-memory index tree' (duration: 186.305591ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:11.816176Z","caller":"traceutil/trace.go:171","msg":"trace[531728625] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"131.233069ms","start":"2026-05-06T00:53:11.684884Z","end":"2026-05-06T00:53:11.816117Z","steps":["trace[531728625] 'process raft request' (duration: 131.026674ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:12.508054Z","caller":"traceutil/trace.go:171","msg":"trace[222595446] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"120.339529ms","start":"2026-05-06T00:53:12.387674Z","end":"2026-05-06T00:53:12.508013Z","steps":["trace[222595446] 'process raft request' (duration: 36.032989ms)","trace[222595446] 'compare' (duration: 84.146156ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:44.831229Z","caller":"traceutil/trace.go:171","msg":"trace[765898027] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"102.345179ms","start":"2026-05-06T00:53:44.728845Z","end":"2026-05-06T00:53:44.83119Z","steps":["trace[765898027] 'process raft request' (duration: 80.434858ms)","trace[765898027] 'compare' (duration: 21.751338ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:55.577561Z","caller":"traceutil/trace.go:171","msg":"trace[478402289] transaction","detail":"{read_only:false; response_revision:2330; number_of_response:1; }","duration":"113.29972ms","start":"2026-05-06T00:53:55.464226Z","end":"2026-05-06T00:53:55.577526Z","steps":["trace[478402289] 'process raft request' (duration: 113.097595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:58.347348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.924741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T00:53:58.347627Z","caller":"traceutil/trace.go:171","msg":"trace[881107021] linearizableReadLoop","detail":"{readStateIndex:2440; appliedIndex:2438; }","duration":"645.593271ms","start":"2026-05-06T00:53:57.702004Z","end":"2026-05-06T00:53:58.347597Z","steps":["trace[881107021] 'read index received' (duration: 52.076511ms)","trace[881107021] 'applied index is now lower than readState.Index' (duration: 593.51529ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T00:53:58.347957Z","caller":"traceutil/trace.go:171","msg":"trace[693742733] transaction","detail":"{read_only:false; response_revision:2342; number_of_response:1; }","duration":"717.294591ms","start":"2026-05-06T00:53:57.630642Z","end":"2026-05-06T00:53:58.347937Z","steps":["trace[693742733] 'process raft request' (duration: 67.657453ms)","trace[693742733] 'compare' (duration: 648.658655ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T00:53:58.348043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:57.630627Z","time spent":"717.370922ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T00:53:58.348284Z","caller":"traceutil/trace.go:171","msg":"trace[180003830] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"714.388432ms","start":"2026-05-06T00:53:57.633882Z","end":"2026-05-06T00:53:58.34827Z","steps":["trace[180003830] 'process raft request' (duration: 713.639103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:58.348415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:57.633858Z","time spent":"714.473674ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T00:53:58.348862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.844621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-06T00:53:58.348892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.420401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T00:53:58.348991Z","caller":"traceutil/trace.go:171","msg":"trace[1726978545] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:2343; }","duration":"534.599935ms","start":"2026-05-06T00:53:57.814363Z","end":"2026-05-06T00:53:58.348963Z","steps":["trace[1726978545] 'agreement among raft nodes before linearized reading' (duration: 534.415881ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T00:53:58.348996Z","caller":"traceutil/trace.go:171","msg":"trace[1333406756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2343; }","duration":"647.048936ms","start":"2026-05-06T00:53:57.701907Z","end":"2026-05-06T00:53:58.348956Z","steps":["trace[1333406756] 'agreement among raft nodes before linearized reading' (duration: 646.676477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T00:53:58.349074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:57.814334Z","time spent":"534.728148ms","remote":"127.0.0.1:47862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-05-06T00:53:58.349113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T00:53:57.701878Z","time spent":"647.21251ms","remote":"127.0.0.1:47692","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\" "}