{"level":"warn","ts":"2026-07-09T09:16:56.279212Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T09:16:56.279315Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T09:16:56.27939Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T09:16:56.279402Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-09T09:16:56.279424Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T09:16:56.280159Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-07-09T09:16:56.280247Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T09:16:56.29265Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.188181ms"} {"level":"info","ts":"2026-07-09T09:16:56.304971Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-07-09T09:16:56.30506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T09:16:56.305083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-07-09T09:16:56.305106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T09:16:56.305117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-07-09T09:16:56.305158Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-07-09T09:16:56.318366Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T09:16:56.32218Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T09:16:56.323596Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T09:16:56.327443Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T09:16:56.327609Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T09:16:56.328007Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T09:16:56.32806Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T09:16:56.327803Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T09:16:56.329541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-07-09T09:16:56.333588Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-07-09T09:16:56.33521Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T09:16:56.335563Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T09:16:56.336733Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T09:16:56.336838Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-09T09:16:56.336885Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-07-09T09:16:56.906301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T09:16:56.906373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T09:16:56.906399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-07-09T09:16:56.90642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-07-09T09:16:56.906433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-09T09:16:56.90645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-07-09T09:16:56.906466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-07-09T09:16:56.909452Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T09:16:56.944045Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T09:16:56.944048Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T09:16:56.944171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T09:16:56.944365Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T09:16:56.944434Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T09:16:56.948033Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T09:16:56.948108Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-07-09T09:16:56.948211Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T09:16:56.948328Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T09:16:56.948378Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-09T09:17:07.383219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.113071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T09:17:07.383367Z","caller":"traceutil/trace.go:171","msg":"trace[850602442] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"362.690616ms","start":"2026-07-09T09:17:07.020657Z","end":"2026-07-09T09:17:07.383348Z","steps":["trace[850602442] 'process raft request' (duration: 123.606674ms)","trace[850602442] 'compare' (duration: 237.710153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:07.383508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:07.020626Z","time spent":"362.784647ms","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":649,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:17:07.38373Z","caller":"traceutil/trace.go:171","msg":"trace[650955330] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:322; }","duration":"320.600513ms","start":"2026-07-09T09:17:07.063099Z","end":"2026-07-09T09:17:07.383699Z","steps":["trace[650955330] 'read index received' (duration: 81.191904ms)","trace[650955330] 'applied index is now lower than readState.Index' (duration: 239.407529ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:07.383946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.829637ms","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-07-09T09:17:07.384058Z","caller":"traceutil/trace.go:171","msg":"trace[1913072950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:310; }","duration":"320.983741ms","start":"2026-07-09T09:17:07.063055Z","end":"2026-07-09T09:17:07.384038Z","steps":["trace[1913072950] 'agreement among raft nodes before linearized reading' (duration: 320.760436ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:07.38406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.351278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-07-09T09:17:07.38413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:07.063037Z","time spent":"321.081754ms","remote":"127.0.0.1:51896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-07-09T09:17:07.383963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.150878ms","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-07-09T09:17:07.384168Z","caller":"traceutil/trace.go:171","msg":"trace[1327753319] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:310; }","duration":"269.427933ms","start":"2026-07-09T09:17:07.114732Z","end":"2026-07-09T09:17:07.38416Z","steps":["trace[1327753319] 'agreement among raft nodes before linearized reading' (duration: 269.157998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:07.38413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.367958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-07-09T09:17:07.384228Z","caller":"traceutil/trace.go:171","msg":"trace[1321648385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:310; }","duration":"119.523331ms","start":"2026-07-09T09:17:07.264685Z","end":"2026-07-09T09:17:07.384208Z","steps":["trace[1321648385] 'agreement among raft nodes before linearized reading' (duration: 119.340918ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:07.384134Z","caller":"traceutil/trace.go:171","msg":"trace[651194438] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:310; }","duration":"220.476401ms","start":"2026-07-09T09:17:07.163642Z","end":"2026-07-09T09:17:07.384119Z","steps":["trace[651194438] 'agreement among raft nodes before linearized reading' (duration: 220.383929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:07.384079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.116497ms","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-07-09T09:17:07.384317Z","caller":"traceutil/trace.go:171","msg":"trace[357436335] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:310; }","duration":"170.389333ms","start":"2026-07-09T09:17:07.213917Z","end":"2026-07-09T09:17:07.384307Z","steps":["trace[357436335] 'agreement among raft nodes before linearized reading' (duration: 170.098857ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:07.383782Z","caller":"traceutil/trace.go:171","msg":"trace[1260695109] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"357.53905ms","start":"2026-07-09T09:17:07.026226Z","end":"2026-07-09T09:17:07.383765Z","steps":["trace[1260695109] 'process raft request' (duration: 357.322476ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:07.385561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:07.026193Z","time spent":"358.789755ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4781,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:17:13.209946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.543486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-07-09T09:17:13.210194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.091116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T09:17:13.210123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.673954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T09:17:13.210299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.432266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T09:17:13.210334Z","caller":"traceutil/trace.go:171","msg":"trace[721790527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"494.93719ms","start":"2026-07-09T09:17:12.715383Z","end":"2026-07-09T09:17:13.21032Z","steps":["trace[721790527] 'range keys from in-memory index tree' (duration: 494.262034ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:13.210301Z","caller":"traceutil/trace.go:171","msg":"trace[2076786998] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:371; }","duration":"191.894514ms","start":"2026-07-09T09:17:13.018299Z","end":"2026-07-09T09:17:13.210194Z","steps":["trace[2076786998] 'range keys from in-memory index tree' (duration: 191.318561ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:13.210398Z","caller":"traceutil/trace.go:171","msg":"trace[464903403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"217.594079ms","start":"2026-07-09T09:17:12.992773Z","end":"2026-07-09T09:17:13.210367Z","steps":["trace[464903403] 'range keys from in-memory index tree' (duration: 217.11906ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:13.21041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:12.715355Z","time spent":"495.025131ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T09:17:13.210275Z","caller":"traceutil/trace.go:171","msg":"trace[821804012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"561.204458ms","start":"2026-07-09T09:17:12.649053Z","end":"2026-07-09T09:17:13.210257Z","steps":["trace[821804012] 'range keys from in-memory index tree' (duration: 560.754589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:13.211139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:12.649026Z","time spent":"562.074367ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T09:17:14.180968Z","caller":"traceutil/trace.go:171","msg":"trace[1065837577] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"964.147463ms","start":"2026-07-09T09:17:13.216776Z","end":"2026-07-09T09:17:14.180923Z","steps":["trace[1065837577] 'process raft request' (duration: 963.96737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:14.181351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:13.216743Z","time spent":"964.344938ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:17:14.741906Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:15.242617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076056,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:15.742934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076056,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T09:17:15.883815Z","caller":"traceutil/trace.go:171","msg":"trace[499639959] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"2.666760259s","start":"2026-07-09T09:17:13.217011Z","end":"2026-07-09T09:17:15.883771Z","steps":["trace[499639959] 'process raft request' (duration: 2.666559145s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:15.884029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:13.216982Z","time spent":"2.666927703s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:17:15.884384Z","caller":"traceutil/trace.go:171","msg":"trace[1712355183] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"2.66630876s","start":"2026-07-09T09:17:13.218013Z","end":"2026-07-09T09:17:15.884322Z","steps":["trace[1712355183] 'process raft request' (duration: 2.665706358s)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:15.884486Z","caller":"traceutil/trace.go:171","msg":"trace[499349411] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:388; }","duration":"1.643652113s","start":"2026-07-09T09:17:14.240796Z","end":"2026-07-09T09:17:15.884448Z","steps":["trace[499349411] 'read index received' (duration: 1.642902988s)","trace[499349411] 'applied index is now lower than readState.Index' (duration: 745.005µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:17:15.884753Z","caller":"traceutil/trace.go:171","msg":"trace[1564576364] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"2.662273065s","start":"2026-07-09T09:17:13.222437Z","end":"2026-07-09T09:17:15.88471Z","steps":["trace[1564576364] 'process raft request' (duration: 2.661847937s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:15.88494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.644146343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:15.885Z","caller":"traceutil/trace.go:171","msg":"trace[759922777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"1.644249015s","start":"2026-07-09T09:17:14.240739Z","end":"2026-07-09T09:17:15.884989Z","steps":["trace[759922777] 'agreement among raft nodes before linearized reading' (duration: 1.644162283s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:15.885038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:14.240708Z","time spent":"1.644315597s","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T09:17:15.885025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:13.218Z","time spent":"2.666892582s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:17:15.885129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:13.222412Z","time spent":"2.662435559s","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:17:16.746301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.404072ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-09T09:17:16.746462Z","caller":"traceutil/trace.go:171","msg":"trace[1662389061] linearizableReadLoop","detail":"{readStateIndex:392; appliedIndex:391; }","duration":"861.840709ms","start":"2026-07-09T09:17:15.884584Z","end":"2026-07-09T09:17:16.746424Z","steps":["trace[1662389061] 'read index received' (duration: 7.242122ms)","trace[1662389061] 'applied index is now lower than readState.Index' (duration: 854.596966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:16.746604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.427755785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T09:17:16.746551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.568450589s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:16.746639Z","caller":"traceutil/trace.go:171","msg":"trace[2133842457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"2.427858287s","start":"2026-07-09T09:17:14.318769Z","end":"2026-07-09T09:17:16.746628Z","steps":["trace[2133842457] 'agreement among raft nodes before linearized reading' (duration: 2.427744734s)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:16.746703Z","caller":"traceutil/trace.go:171","msg":"trace[1218629111] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:375; }","duration":"1.568633823s","start":"2026-07-09T09:17:15.17805Z","end":"2026-07-09T09:17:16.746684Z","steps":["trace[1218629111] 'agreement among raft nodes before linearized reading' (duration: 1.568404818s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:16.746709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.115079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T09:17:16.746717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:14.318742Z","time spent":"2.427966159s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T09:17:16.74674Z","caller":"traceutil/trace.go:171","msg":"trace[137869730] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"560.14591ms","start":"2026-07-09T09:17:16.186587Z","end":"2026-07-09T09:17:16.746733Z","steps":["trace[137869730] 'agreement among raft nodes before linearized reading' (duration: 560.091329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:16.746758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:16.186505Z","time spent":"560.248732ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T09:17:16.746659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.577502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:16.746841Z","caller":"traceutil/trace.go:171","msg":"trace[191151287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"860.809956ms","start":"2026-07-09T09:17:15.88599Z","end":"2026-07-09T09:17:16.7468Z","steps":["trace[191151287] 'agreement among raft nodes before linearized reading' (duration: 860.586772ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:16.747028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:15.885973Z","time spent":"861.002411ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T09:17:16.884598Z","caller":"traceutil/trace.go:171","msg":"trace[23281887] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"132.338693ms","start":"2026-07-09T09:17:16.752229Z","end":"2026-07-09T09:17:16.884568Z","steps":["trace[23281887] 'process raft request' (duration: 132.082958ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:16.891242Z","caller":"traceutil/trace.go:171","msg":"trace[693230731] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:392; }","duration":"135.196964ms","start":"2026-07-09T09:17:16.755982Z","end":"2026-07-09T09:17:16.891178Z","steps":["trace[693230731] 'read index received' (duration: 128.36311ms)","trace[693230731] 'applied index is now lower than readState.Index' (duration: 6.831804ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:17:16.891323Z","caller":"traceutil/trace.go:171","msg":"trace[1961874376] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"138.943493ms","start":"2026-07-09T09:17:16.752362Z","end":"2026-07-09T09:17:16.891306Z","steps":["trace[1961874376] 'process raft request' (duration: 138.543394ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:16.891339Z","caller":"traceutil/trace.go:171","msg":"trace[1746765158] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"134.603951ms","start":"2026-07-09T09:17:16.756704Z","end":"2026-07-09T09:17:16.891308Z","steps":["trace[1746765158] 'process raft request' (duration: 134.398427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:16.891418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.422138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:16.891527Z","caller":"traceutil/trace.go:171","msg":"trace[635744617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"135.560811ms","start":"2026-07-09T09:17:16.755945Z","end":"2026-07-09T09:17:16.891506Z","steps":["trace[635744617] 'agreement among raft nodes before linearized reading' (duration: 135.401208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:42.675168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.677619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-09T09:17:42.675253Z","caller":"traceutil/trace.go:171","msg":"trace[449105857] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:590; }","duration":"242.833542ms","start":"2026-07-09T09:17:42.432398Z","end":"2026-07-09T09:17:42.675232Z","steps":["trace[449105857] 'range keys from in-memory index tree' (duration: 242.474964ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:42.675606Z","caller":"traceutil/trace.go:171","msg":"trace[675125925] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"127.958631ms","start":"2026-07-09T09:17:42.547636Z","end":"2026-07-09T09:17:42.675595Z","steps":["trace[675125925] 'process raft request' (duration: 120.702599ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:43.497752Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:43.998196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076460,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:44.244703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.358302664s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T09:17:44.255767Z","caller":"traceutil/trace.go:171","msg":"trace[1778570072] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"1.369935843s","start":"2026-07-09T09:17:42.885784Z","end":"2026-07-09T09:17:44.25572Z","steps":["trace[1778570072] 'process raft request' (duration: 1.359216774s)","trace[1778570072] 'compare' (duration: 10.288399ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:44.256148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:42.885762Z","time spent":"1.370114046s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:17:44.257063Z","caller":"traceutil/trace.go:171","msg":"trace[1576314861] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"1.283494214s","start":"2026-07-09T09:17:42.973549Z","end":"2026-07-09T09:17:44.257043Z","steps":["trace[1576314861] 'process raft request' (duration: 1.283265919s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.257159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:42.973521Z","time spent":"1.283589996s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:17:44.257228Z","caller":"traceutil/trace.go:171","msg":"trace[1734674932] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:622; }","duration":"1.26030696s","start":"2026-07-09T09:17:42.996895Z","end":"2026-07-09T09:17:44.257202Z","steps":["trace[1734674932] 'read index received' (duration: 1.2481424s)","trace[1734674932] 'applied index is now lower than readState.Index' (duration: 12.16392ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:17:44.257279Z","caller":"traceutil/trace.go:171","msg":"trace[1703147327] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.106835538s","start":"2026-07-09T09:17:43.150429Z","end":"2026-07-09T09:17:44.257264Z","steps":["trace[1703147327] 'process raft request' (duration: 1.106685624s)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:44.257327Z","caller":"traceutil/trace.go:171","msg":"trace[841247589] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"909.124342ms","start":"2026-07-09T09:17:43.348189Z","end":"2026-07-09T09:17:44.257313Z","steps":["trace[841247589] 'process raft request' (duration: 908.958118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.257346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.150396Z","time spent":"1.10691493s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T09:17:44.257461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.348157Z","time spent":"909.197224ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:17:44.257626Z","caller":"traceutil/trace.go:171","msg":"trace[1569748270] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.227726277s","start":"2026-07-09T09:17:43.029876Z","end":"2026-07-09T09:17:44.257603Z","steps":["trace[1569748270] 'process raft request' (duration: 1.227079062s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.257798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.725706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T09:17:44.257795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.029831Z","time spent":"1.22786511s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:17:44.257845Z","caller":"traceutil/trace.go:171","msg":"trace[1959323400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"207.830419ms","start":"2026-07-09T09:17:44.05Z","end":"2026-07-09T09:17:44.257831Z","steps":["trace[1959323400] 'agreement among raft nodes before linearized reading' (duration: 207.663915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.25806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.802053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-07-09T09:17:44.258093Z","caller":"traceutil/trace.go:171","msg":"trace[2127518964] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:604; }","duration":"112.870134ms","start":"2026-07-09T09:17:44.145212Z","end":"2026-07-09T09:17:44.258083Z","steps":["trace[2127518964] 'agreement among raft nodes before linearized reading' (duration: 112.792602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.258228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.915161ms","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-07-09T09:17:44.258313Z","caller":"traceutil/trace.go:171","msg":"trace[225208931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"204.030973ms","start":"2026-07-09T09:17:44.054262Z","end":"2026-07-09T09:17:44.258293Z","steps":["trace[225208931] 'agreement among raft nodes before linearized reading' (duration: 203.758177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.258314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.966742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:44.258354Z","caller":"traceutil/trace.go:171","msg":"trace[1157498548] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"713.006422ms","start":"2026-07-09T09:17:43.545338Z","end":"2026-07-09T09:17:44.258344Z","steps":["trace[1157498548] 'agreement among raft nodes before linearized reading' (duration: 712.947401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.258387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.545311Z","time spent":"713.068973ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-09T09:17:44.258332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.490087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:44.258452Z","caller":"traceutil/trace.go:171","msg":"trace[2052129041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"513.670221ms","start":"2026-07-09T09:17:43.744773Z","end":"2026-07-09T09:17:44.258443Z","steps":["trace[2052129041] 'agreement among raft nodes before linearized reading' (duration: 513.509557ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:44.258331Z","caller":"traceutil/trace.go:171","msg":"trace[1908377995] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"310.161415ms","start":"2026-07-09T09:17:43.947492Z","end":"2026-07-09T09:17:44.257653Z","steps":["trace[1908377995] 'process raft request' (duration: 309.683854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.25857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083314885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-09T09:17:44.258583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209130652s","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-07-09T09:17:44.258711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261808284s","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:7708"} {"level":"info","ts":"2026-07-09T09:17:44.258766Z","caller":"traceutil/trace.go:171","msg":"trace[964273925] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:604; }","duration":"1.261891956s","start":"2026-07-09T09:17:42.996856Z","end":"2026-07-09T09:17:44.258748Z","steps":["trace[964273925] 'agreement among raft nodes before linearized reading' (duration: 1.261746403s)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:44.258757Z","caller":"traceutil/trace.go:171","msg":"trace[935522376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"1.209338087s","start":"2026-07-09T09:17:43.049407Z","end":"2026-07-09T09:17:44.258745Z","steps":["trace[935522376] 'agreement among raft nodes before linearized reading' (duration: 1.208208102s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.25849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.665413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T09:17:44.258846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:42.996837Z","time spent":"1.261963467s","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7730,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-07-09T09:17:44.258882Z","caller":"traceutil/trace.go:171","msg":"trace[1681706188] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"296.100743ms","start":"2026-07-09T09:17:43.962772Z","end":"2026-07-09T09:17:44.258872Z","steps":["trace[1681706188] 'agreement among raft nodes before linearized reading' (duration: 295.602372ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:44.25861Z","caller":"traceutil/trace.go:171","msg":"trace[163784584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"1.083427278s","start":"2026-07-09T09:17:43.175172Z","end":"2026-07-09T09:17:44.258599Z","steps":["trace[163784584] 'agreement among raft nodes before linearized reading' (duration: 1.083328316s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:44.259017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.175147Z","time spent":"1.083833756s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T09:17:44.258655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.947457Z","time spent":"311.084246ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T09:17:44.258836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.04939Z","time spent":"1.20943658s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T09:17:44.25849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:43.744746Z","time spent":"513.731941ms","remote":"127.0.0.1:51724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-07-09T09:17:45.384514Z","caller":"traceutil/trace.go:171","msg":"trace[1512480585] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"157.772319ms","start":"2026-07-09T09:17:45.226048Z","end":"2026-07-09T09:17:45.383821Z","steps":["trace[1512480585] 'process raft request' (duration: 157.586334ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:45.664395Z","caller":"traceutil/trace.go:171","msg":"trace[933630222] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"289.526749ms","start":"2026-07-09T09:17:45.374814Z","end":"2026-07-09T09:17:45.664341Z","steps":["trace[933630222] 'process raft request' (duration: 234.265446ms)","trace[933630222] 'compare' (duration: 54.851784ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:17:45.701685Z","caller":"traceutil/trace.go:171","msg":"trace[1392322418] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"156.066381ms","start":"2026-07-09T09:17:45.54559Z","end":"2026-07-09T09:17:45.701657Z","steps":["trace[1392322418] 'read index received' (duration: 63.340123ms)","trace[1392322418] 'applied index is now lower than readState.Index' (duration: 92.725078ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:45.702081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.45931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-09T09:17:45.702124Z","caller":"traceutil/trace.go:171","msg":"trace[1571962921] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:623; }","duration":"156.579903ms","start":"2026-07-09T09:17:45.545532Z","end":"2026-07-09T09:17:45.702112Z","steps":["trace[1571962921] 'agreement among raft nodes before linearized reading' (duration: 156.223715ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:45.707014Z","caller":"traceutil/trace.go:171","msg":"trace[2116964728] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"242.615562ms","start":"2026-07-09T09:17:45.464334Z","end":"2026-07-09T09:17:45.70695Z","steps":["trace[2116964728] 'process raft request' (duration: 237.019767ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:46.310483Z","caller":"traceutil/trace.go:171","msg":"trace[1742202644] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"109.134668ms","start":"2026-07-09T09:17:46.201313Z","end":"2026-07-09T09:17:46.310448Z","steps":["trace[1742202644] 'process raft request' (duration: 96.881974ms)","trace[1742202644] 'compare' (duration: 11.991098ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:47.221318Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076539,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T09:17:47.524436Z","caller":"traceutil/trace.go:171","msg":"trace[1531979244] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"862.882924ms","start":"2026-07-09T09:17:46.661491Z","end":"2026-07-09T09:17:47.524374Z","steps":["trace[1531979244] 'process raft request' (duration: 862.204718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.524772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.661424Z","time spent":"863.160529ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:17:47.531655Z","caller":"traceutil/trace.go:171","msg":"trace[700818689] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:659; }","duration":"810.926302ms","start":"2026-07-09T09:17:46.720692Z","end":"2026-07-09T09:17:47.531619Z","steps":["trace[700818689] 'read index received' (duration: 802.281829ms)","trace[700818689] 'applied index is now lower than readState.Index' (duration: 8.642883ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:17:47.531746Z","caller":"traceutil/trace.go:171","msg":"trace[417309552] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"720.732138ms","start":"2026-07-09T09:17:46.810971Z","end":"2026-07-09T09:17:47.531703Z","steps":["trace[417309552] 'process raft request' (duration: 720.478712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.531883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.031695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.531885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.136956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.53194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.810936Z","time spent":"720.899182ms","remote":"127.0.0.1:51854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:17:47.53195Z","caller":"traceutil/trace.go:171","msg":"trace[1006124149] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:636; }","duration":"811.148967ms","start":"2026-07-09T09:17:46.720781Z","end":"2026-07-09T09:17:47.53193Z","steps":["trace[1006124149] 'agreement among raft nodes before linearized reading' (duration: 811.037264ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:47.53197Z","caller":"traceutil/trace.go:171","msg":"trace[400153197] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:636; }","duration":"811.346871ms","start":"2026-07-09T09:17:46.7206Z","end":"2026-07-09T09:17:47.531947Z","steps":["trace[400153197] 'agreement among raft nodes before linearized reading' (duration: 811.168167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.720573Z","time spent":"811.464764ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:17:47.532053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.720774Z","time spent":"811.226329ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"warn","ts":"2026-07-09T09:17:47.532072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.365357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532129Z","caller":"traceutil/trace.go:171","msg":"trace[1992759789] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:636; }","duration":"651.45201ms","start":"2026-07-09T09:17:46.880659Z","end":"2026-07-09T09:17:47.532111Z","steps":["trace[1992759789] 'agreement among raft nodes before linearized reading' (duration: 651.329897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.880633Z","time spent":"651.525622ms","remote":"127.0.0.1:47074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T09:17:47.532236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.784247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.532284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.397852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532303Z","caller":"traceutil/trace.go:171","msg":"trace[1276109910] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"604.861459ms","start":"2026-07-09T09:17:46.927429Z","end":"2026-07-09T09:17:47.532291Z","steps":["trace[1276109910] 'agreement among raft nodes before linearized reading' (duration: 604.772247ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:47.532324Z","caller":"traceutil/trace.go:171","msg":"trace[1363589529] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:636; }","duration":"794.483895ms","start":"2026-07-09T09:17:46.737826Z","end":"2026-07-09T09:17:47.53231Z","steps":["trace[1363589529] 'agreement among raft nodes before linearized reading' (duration: 794.406083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.737799Z","time spent":"794.547806ms","remote":"127.0.0.1:47060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-07-09T09:17:47.532368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.927426Z","time spent":"604.90479ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:17:47.532408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.975052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.532422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.844424ms","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:5"} {"level":"info","ts":"2026-07-09T09:17:47.532444Z","caller":"traceutil/trace.go:171","msg":"trace[603777378] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"605.062894ms","start":"2026-07-09T09:17:46.927371Z","end":"2026-07-09T09:17:47.532434Z","steps":["trace[603777378] 'agreement among raft nodes before linearized reading' (duration: 604.983951ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:47.532459Z","caller":"traceutil/trace.go:171","msg":"trace[1129774957] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:636; }","duration":"794.902775ms","start":"2026-07-09T09:17:46.737546Z","end":"2026-07-09T09:17:47.532449Z","steps":["trace[1129774957] 'agreement among raft nodes before linearized reading' (duration: 794.819822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.92735Z","time spent":"605.115375ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T09:17:47.532483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.619887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532514Z","caller":"traceutil/trace.go:171","msg":"trace[787235430] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:636; }","duration":"703.704628ms","start":"2026-07-09T09:17:46.8288Z","end":"2026-07-09T09:17:47.532505Z","steps":["trace[787235430] 'agreement among raft nodes before linearized reading' (duration: 703.658587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.58545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.532539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.828738Z","time spent":"703.79351ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-07-09T09:17:47.532559Z","caller":"traceutil/trace.go:171","msg":"trace[1029177253] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:636; }","duration":"795.669401ms","start":"2026-07-09T09:17:46.73688Z","end":"2026-07-09T09:17:47.532549Z","steps":["trace[1029177253] 'agreement among raft nodes before linearized reading' (duration: 795.62233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.736858Z","time spent":"795.721323ms","remote":"127.0.0.1:47060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T09:17:47.532627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.87845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532657Z","caller":"traceutil/trace.go:171","msg":"trace[1537514511] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:636; }","duration":"651.918451ms","start":"2026-07-09T09:17:46.88073Z","end":"2026-07-09T09:17:47.532648Z","steps":["trace[1537514511] 'agreement among raft nodes before linearized reading' (duration: 651.870849ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.880726Z","time spent":"651.947562ms","remote":"127.0.0.1:47074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:17:47.532683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.879692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532721Z","caller":"traceutil/trace.go:171","msg":"trace[1207271710] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:636; }","duration":"703.917913ms","start":"2026-07-09T09:17:46.828796Z","end":"2026-07-09T09:17:47.532714Z","steps":["trace[1207271710] 'agreement among raft nodes before linearized reading' (duration: 703.866062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.828753Z","time spent":"703.983334ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-07-09T09:17:47.532799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.068215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T09:17:47.532826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.784388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.532841Z","caller":"traceutil/trace.go:171","msg":"trace[385651822] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:636; }","duration":"652.122136ms","start":"2026-07-09T09:17:46.88071Z","end":"2026-07-09T09:17:47.532832Z","steps":["trace[385651822] 'agreement among raft nodes before linearized reading' (duration: 652.060354ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:17:47.532857Z","caller":"traceutil/trace.go:171","msg":"trace[408898889] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:636; }","duration":"604.826809ms","start":"2026-07-09T09:17:46.928022Z","end":"2026-07-09T09:17:47.532848Z","steps":["trace[408898889] 'agreement among raft nodes before linearized reading' (duration: 604.761618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.532867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.880706Z","time spent":"652.152606ms","remote":"127.0.0.1:47074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-07-09T09:17:47.532887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.928018Z","time spent":"604.86195ms","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"warn","ts":"2026-07-09T09:17:47.533045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.197231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.533085Z","caller":"traceutil/trace.go:171","msg":"trace[602505755] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:636; }","duration":"812.248902ms","start":"2026-07-09T09:17:46.720824Z","end":"2026-07-09T09:17:47.533073Z","steps":["trace[602505755] 'agreement among raft nodes before linearized reading' (duration: 812.196391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.533113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.720819Z","time spent":"812.284174ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T09:17:47.532486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.737466Z","time spent":"795.010767ms","remote":"127.0.0.1:47060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:17:47.533423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.70979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:17:47.533468Z","caller":"traceutil/trace.go:171","msg":"trace[62318856] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:636; }","duration":"704.791772ms","start":"2026-07-09T09:17:46.828663Z","end":"2026-07-09T09:17:47.533455Z","steps":["trace[62318856] 'agreement among raft nodes before linearized reading' (duration: 704.732061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:47.533494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:46.828644Z","time spent":"704.843083ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"warn","ts":"2026-07-09T09:17:52.745977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:53.246882Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:53.747154Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:54.244962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000000923s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T09:17:54.245148Z","caller":"traceutil/trace.go:171","msg":"trace[536535529] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000253029s","start":"2026-07-09T09:17:52.244864Z","end":"2026-07-09T09:17:54.245117Z","steps":["trace[536535529] 'agreement among raft nodes before linearized reading' (duration: 2.000045624s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:54.24524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.244836Z","time spent":"2.000386852s","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 09:17:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T09:17:54.248364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:54.748903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:55.249271Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:55.750119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276103584773076618,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T09:17:56.214116Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.629602647s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T09:17:56.214677Z","caller":"traceutil/trace.go:171","msg":"trace[1601361486] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"4.071468485s","start":"2026-07-09T09:17:52.143171Z","end":"2026-07-09T09:17:56.21464Z","steps":["trace[1601361486] 'process raft request' (duration: 4.071172109s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.214894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.143137Z","time spent":"4.071635618s","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:17:56.245441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000030285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T09:17:56.245538Z","caller":"traceutil/trace.go:171","msg":"trace[678290276] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000204419s","start":"2026-07-09T09:17:54.24531Z","end":"2026-07-09T09:17:56.245514Z","steps":["trace[678290276] 'agreement among raft nodes before linearized reading' (duration: 2.000084126s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.245603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:54.245277Z","time spent":"2.000314212s","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 09:17:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-07-09T09:17:56.256969Z","caller":"traceutil/trace.go:171","msg":"trace[1073834445] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"3.351087635s","start":"2026-07-09T09:17:52.905841Z","end":"2026-07-09T09:17:56.256929Z","steps":["trace[1073834445] 'process raft request' (duration: 3.35086415s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.257111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.905808Z","time spent":"3.351226289s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:17:56.279204Z","caller":"traceutil/trace.go:171","msg":"trace[853962495] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:696; }","duration":"4.034244803s","start":"2026-07-09T09:17:52.244922Z","end":"2026-07-09T09:17:56.279167Z","steps":["trace[853962495] 'read index received' (duration: 3.969394974s)","trace[853962495] 'applied index is now lower than readState.Index' (duration: 64.847439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:17:56.280858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.927440197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T09:17:56.28094Z","caller":"traceutil/trace.go:171","msg":"trace[810360214] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:673; }","duration":"3.92759688s","start":"2026-07-09T09:17:52.353321Z","end":"2026-07-09T09:17:56.280918Z","steps":["trace[810360214] 'agreement among raft nodes before linearized reading' (duration: 3.927307704s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.280973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.870029012s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T09:17:56.280859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.867340752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T09:17:56.281031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.353281Z","time spent":"3.927722633s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T09:17:56.281076Z","caller":"traceutil/trace.go:171","msg":"trace[385574290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"3.867623898s","start":"2026-07-09T09:17:52.413429Z","end":"2026-07-09T09:17:56.281053Z","steps":["trace[385574290] 'agreement among raft nodes before linearized reading' (duration: 3.867235899s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.28113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.4134Z","time spent":"3.86771429s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-09T09:17:56.281024Z","caller":"traceutil/trace.go:171","msg":"trace[106873350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"3.870098914s","start":"2026-07-09T09:17:52.410909Z","end":"2026-07-09T09:17:56.281008Z","steps":["trace[106873350] 'agreement among raft nodes before linearized reading' (duration: 3.870000362s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.281225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.410894Z","time spent":"3.87032391s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T09:17:56.28088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.905272437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T09:17:56.281328Z","caller":"traceutil/trace.go:171","msg":"trace[1938497113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"3.905749788s","start":"2026-07-09T09:17:52.375568Z","end":"2026-07-09T09:17:56.281318Z","steps":["trace[1938497113] 'agreement among raft nodes before linearized reading' (duration: 3.905162624s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.281359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.375553Z","time spent":"3.90579701s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-09T09:17:56.282202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.907060948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T09:17:56.282348Z","caller":"traceutil/trace.go:171","msg":"trace[559288407] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"3.907274332s","start":"2026-07-09T09:17:52.375045Z","end":"2026-07-09T09:17:56.282319Z","steps":["trace[559288407] 'agreement among raft nodes before linearized reading' (duration: 3.905828109s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:17:56.283074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:17:52.37502Z","time spent":"3.908034439s","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T09:18:14.310457Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.037666305s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T09:18:14.311031Z","caller":"traceutil/trace.go:171","msg":"trace[1519154790] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"1.03828753s","start":"2026-07-09T09:18:13.272661Z","end":"2026-07-09T09:18:14.310949Z","steps":["trace[1519154790] 'process raft request' (duration: 1.038056535s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:14.311284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:13.272635Z","time spent":"1.038458474s","remote":"127.0.0.1:51924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:14.589798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.106186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-09T09:18:14.589798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.198781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-09T09:18:14.5899Z","caller":"traceutil/trace.go:171","msg":"trace[382471513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"185.373454ms","start":"2026-07-09T09:18:14.404505Z","end":"2026-07-09T09:18:14.589879Z","steps":["trace[382471513] 'range keys from in-memory index tree' (duration: 185.004176ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:14.589995Z","caller":"traceutil/trace.go:171","msg":"trace[410298052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"184.322981ms","start":"2026-07-09T09:18:14.405658Z","end":"2026-07-09T09:18:14.589981Z","steps":["trace[410298052] 'range keys from in-memory index tree' (duration: 183.963792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:14.589798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.096132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T09:18:14.590145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.701329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T09:18:14.5903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.17865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T09:18:14.590372Z","caller":"traceutil/trace.go:171","msg":"trace[944285920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"184.956995ms","start":"2026-07-09T09:18:14.4054Z","end":"2026-07-09T09:18:14.590357Z","steps":["trace[944285920] 'range keys from in-memory index tree' (duration: 184.485064ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:14.590528Z","caller":"traceutil/trace.go:171","msg":"trace[462922846] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"185.452256ms","start":"2026-07-09T09:18:14.404953Z","end":"2026-07-09T09:18:14.590405Z","steps":["trace[462922846] 'range keys from in-memory index tree' (duration: 184.704059ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:14.59018Z","caller":"traceutil/trace.go:171","msg":"trace[712921226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"183.442719ms","start":"2026-07-09T09:18:14.406648Z","end":"2026-07-09T09:18:14.59009Z","steps":["trace[712921226] 'range keys from in-memory index tree' (duration: 182.869827ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:16.808799Z","caller":"traceutil/trace.go:171","msg":"trace[298864240] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"166.516185ms","start":"2026-07-09T09:18:16.642248Z","end":"2026-07-09T09:18:16.808764Z","steps":["trace[298864240] 'process raft request' (duration: 123.561341ms)","trace[298864240] 'compare' (duration: 42.732249ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:16.809345Z","caller":"traceutil/trace.go:171","msg":"trace[978826911] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"166.984746ms","start":"2026-07-09T09:18:16.642336Z","end":"2026-07-09T09:18:16.80932Z","steps":["trace[978826911] 'process raft request' (duration: 166.378122ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:16.809515Z","caller":"traceutil/trace.go:171","msg":"trace[952349797] linearizableReadLoop","detail":"{readStateIndex:841; appliedIndex:837; }","duration":"104.843657ms","start":"2026-07-09T09:18:16.70456Z","end":"2026-07-09T09:18:16.809403Z","steps":["trace[952349797] 'read index received' (duration: 18.222222ms)","trace[952349797] 'applied index is now lower than readState.Index' (duration: 86.619525ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:16.809628Z","caller":"traceutil/trace.go:171","msg":"trace[1248669] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"167.164181ms","start":"2026-07-09T09:18:16.642446Z","end":"2026-07-09T09:18:16.809611Z","steps":["trace[1248669] 'process raft request' (duration: 166.777061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:16.810015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.429592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T09:18:16.810091Z","caller":"traceutil/trace.go:171","msg":"trace[1740258791] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:808; }","duration":"105.613116ms","start":"2026-07-09T09:18:16.704457Z","end":"2026-07-09T09:18:16.81007Z","steps":["trace[1740258791] 'agreement among raft nodes before linearized reading' (duration: 105.221027ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:22.978336Z","caller":"traceutil/trace.go:171","msg":"trace[806059793] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:912; }","duration":"133.220051ms","start":"2026-07-09T09:18:22.845088Z","end":"2026-07-09T09:18:22.978308Z","steps":["trace[806059793] 'read index received' (duration: 14.291573ms)","trace[806059793] 'applied index is now lower than readState.Index' (duration: 118.927418ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:22.978473Z","caller":"traceutil/trace.go:171","msg":"trace[1073026305] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"208.396291ms","start":"2026-07-09T09:18:22.770064Z","end":"2026-07-09T09:18:22.97846Z","steps":["trace[1073026305] 'process raft request' (duration: 208.088634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:22.978681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.938445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T09:18:22.978758Z","caller":"traceutil/trace.go:171","msg":"trace[754613867] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:879; }","duration":"115.099519ms","start":"2026-07-09T09:18:22.863642Z","end":"2026-07-09T09:18:22.978741Z","steps":["trace[754613867] 'agreement among raft nodes before linearized reading' (duration: 114.908365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:22.978994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.881907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T09:18:22.979003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.104193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T09:18:22.97903Z","caller":"traceutil/trace.go:171","msg":"trace[1284961414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:879; }","duration":"133.983329ms","start":"2026-07-09T09:18:22.845037Z","end":"2026-07-09T09:18:22.97902Z","steps":["trace[1284961414] 'agreement among raft nodes before linearized reading' (duration: 133.810645ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:22.979058Z","caller":"traceutil/trace.go:171","msg":"trace[594549877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:879; }","duration":"110.198395ms","start":"2026-07-09T09:18:22.868844Z","end":"2026-07-09T09:18:22.979042Z","steps":["trace[594549877] 'agreement among raft nodes before linearized reading' (duration: 110.057312ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:23.580409Z","caller":"traceutil/trace.go:171","msg":"trace[2006929774] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"128.41426ms","start":"2026-07-09T09:18:23.451954Z","end":"2026-07-09T09:18:23.580368Z","steps":["trace[2006929774] 'process raft request' (duration: 128.223736ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:24.539378Z","caller":"traceutil/trace.go:171","msg":"trace[1916620106] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"223.138783ms","start":"2026-07-09T09:18:24.316202Z","end":"2026-07-09T09:18:24.53934Z","steps":["trace[1916620106] 'process raft request' (duration: 222.913578ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:24.804698Z","caller":"traceutil/trace.go:171","msg":"trace[1745363554] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"178.686356ms","start":"2026-07-09T09:18:24.625974Z","end":"2026-07-09T09:18:24.80466Z","steps":["trace[1745363554] 'process raft request' (duration: 107.328032ms)","trace[1745363554] 'compare' (duration: 71.21413ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:24.970617Z","caller":"traceutil/trace.go:171","msg":"trace[1539997617] linearizableReadLoop","detail":"{readStateIndex:928; appliedIndex:925; }","duration":"301.052779ms","start":"2026-07-09T09:18:24.669534Z","end":"2026-07-09T09:18:24.970586Z","steps":["trace[1539997617] 'read index received' (duration: 63.736536ms)","trace[1539997617] 'applied index is now lower than readState.Index' (duration: 237.314983ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:24.970945Z","caller":"traceutil/trace.go:171","msg":"trace[1345640260] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"344.720417ms","start":"2026-07-09T09:18:24.626037Z","end":"2026-07-09T09:18:24.970757Z","steps":["trace[1345640260] 'process raft request' (duration: 290.196835ms)","trace[1345640260] 'compare' (duration: 54.088132ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:24.971163Z","caller":"traceutil/trace.go:171","msg":"trace[1687212522] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"343.342305ms","start":"2026-07-09T09:18:24.627799Z","end":"2026-07-09T09:18:24.971142Z","steps":["trace[1687212522] 'process raft request' (duration: 342.70501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:24.97119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.096448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T09:18:24.971183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.589232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T09:18:24.971223Z","caller":"traceutil/trace.go:171","msg":"trace[1963929597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"249.17616ms","start":"2026-07-09T09:18:24.722036Z","end":"2026-07-09T09:18:24.971212Z","steps":["trace[1963929597] 'agreement among raft nodes before linearized reading' (duration: 249.045987ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:24.971228Z","caller":"traceutil/trace.go:171","msg":"trace[343458490] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:894; }","duration":"301.743435ms","start":"2026-07-09T09:18:24.669476Z","end":"2026-07-09T09:18:24.971219Z","steps":["trace[343458490] 'agreement among raft nodes before linearized reading' (duration: 301.581151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:24.971205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:24.625932Z","time spent":"345.149007ms","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1012,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:24.971254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:24.669458Z","time spent":"301.788476ms","remote":"127.0.0.1:51964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":3,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:18:24.971301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:24.62779Z","time spent":"343.420518ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:25.271081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.104101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T09:18:25.271248Z","caller":"traceutil/trace.go:171","msg":"trace[1533770774] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"291.060611ms","start":"2026-07-09T09:18:24.980155Z","end":"2026-07-09T09:18:25.271216Z","steps":["trace[1533770774] 'process raft request' (duration: 149.693773ms)","trace[1533770774] 'compare' (duration: 140.907477ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:25.306762Z","caller":"traceutil/trace.go:171","msg":"trace[192968338] linearizableReadLoop","detail":"{readStateIndex:930; appliedIndex:928; }","duration":"323.973869ms","start":"2026-07-09T09:18:24.982748Z","end":"2026-07-09T09:18:25.306721Z","steps":["trace[192968338] 'read index received' (duration: 147.120333ms)","trace[192968338] 'applied index is now lower than readState.Index' (duration: 176.851606ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:25.306926Z","caller":"traceutil/trace.go:171","msg":"trace[1693232413] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"325.133625ms","start":"2026-07-09T09:18:24.981728Z","end":"2026-07-09T09:18:25.306862Z","steps":["trace[1693232413] 'process raft request' (duration: 324.766777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.307486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:24.981691Z","time spent":"325.436523ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:25.307613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.818978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T09:18:25.307687Z","caller":"traceutil/trace.go:171","msg":"trace[611501826] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:896; }","duration":"324.926451ms","start":"2026-07-09T09:18:24.982742Z","end":"2026-07-09T09:18:25.307668Z","steps":["trace[611501826] 'agreement among raft nodes before linearized reading' (duration: 324.178264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.307919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:24.982703Z","time spent":"325.088235ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-09T09:18:25.498218Z","caller":"traceutil/trace.go:171","msg":"trace[1201386381] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:930; }","duration":"191.315123ms","start":"2026-07-09T09:18:25.306867Z","end":"2026-07-09T09:18:25.498182Z","steps":["trace[1201386381] 'read index received' (duration: 178.594857ms)","trace[1201386381] 'applied index is now lower than readState.Index' (duration: 12.718606ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:25.498374Z","caller":"traceutil/trace.go:171","msg":"trace[1189228336] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"222.806958ms","start":"2026-07-09T09:18:25.275553Z","end":"2026-07-09T09:18:25.49836Z","steps":["trace[1189228336] 'process raft request' (duration: 209.851337ms)","trace[1189228336] 'compare' (duration: 12.308876ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:18:25.498532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.785008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:18:25.498602Z","caller":"traceutil/trace.go:171","msg":"trace[1744330517] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:898; }","duration":"486.926981ms","start":"2026-07-09T09:18:25.011655Z","end":"2026-07-09T09:18:25.498582Z","steps":["trace[1744330517] 'agreement among raft nodes before linearized reading' (duration: 486.760488ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.498574Z","caller":"traceutil/trace.go:171","msg":"trace[1690298078] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"218.876197ms","start":"2026-07-09T09:18:25.279645Z","end":"2026-07-09T09:18:25.498521Z","steps":["trace[1690298078] 'process raft request' (duration: 218.430667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.498652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.011618Z","time spent":"487.024034ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-07-09T09:18:25.498717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.073331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T09:18:25.498821Z","caller":"traceutil/trace.go:171","msg":"trace[1070251873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"474.257586ms","start":"2026-07-09T09:18:25.024544Z","end":"2026-07-09T09:18:25.498801Z","steps":["trace[1070251873] 'agreement among raft nodes before linearized reading' (duration: 473.875447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.49889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.024531Z","time spent":"474.343517ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T09:18:25.498851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.75428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T09:18:25.499054Z","caller":"traceutil/trace.go:171","msg":"trace[54911284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"494.044047ms","start":"2026-07-09T09:18:25.004989Z","end":"2026-07-09T09:18:25.499033Z","steps":["trace[54911284] 'agreement among raft nodes before linearized reading' (duration: 493.455383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.498906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.14865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2kj6v\" ","response":"range_response_count:1 size:4872"} {"level":"info","ts":"2026-07-09T09:18:25.499153Z","caller":"traceutil/trace.go:171","msg":"trace[1262858118] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2kj6v; range_end:; response_count:1; response_revision:898; }","duration":"224.432246ms","start":"2026-07-09T09:18:25.274705Z","end":"2026-07-09T09:18:25.499137Z","steps":["trace[1262858118] 'agreement among raft nodes before linearized reading' (duration: 224.077628ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.499207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.004961Z","time spent":"494.235002ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T09:18:25.499715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.116216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-09T09:18:25.500481Z","caller":"traceutil/trace.go:171","msg":"trace[1440070834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"475.926535ms","start":"2026-07-09T09:18:25.024541Z","end":"2026-07-09T09:18:25.500467Z","steps":["trace[1440070834] 'agreement among raft nodes before linearized reading' (duration: 475.030134ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.500559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.024523Z","time spent":"476.023077ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T09:18:25.500592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.400102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-qcn27\" ","response":"range_response_count:1 size:5086"} {"level":"info","ts":"2026-07-09T09:18:25.500739Z","caller":"traceutil/trace.go:171","msg":"trace[909237625] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-qcn27; range_end:; response_count:1; response_revision:899; }","duration":"184.522445ms","start":"2026-07-09T09:18:25.316139Z","end":"2026-07-09T09:18:25.500662Z","steps":["trace[909237625] 'agreement among raft nodes before linearized reading' (duration: 184.322301ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.500939Z","caller":"traceutil/trace.go:171","msg":"trace[1847378006] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"185.643231ms","start":"2026-07-09T09:18:25.315145Z","end":"2026-07-09T09:18:25.500788Z","steps":["trace[1847378006] 'process raft request' (duration: 185.210211ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.716067Z","caller":"traceutil/trace.go:171","msg":"trace[1990975221] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"213.598664ms","start":"2026-07-09T09:18:25.50243Z","end":"2026-07-09T09:18:25.716028Z","steps":["trace[1990975221] 'process raft request' (duration: 197.422666ms)","trace[1990975221] 'compare' (duration: 15.941092ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:25.788284Z","caller":"traceutil/trace.go:171","msg":"trace[1775797699] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"282.765828ms","start":"2026-07-09T09:18:25.505485Z","end":"2026-07-09T09:18:25.788251Z","steps":["trace[1775797699] 'process raft request' (duration: 282.674966ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.788344Z","caller":"traceutil/trace.go:171","msg":"trace[1753205868] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"283.70591ms","start":"2026-07-09T09:18:25.504592Z","end":"2026-07-09T09:18:25.788298Z","steps":["trace[1753205868] 'process raft request' (duration: 283.355322ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.788692Z","caller":"traceutil/trace.go:171","msg":"trace[2062518704] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"281.58183ms","start":"2026-07-09T09:18:25.507082Z","end":"2026-07-09T09:18:25.788664Z","steps":["trace[2062518704] 'process raft request' (duration: 281.12347ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.788827Z","caller":"traceutil/trace.go:171","msg":"trace[628866463] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"277.826252ms","start":"2026-07-09T09:18:25.510989Z","end":"2026-07-09T09:18:25.788815Z","steps":["trace[628866463] 'process raft request' (duration: 277.509734ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.789923Z","caller":"traceutil/trace.go:171","msg":"trace[1519361005] linearizableReadLoop","detail":"{readStateIndex:940; appliedIndex:933; }","duration":"188.010836ms","start":"2026-07-09T09:18:25.600943Z","end":"2026-07-09T09:18:25.788954Z","steps":["trace[1519361005] 'read index received' (duration: 98.931017ms)","trace[1519361005] 'applied index is now lower than readState.Index' (duration: 89.078739ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:25.789943Z","caller":"traceutil/trace.go:171","msg":"trace[850888654] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"203.327383ms","start":"2026-07-09T09:18:25.5865Z","end":"2026-07-09T09:18:25.789828Z","steps":["trace[850888654] 'process raft request' (duration: 202.218368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:25.789997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.06172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:18:25.790131Z","caller":"traceutil/trace.go:171","msg":"trace[242600698] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:906; }","duration":"189.259125ms","start":"2026-07-09T09:18:25.600857Z","end":"2026-07-09T09:18:25.790116Z","steps":["trace[242600698] 'agreement among raft nodes before linearized reading' (duration: 189.085201ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:25.79021Z","caller":"traceutil/trace.go:171","msg":"trace[163125329] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"279.220364ms","start":"2026-07-09T09:18:25.510972Z","end":"2026-07-09T09:18:25.790193Z","steps":["trace[163125329] 'process raft request' (duration: 277.689599ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:26.175208Z","caller":"traceutil/trace.go:171","msg":"trace[1278056810] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"453.652338ms","start":"2026-07-09T09:18:25.721526Z","end":"2026-07-09T09:18:26.175178Z","steps":["trace[1278056810] 'process raft request' (duration: 452.687555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.175376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.721499Z","time spent":"453.784202ms","remote":"127.0.0.1:51844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":741,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:18:26.17541Z","caller":"traceutil/trace.go:171","msg":"trace[1038002218] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"452.284617ms","start":"2026-07-09T09:18:25.723112Z","end":"2026-07-09T09:18:26.175396Z","steps":["trace[1038002218] 'process raft request' (duration: 452.016461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.175502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.723101Z","time spent":"452.351209ms","remote":"127.0.0.1:51964","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:18:26.176037Z","caller":"traceutil/trace.go:171","msg":"trace[1447711699] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"451.60466ms","start":"2026-07-09T09:18:25.724419Z","end":"2026-07-09T09:18:26.176023Z","steps":["trace[1447711699] 'process raft request' (duration: 450.932874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.176132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.724405Z","time spent":"451.699383ms","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:18:26.176096Z","caller":"traceutil/trace.go:171","msg":"trace[1459002343] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:940; }","duration":"379.57785ms","start":"2026-07-09T09:18:25.796487Z","end":"2026-07-09T09:18:26.176065Z","steps":["trace[1459002343] 'read index received' (duration: 377.763457ms)","trace[1459002343] 'applied index is now lower than readState.Index' (duration: 1.813143ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:18:26.176159Z","caller":"traceutil/trace.go:171","msg":"trace[2027506512] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"451.709603ms","start":"2026-07-09T09:18:25.724433Z","end":"2026-07-09T09:18:26.176143Z","steps":["trace[2027506512] 'process raft request' (duration: 451.423066ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:26.176059Z","caller":"traceutil/trace.go:171","msg":"trace[851065594] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"452.644365ms","start":"2026-07-09T09:18:25.722868Z","end":"2026-07-09T09:18:26.175512Z","steps":["trace[851065594] 'process raft request' (duration: 452.214315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.17638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.724405Z","time spent":"451.842906ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3852,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:26.176285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.801265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2kj6v\" ","response":"range_response_count:1 size:4884"} {"level":"info","ts":"2026-07-09T09:18:26.176575Z","caller":"traceutil/trace.go:171","msg":"trace[1812943566] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-2kj6v; range_end:; response_count:1; response_revision:911; }","duration":"380.090821ms","start":"2026-07-09T09:18:25.796436Z","end":"2026-07-09T09:18:26.176526Z","steps":["trace[1812943566] 'agreement among raft nodes before linearized reading' (duration: 379.754464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.176697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.796415Z","time spent":"380.240504ms","remote":"127.0.0.1:51870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4906,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-2kj6v\" "} {"level":"warn","ts":"2026-07-09T09:18:26.177533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.72283Z","time spent":"453.72016ms","remote":"127.0.0.1:51760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T09:18:26.394481Z","caller":"traceutil/trace.go:171","msg":"trace[928687787] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"595.669027ms","start":"2026-07-09T09:18:25.798758Z","end":"2026-07-09T09:18:26.394427Z","steps":["trace[928687787] 'process raft request' (duration: 591.300064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.394733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.798728Z","time spent":"595.85951ms","remote":"127.0.0.1:52198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3888,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T09:18:26.472589Z","caller":"traceutil/trace.go:171","msg":"trace[1262170091] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:945; }","duration":"296.35618ms","start":"2026-07-09T09:18:26.176195Z","end":"2026-07-09T09:18:26.472551Z","steps":["trace[1262170091] 'read index received' (duration: 213.860973ms)","trace[1262170091] 'applied index is now lower than readState.Index' (duration: 82.493287ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:18:26.472901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.979558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:18:26.472989Z","caller":"traceutil/trace.go:171","msg":"trace[681521411] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:912; }","duration":"675.132232ms","start":"2026-07-09T09:18:25.797839Z","end":"2026-07-09T09:18:26.472971Z","steps":["trace[681521411] 'agreement among raft nodes before linearized reading' (duration: 674.972188ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.473064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:25.797821Z","time spent":"675.229464ms","remote":"127.0.0.1:51774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"info","ts":"2026-07-09T09:18:26.473507Z","caller":"traceutil/trace.go:171","msg":"trace[332605145] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"290.904813ms","start":"2026-07-09T09:18:26.182476Z","end":"2026-07-09T09:18:26.473381Z","steps":["trace[332605145] 'process raft request' (duration: 290.190427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.473608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.791066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:18:26.473756Z","caller":"traceutil/trace.go:171","msg":"trace[903966650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:915; }","duration":"232.969651ms","start":"2026-07-09T09:18:26.240727Z","end":"2026-07-09T09:18:26.473697Z","steps":["trace[903966650] 'agreement among raft nodes before linearized reading' (duration: 232.707965ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:26.473876Z","caller":"traceutil/trace.go:171","msg":"trace[1167976996] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"290.652826ms","start":"2026-07-09T09:18:26.183207Z","end":"2026-07-09T09:18:26.473859Z","steps":["trace[1167976996] 'process raft request' (duration: 290.141084ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:26.473959Z","caller":"traceutil/trace.go:171","msg":"trace[280047228] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"291.365563ms","start":"2026-07-09T09:18:26.182582Z","end":"2026-07-09T09:18:26.473947Z","steps":["trace[280047228] 'process raft request' (duration: 290.689888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.474307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.639926ms","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-07-09T09:18:26.474443Z","caller":"traceutil/trace.go:171","msg":"trace[329517200] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:915; }","duration":"105.842902ms","start":"2026-07-09T09:18:26.368562Z","end":"2026-07-09T09:18:26.474405Z","steps":["trace[329517200] 'agreement among raft nodes before linearized reading' (duration: 105.605185ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:26.988808Z","caller":"traceutil/trace.go:171","msg":"trace[2032637903] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"503.99989ms","start":"2026-07-09T09:18:26.484778Z","end":"2026-07-09T09:18:26.988778Z","steps":["trace[2032637903] 'process raft request' (duration: 503.818066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:26.988991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:26.484761Z","time spent":"504.131762ms","remote":"127.0.0.1:52170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4204,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:27.165022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.770115ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T09:18:27.165163Z","caller":"traceutil/trace.go:171","msg":"trace[129332393] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"606.619648ms","start":"2026-07-09T09:18:26.558517Z","end":"2026-07-09T09:18:27.165136Z","steps":["trace[129332393] 'process raft request' (duration: 496.630878ms)","trace[129332393] 'compare' (duration: 109.599111ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:18:27.165548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:18:26.558473Z","time spent":"606.732211ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:18:27.929465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.920913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T09:18:27.929551Z","caller":"traceutil/trace.go:171","msg":"trace[1355119022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:919; }","duration":"135.073317ms","start":"2026-07-09T09:18:27.794459Z","end":"2026-07-09T09:18:27.929533Z","steps":["trace[1355119022] 'range keys from in-memory index tree' (duration: 134.719539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:27.929775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.842071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T09:18:27.929808Z","caller":"traceutil/trace.go:171","msg":"trace[353291058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:919; }","duration":"134.896373ms","start":"2026-07-09T09:18:27.794904Z","end":"2026-07-09T09:18:27.929801Z","steps":["trace[353291058] 'range keys from in-memory index tree' (duration: 134.762069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:18:27.930272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.939137ms","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-07-09T09:18:27.930378Z","caller":"traceutil/trace.go:171","msg":"trace[586980421] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:919; }","duration":"133.07589ms","start":"2026-07-09T09:18:27.797281Z","end":"2026-07-09T09:18:27.930357Z","steps":["trace[586980421] 'range keys from in-memory index tree' (duration: 132.788653ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:27.978927Z","caller":"traceutil/trace.go:171","msg":"trace[1651153880] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"180.149291ms","start":"2026-07-09T09:18:27.798745Z","end":"2026-07-09T09:18:27.978894Z","steps":["trace[1651153880] 'process raft request' (duration: 179.908965ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:27.980068Z","caller":"traceutil/trace.go:171","msg":"trace[769776380] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"173.256699ms","start":"2026-07-09T09:18:27.806791Z","end":"2026-07-09T09:18:27.980048Z","steps":["trace[769776380] 'process raft request' (duration: 173.015834ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:18:27.980574Z","caller":"traceutil/trace.go:171","msg":"trace[1538478534] linearizableReadLoop","detail":"{readStateIndex:956; appliedIndex:954; }","duration":"108.544177ms","start":"2026-07-09T09:18:27.872003Z","end":"2026-07-09T09:18:27.980547Z","steps":["trace[1538478534] 'read index received' (duration: 106.639013ms)","trace[1538478534] 'applied index is now lower than readState.Index' (duration: 1.903224ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:18:27.980672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.68098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:18:27.980716Z","caller":"traceutil/trace.go:171","msg":"trace[2002672801] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:924; }","duration":"108.787772ms","start":"2026-07-09T09:18:27.871909Z","end":"2026-07-09T09:18:27.980697Z","steps":["trace[2002672801] 'agreement among raft nodes before linearized reading' (duration: 108.70158ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:04.501376Z","caller":"traceutil/trace.go:171","msg":"trace[419895066] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"327.887179ms","start":"2026-07-09T09:19:04.173458Z","end":"2026-07-09T09:19:04.501345Z","steps":["trace[419895066] 'read index received' (duration: 327.405675ms)","trace[419895066] 'applied index is now lower than readState.Index' (duration: 480.514µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:19:04.501492Z","caller":"traceutil/trace.go:171","msg":"trace[1097596246] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"378.871151ms","start":"2026-07-09T09:19:04.122578Z","end":"2026-07-09T09:19:04.501449Z","steps":["trace[1097596246] 'process raft request' (duration: 378.479652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:19:04.501627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.133462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T09:19:04.501672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.55435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:19:04.501724Z","caller":"traceutil/trace.go:171","msg":"trace[1034941175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"260.688458ms","start":"2026-07-09T09:19:04.241021Z","end":"2026-07-09T09:19:04.50171Z","steps":["trace[1034941175] 'agreement among raft nodes before linearized reading' (duration: 260.574462ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:04.501713Z","caller":"traceutil/trace.go:171","msg":"trace[1898430993] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1087; }","duration":"328.318191ms","start":"2026-07-09T09:19:04.173375Z","end":"2026-07-09T09:19:04.501693Z","steps":["trace[1898430993] 'agreement among raft nodes before linearized reading' (duration: 328.122771ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:19:04.501735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.571244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-07-09T09:19:04.501775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:19:04.173346Z","time spent":"328.416815ms","remote":"127.0.0.1:47070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-09T09:19:04.501804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.48419ms","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-07-09T09:19:04.501819Z","caller":"traceutil/trace.go:171","msg":"trace[2074395353] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"266.69106ms","start":"2026-07-09T09:19:04.235106Z","end":"2026-07-09T09:19:04.501797Z","steps":["trace[2074395353] 'agreement among raft nodes before linearized reading' (duration: 266.512471ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:04.501842Z","caller":"traceutil/trace.go:171","msg":"trace[1758818767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"266.556834ms","start":"2026-07-09T09:19:04.235274Z","end":"2026-07-09T09:19:04.501831Z","steps":["trace[1758818767] 'agreement among raft nodes before linearized reading' (duration: 266.348003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:19:04.502296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:19:04.122562Z","time spent":"379.011849ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T09:19:04.502405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.098571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T09:19:04.502467Z","caller":"traceutil/trace.go:171","msg":"trace[1260332776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"310.192895ms","start":"2026-07-09T09:19:04.192261Z","end":"2026-07-09T09:19:04.502454Z","steps":["trace[1260332776] 'agreement among raft nodes before linearized reading' (duration: 309.281639ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:19:04.502523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:19:04.192244Z","time spent":"310.259328ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T09:19:18.863325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.296484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T09:19:18.863633Z","caller":"traceutil/trace.go:171","msg":"trace[944003148] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"156.79161ms","start":"2026-07-09T09:19:18.706805Z","end":"2026-07-09T09:19:18.863597Z","steps":["trace[944003148] 'process raft request' (duration: 52.101595ms)","trace[944003148] 'compare' (duration: 104.102326ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:19:19.993377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.686363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-09T09:19:19.993538Z","caller":"traceutil/trace.go:171","msg":"trace[1614247602] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1137; }","duration":"135.896213ms","start":"2026-07-09T09:19:19.857578Z","end":"2026-07-09T09:19:19.993474Z","steps":["trace[1614247602] 'range keys from in-memory index tree' (duration: 135.360417ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:27.945601Z","caller":"traceutil/trace.go:171","msg":"trace[2035857030] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"129.790679ms","start":"2026-07-09T09:19:27.815777Z","end":"2026-07-09T09:19:27.945568Z","steps":["trace[2035857030] 'process raft request' (duration: 129.585309ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:28.795596Z","caller":"traceutil/trace.go:171","msg":"trace[1113482993] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"144.586878ms","start":"2026-07-09T09:19:28.650977Z","end":"2026-07-09T09:19:28.795564Z","steps":["trace[1113482993] 'process raft request' (duration: 123.781209ms)","trace[1113482993] 'compare' (duration: 20.300136ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:19:30.601002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.880359ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:19:30.60124Z","caller":"traceutil/trace.go:171","msg":"trace[349891820] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1171; }","duration":"121.110879ms","start":"2026-07-09T09:19:30.480063Z","end":"2026-07-09T09:19:30.601174Z","steps":["trace[349891820] 'range keys from in-memory index tree' (duration: 120.851148ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:30.601212Z","caller":"traceutil/trace.go:171","msg":"trace[289864001] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1221; }","duration":"176.142493ms","start":"2026-07-09T09:19:30.425032Z","end":"2026-07-09T09:19:30.601174Z","steps":["trace[289864001] 'read index received' (duration: 152.899669ms)","trace[289864001] 'applied index is now lower than readState.Index' (duration: 23.240554ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:19:30.601231Z","caller":"traceutil/trace.go:171","msg":"trace[886618730] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"191.815598ms","start":"2026-07-09T09:19:30.409378Z","end":"2026-07-09T09:19:30.601194Z","steps":["trace[886618730] 'process raft request' (duration: 168.517712ms)","trace[886618730] 'compare' (duration: 22.911619ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:19:30.601418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.345002ms","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-07-09T09:19:30.60146Z","caller":"traceutil/trace.go:171","msg":"trace[41834677] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1172; }","duration":"176.455008ms","start":"2026-07-09T09:19:30.424992Z","end":"2026-07-09T09:19:30.601447Z","steps":["trace[41834677] 'agreement among raft nodes before linearized reading' (duration: 176.30072ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:30.977182Z","caller":"traceutil/trace.go:171","msg":"trace[237109968] transaction","detail":"{read_only:false; response_revision:1174; number_of_response:1; }","duration":"119.17252ms","start":"2026-07-09T09:19:30.857961Z","end":"2026-07-09T09:19:30.977133Z","steps":["trace[237109968] 'process raft request' (duration: 37.015091ms)","trace[237109968] 'compare' (duration: 81.883477ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:19:49.282458Z","caller":"traceutil/trace.go:171","msg":"trace[1420074180] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"125.945017ms","start":"2026-07-09T09:19:49.156478Z","end":"2026-07-09T09:19:49.282423Z","steps":["trace[1420074180] 'read index received' (duration: 34.169691ms)","trace[1420074180] 'applied index is now lower than readState.Index' (duration: 91.773746ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T09:19:49.282586Z","caller":"traceutil/trace.go:171","msg":"trace[1819651597] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"121.354476ms","start":"2026-07-09T09:19:49.161221Z","end":"2026-07-09T09:19:49.282576Z","steps":["trace[1819651597] 'process raft request' (duration: 121.150358ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:19:49.282502Z","caller":"traceutil/trace.go:171","msg":"trace[6341007] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"198.367988ms","start":"2026-07-09T09:19:49.084108Z","end":"2026-07-09T09:19:49.282476Z","steps":["trace[6341007] 'process raft request' (duration: 106.565851ms)","trace[6341007] 'compare' (duration: 91.490364ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:19:49.282781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.330907ms","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-07-09T09:19:49.28287Z","caller":"traceutil/trace.go:171","msg":"trace[661822029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"157.474893ms","start":"2026-07-09T09:19:49.125372Z","end":"2026-07-09T09:19:49.282847Z","steps":["trace[661822029] 'agreement among raft nodes before linearized reading' (duration: 157.226462ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:20:12.442683Z","caller":"traceutil/trace.go:171","msg":"trace[901096595] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"101.076832ms","start":"2026-07-09T09:20:12.341563Z","end":"2026-07-09T09:20:12.44264Z","steps":["trace[901096595] 'process raft request' (duration: 100.879464ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T09:20:18.64096Z","caller":"traceutil/trace.go:171","msg":"trace[1898648222] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"120.310696ms","start":"2026-07-09T09:20:18.520606Z","end":"2026-07-09T09:20:18.640916Z","steps":["trace[1898648222] 'process raft request' (duration: 75.230637ms)","trace[1898648222] 'compare' (duration: 44.898322ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T09:20:19.826016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.897255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T09:20:19.826135Z","caller":"traceutil/trace.go:171","msg":"trace[501201712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1368; }","duration":"378.138165ms","start":"2026-07-09T09:20:19.447972Z","end":"2026-07-09T09:20:19.82611Z","steps":["trace[501201712] 'range keys from in-memory index tree' (duration: 377.600204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:20:19.826187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:20:19.447949Z","time spent":"378.228249ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T09:20:19.826291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.554586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T09:20:19.8264Z","caller":"traceutil/trace.go:171","msg":"trace[1098327767] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1368; }","duration":"167.739194ms","start":"2026-07-09T09:20:19.658637Z","end":"2026-07-09T09:20:19.826377Z","steps":["trace[1098327767] 'count revisions from in-memory index tree' (duration: 167.422801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:20:19.826413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.048436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-09T09:20:19.826522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.564787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-09T09:20:19.826526Z","caller":"traceutil/trace.go:171","msg":"trace[825514078] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1368; }","duration":"282.166071ms","start":"2026-07-09T09:20:19.544305Z","end":"2026-07-09T09:20:19.826471Z","steps":["trace[825514078] 'range keys from in-memory index tree' (duration: 281.624859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:20:19.826828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.926951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T09:20:19.826558Z","caller":"traceutil/trace.go:171","msg":"trace[474807573] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1368; }","duration":"511.653621ms","start":"2026-07-09T09:20:19.314896Z","end":"2026-07-09T09:20:19.826549Z","steps":["trace[474807573] 'range keys from in-memory index tree' (duration: 511.423542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:20:19.826953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:20:19.314861Z","time spent":"512.083948ms","remote":"127.0.0.1:51958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T09:20:19.826943Z","caller":"traceutil/trace.go:171","msg":"trace[690142508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1368; }","duration":"585.114348ms","start":"2026-07-09T09:20:19.241805Z","end":"2026-07-09T09:20:19.826919Z","steps":["trace[690142508] 'range keys from in-memory index tree' (duration: 584.760325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T09:20:19.827045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T09:20:19.241774Z","time spent":"585.256564ms","remote":"127.0.0.1:51660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T09:20:20.922289Z","caller":"traceutil/trace.go:171","msg":"trace[2013442786] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"130.46531ms","start":"2026-07-09T09:20:20.791788Z","end":"2026-07-09T09:20:20.922253Z","steps":["trace[2013442786] 'process raft request' (duration: 129.896327ms)"],"step_count":1}