{"level":"warn","ts":"2026-05-28T02:00:52.665021Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T02:00:52.665095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.140: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.140:2380","--initial-cluster=instance=https://199.204.45.140:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.140:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.140:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-28T02:00:52.665163Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-28T02:00:52.665176Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-28T02:00:52.665195Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T02:00:52.66579Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"]} {"level":"info","ts":"2026-05-28T02:00:52.665867Z","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.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.140:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-28T02:00:52.670119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.058269ms"} {"level":"info","ts":"2026-05-28T02:00:52.677216Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"35b2766da46a76bb","cluster-id":"f5fd3aeb8d9f53dc"} {"level":"info","ts":"2026-05-28T02:00:52.677316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=()"} {"level":"info","ts":"2026-05-28T02:00:52.677354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 0"} {"level":"info","ts":"2026-05-28T02:00:52.677389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 35b2766da46a76bb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-28T02:00:52.67742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became follower at term 1"} {"level":"info","ts":"2026-05-28T02:00:52.67748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"warn","ts":"2026-05-28T02:00:52.685654Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-28T02:00:52.687308Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-28T02:00:52.689343Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-28T02:00:52.696754Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"35b2766da46a76bb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-28T02:00:52.697617Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"35b2766da46a76bb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-28T02:00:52.697714Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T02:00:52.697797Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T02:00:52.697806Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-28T02:00:52.698021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb switched to configuration voters=(3869285243146630843)"} {"level":"info","ts":"2026-05-28T02:00:52.699186Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","added-peer-id":"35b2766da46a76bb","added-peer-peer-urls":["https://199.204.45.140:2380"]} {"level":"info","ts":"2026-05-28T02:00:52.700329Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-28T02:00:52.700503Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"35b2766da46a76bb","initial-advertise-peer-urls":["https://199.204.45.140:2380"],"listen-peer-urls":["https://199.204.45.140:2380"],"advertise-client-urls":["https://199.204.45.140:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.140:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-28T02:00:52.700543Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-28T02:00:52.700505Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-28T02:00:52.70056Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.140:2380"} {"level":"info","ts":"2026-05-28T02:00:53.578527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb is starting a new election at term 1"} {"level":"info","ts":"2026-05-28T02:00:53.578904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-28T02:00:53.579014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgPreVoteResp from 35b2766da46a76bb at term 1"} {"level":"info","ts":"2026-05-28T02:00:53.579057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became candidate at term 2"} {"level":"info","ts":"2026-05-28T02:00:53.579081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb received MsgVoteResp from 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-28T02:00:53.579111Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"35b2766da46a76bb became leader at term 2"} {"level":"info","ts":"2026-05-28T02:00:53.579131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 35b2766da46a76bb elected leader 35b2766da46a76bb at term 2"} {"level":"info","ts":"2026-05-28T02:00:53.580492Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"35b2766da46a76bb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.140:2379]}","request-path":"/0/members/35b2766da46a76bb/attributes","cluster-id":"f5fd3aeb8d9f53dc","publish-timeout":"7s"} {"level":"info","ts":"2026-05-28T02:00:53.580675Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T02:00:53.580751Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T02:00:53.580964Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-28T02:00:53.581587Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5fd3aeb8d9f53dc","local-member-id":"35b2766da46a76bb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T02:00:53.581677Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T02:00:53.581702Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-28T02:00:53.584798Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.140:2379"} {"level":"info","ts":"2026-05-28T02:00:53.584995Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-28T02:00:53.585048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-28T02:00:53.586953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-28T02:00:59.83721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.599595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:00:59.83732Z","caller":"traceutil/trace.go:171","msg":"trace[37639219] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:287; }","duration":"101.763423ms","start":"2026-05-28T02:00:59.735538Z","end":"2026-05-28T02:00:59.837301Z","steps":["trace[37639219] 'range keys from in-memory index tree' (duration: 101.530952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:02.905848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.615615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:01:02.905949Z","caller":"traceutil/trace.go:171","msg":"trace[147105902] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"290.182185ms","start":"2026-05-28T02:01:02.615747Z","end":"2026-05-28T02:01:02.90593Z","steps":["trace[147105902] 'process raft request' (duration: 104.12572ms)","trace[147105902] 'compare' (duration: 185.504421ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:03.168788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.543068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:01:03.168921Z","caller":"traceutil/trace.go:171","msg":"trace[1302529028] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"258.002004ms","start":"2026-05-28T02:01:02.910901Z","end":"2026-05-28T02:01:03.168903Z","steps":["trace[1302529028] 'process raft request' (duration: 100.267509ms)","trace[1302529028] 'compare' (duration: 157.363309ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:03.181484Z","caller":"traceutil/trace.go:171","msg":"trace[1898987533] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"270.502288ms","start":"2026-05-28T02:01:02.910961Z","end":"2026-05-28T02:01:03.181463Z","steps":["trace[1898987533] 'process raft request' (duration: 270.375382ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:03.181563Z","caller":"traceutil/trace.go:171","msg":"trace[121315113] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:349; }","duration":"246.21953ms","start":"2026-05-28T02:01:02.935327Z","end":"2026-05-28T02:01:03.181546Z","steps":["trace[121315113] 'read index received' (duration: 75.827243ms)","trace[121315113] 'applied index is now lower than readState.Index' (duration: 170.391207ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:03.181656Z","caller":"traceutil/trace.go:171","msg":"trace[1180185626] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"264.389689ms","start":"2026-05-28T02:01:02.917252Z","end":"2026-05-28T02:01:03.181642Z","steps":["trace[1180185626] 'process raft request' (duration: 264.248823ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:03.181646Z","caller":"traceutil/trace.go:171","msg":"trace[1575669104] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"270.605973ms","start":"2026-05-28T02:01:02.911015Z","end":"2026-05-28T02:01:03.181621Z","steps":["trace[1575669104] 'process raft request' (duration: 270.413724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.181738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.403918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T02:01:03.181908Z","caller":"traceutil/trace.go:171","msg":"trace[711346304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"246.565885ms","start":"2026-05-28T02:01:02.935314Z","end":"2026-05-28T02:01:03.18188Z","steps":["trace[711346304] 'agreement among raft nodes before linearized reading' (duration: 246.380017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.782502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.652402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:01:03.782611Z","caller":"traceutil/trace.go:171","msg":"trace[1414815377] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"600.822193ms","start":"2026-05-28T02:01:03.181772Z","end":"2026-05-28T02:01:03.782594Z","steps":["trace[1414815377] 'read index received' (duration: 238.807665ms)","trace[1414815377] 'applied index is now lower than readState.Index' (duration: 362.013378ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:03.78275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.49155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T02:01:03.782818Z","caller":"traceutil/trace.go:171","msg":"trace[1452159463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:343; }","duration":"844.576564ms","start":"2026-05-28T02:01:02.938224Z","end":"2026-05-28T02:01:03.782801Z","steps":["trace[1452159463] 'agreement among raft nodes before linearized reading' (duration: 844.449498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.782874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:02.938218Z","time spent":"844.642816ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T02:01:03.782927Z","caller":"traceutil/trace.go:171","msg":"trace[299568200] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"607.363677ms","start":"2026-05-28T02:01:03.175273Z","end":"2026-05-28T02:01:03.782637Z","steps":["trace[299568200] 'process raft request' (duration: 245.354259ms)","trace[299568200] 'compare' (duration: 361.518876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:03.783125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.175255Z","time spent":"607.753384ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:03.87869Z","caller":"traceutil/trace.go:171","msg":"trace[1268050025] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"693.258906ms","start":"2026-05-28T02:01:03.1854Z","end":"2026-05-28T02:01:03.878659Z","steps":["trace[1268050025] 'process raft request' (duration: 692.935432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.878884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.185383Z","time spent":"693.402161ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:03.878915Z","caller":"traceutil/trace.go:171","msg":"trace[129414453] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"690.048885ms","start":"2026-05-28T02:01:03.18885Z","end":"2026-05-28T02:01:03.878899Z","steps":["trace[129414453] 'process raft request' (duration: 689.736722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.878977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.188841Z","time spent":"690.105879ms","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T02:01:03.879139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.241384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T02:01:03.87924Z","caller":"traceutil/trace.go:171","msg":"trace[130193790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:347; }","duration":"619.400401ms","start":"2026-05-28T02:01:03.259821Z","end":"2026-05-28T02:01:03.879222Z","steps":["trace[130193790] 'agreement among raft nodes before linearized reading' (duration: 619.235563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.879279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.259769Z","time spent":"619.502226ms","remote":"127.0.0.1:33416","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-05-28T02:01:03.879307Z","caller":"traceutil/trace.go:171","msg":"trace[2132344744] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"689.615547ms","start":"2026-05-28T02:01:03.189677Z","end":"2026-05-28T02:01:03.879293Z","steps":["trace[2132344744] 'process raft request' (duration: 689.150286ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:03.879362Z","caller":"traceutil/trace.go:171","msg":"trace[2048298230] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"686.673229ms","start":"2026-05-28T02:01:03.192676Z","end":"2026-05-28T02:01:03.879349Z","steps":["trace[2048298230] 'process raft request' (duration: 686.251881ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.879383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.189669Z","time spent":"689.67085ms","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T02:01:03.879448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.192667Z","time spent":"686.739282ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:03.879477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.150842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T02:01:03.879555Z","caller":"traceutil/trace.go:171","msg":"trace[647918645] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:347; }","duration":"580.279947ms","start":"2026-05-28T02:01:03.299259Z","end":"2026-05-28T02:01:03.879539Z","steps":["trace[647918645] 'agreement among raft nodes before linearized reading' (duration: 580.151432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:03.879599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.299248Z","time spent":"580.343431ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T02:01:04.129742Z","caller":"traceutil/trace.go:171","msg":"trace[318613837] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"339.50093ms","start":"2026-05-28T02:01:03.790212Z","end":"2026-05-28T02:01:04.129713Z","steps":["trace[318613837] 'process raft request' (duration: 339.405856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:04.129917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.790203Z","time spent":"339.625675ms","remote":"127.0.0.1:33622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:04.129917Z","caller":"traceutil/trace.go:171","msg":"trace[786294003] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"342.224749ms","start":"2026-05-28T02:01:03.787665Z","end":"2026-05-28T02:01:04.12989Z","steps":["trace[786294003] 'process raft request' (duration: 332.772432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:04.13017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:03.787645Z","time spent":"342.432007ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:04.163143Z","caller":"traceutil/trace.go:171","msg":"trace[682878772] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"279.334706ms","start":"2026-05-28T02:01:03.883793Z","end":"2026-05-28T02:01:04.163128Z","steps":["trace[682878772] 'process raft request' (duration: 279.238172ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:04.163252Z","caller":"traceutil/trace.go:171","msg":"trace[791391050] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"279.43136ms","start":"2026-05-28T02:01:03.883815Z","end":"2026-05-28T02:01:04.163247Z","steps":["trace[791391050] 'process raft request' (duration: 279.291944ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:04.271211Z","caller":"traceutil/trace.go:171","msg":"trace[679206757] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"134.395948ms","start":"2026-05-28T02:01:04.1368Z","end":"2026-05-28T02:01:04.271196Z","steps":["trace[679206757] 'process raft request' (duration: 129.249177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:05.226472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.93232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-05-28T02:01:05.226569Z","caller":"traceutil/trace.go:171","msg":"trace[2030247046] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:354; }","duration":"762.066495ms","start":"2026-05-28T02:01:04.46448Z","end":"2026-05-28T02:01:05.226547Z","steps":["trace[2030247046] 'range keys from in-memory index tree' (duration: 761.833185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:05.226613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:04.464463Z","time spent":"762.140879ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4349,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-05-28T02:01:05.226817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.884167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4425"} {"level":"info","ts":"2026-05-28T02:01:05.226903Z","caller":"traceutil/trace.go:171","msg":"trace[33169699] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:354; }","duration":"752.000942ms","start":"2026-05-28T02:01:04.474878Z","end":"2026-05-28T02:01:05.226879Z","steps":["trace[33169699] 'range keys from in-memory index tree' (duration: 751.813134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:05.226951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:04.474865Z","time spent":"752.074855ms","remote":"127.0.0.1:33342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4448,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-05-28T02:01:05.227015Z","caller":"traceutil/trace.go:171","msg":"trace[1423872412] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"293.856768ms","start":"2026-05-28T02:01:04.933136Z","end":"2026-05-28T02:01:05.226993Z","steps":["trace[1423872412] 'process raft request' (duration: 272.648944ms)","trace[1423872412] 'compare' (duration: 20.209222ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:18.370353Z","caller":"traceutil/trace.go:171","msg":"trace[1195608117] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"157.364306ms","start":"2026-05-28T02:01:18.212968Z","end":"2026-05-28T02:01:18.370332Z","steps":["trace[1195608117] 'read index received' (duration: 157.20653ms)","trace[1195608117] 'applied index is now lower than readState.Index' (duration: 156.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:18.370487Z","caller":"traceutil/trace.go:171","msg":"trace[2146983481] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"398.974244ms","start":"2026-05-28T02:01:17.971478Z","end":"2026-05-28T02:01:18.370452Z","steps":["trace[2146983481] 'process raft request' (duration: 398.739175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:18.370666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:17.971456Z","time spent":"399.12126ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:18.370683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.696948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-05-28T02:01:18.370837Z","caller":"traceutil/trace.go:171","msg":"trace[1221109394] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:397; }","duration":"157.890085ms","start":"2026-05-28T02:01:18.212933Z","end":"2026-05-28T02:01:18.370824Z","steps":["trace[1221109394] 'agreement among raft nodes before linearized reading' (duration: 157.547552ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:19.070868Z","caller":"traceutil/trace.go:171","msg":"trace[459061129] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"696.721915ms","start":"2026-05-28T02:01:18.374122Z","end":"2026-05-28T02:01:19.070843Z","steps":["trace[459061129] 'process raft request' (duration: 678.091025ms)","trace[459061129] 'compare' (duration: 18.502886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:19.071143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:18.374109Z","time spent":"696.967265ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:19.071754Z","caller":"traceutil/trace.go:171","msg":"trace[1295346809] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"416.745673ms","start":"2026-05-28T02:01:18.654986Z","end":"2026-05-28T02:01:19.071732Z","steps":["trace[1295346809] 'process raft request' (duration: 416.711562ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:19.07193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:18.654968Z","time spent":"416.854778ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:19.072021Z","caller":"traceutil/trace.go:171","msg":"trace[608674662] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"696.202127ms","start":"2026-05-28T02:01:18.375776Z","end":"2026-05-28T02:01:19.071978Z","steps":["trace[608674662] 'process raft request' (duration: 695.850544ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:19.072075Z","caller":"traceutil/trace.go:171","msg":"trace[301019199] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"697.451843ms","start":"2026-05-28T02:01:18.374594Z","end":"2026-05-28T02:01:19.072046Z","steps":["trace[301019199] 'process raft request' (duration: 696.952565ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:19.072091Z","caller":"traceutil/trace.go:171","msg":"trace[1406611910] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"687.144027ms","start":"2026-05-28T02:01:18.384929Z","end":"2026-05-28T02:01:19.072073Z","steps":["trace[1406611910] 'process raft request' (duration: 686.728532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:19.072134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:18.374573Z","time spent":"697.530926ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:19.072192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:18.384915Z","time spent":"687.225391ms","remote":"127.0.0.1:33590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:19.07221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:18.375765Z","time spent":"696.327441ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:33.104896Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158764923,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-28T02:01:33.35561Z","caller":"traceutil/trace.go:171","msg":"trace[1069702700] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"797.622807ms","start":"2026-05-28T02:01:32.557943Z","end":"2026-05-28T02:01:33.355566Z","steps":["trace[1069702700] 'process raft request' (duration: 742.940246ms)","trace[1069702700] 'compare' (duration: 54.379971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:33.355847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:32.557918Z","time spent":"797.816033ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T02:01:33.595523Z","caller":"traceutil/trace.go:171","msg":"trace[1968382259] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:570; }","duration":"990.91321ms","start":"2026-05-28T02:01:32.604585Z","end":"2026-05-28T02:01:33.595498Z","steps":["trace[1968382259] 'read index received' (duration: 696.202521ms)","trace[1968382259] 'applied index is now lower than readState.Index' (duration: 294.709869ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:33.595612Z","caller":"traceutil/trace.go:171","msg":"trace[1870377377] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.03567463s","start":"2026-05-28T02:01:32.559896Z","end":"2026-05-28T02:01:33.595571Z","steps":["trace[1870377377] 'process raft request' (duration: 993.220987ms)","trace[1870377377] 'compare' (duration: 42.218995ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:33.595787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:32.559874Z","time spent":"1.035810564s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:33.595805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.150688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-28T02:01:33.595874Z","caller":"traceutil/trace.go:171","msg":"trace[455615388] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:548; }","duration":"991.270681ms","start":"2026-05-28T02:01:32.604568Z","end":"2026-05-28T02:01:33.595838Z","steps":["trace[455615388] 'agreement among raft nodes before linearized reading' (duration: 991.055144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:33.59592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:32.604534Z","time spent":"991.375915ms","remote":"127.0.0.1:33264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-28T02:01:34.055527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.883352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:01:34.055857Z","caller":"traceutil/trace.go:171","msg":"trace[909685272] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"460.241454ms","start":"2026-05-28T02:01:33.595597Z","end":"2026-05-28T02:01:34.055838Z","steps":["trace[909685272] 'read index received' (duration: 292.053622ms)","trace[909685272] 'applied index is now lower than readState.Index' (duration: 168.186662ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:34.055875Z","caller":"traceutil/trace.go:171","msg":"trace[1900765339] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"752.920737ms","start":"2026-05-28T02:01:33.302918Z","end":"2026-05-28T02:01:34.055839Z","steps":["trace[1900765339] 'process raft request' (duration: 584.642632ms)","trace[1900765339] 'compare' (duration: 167.739428ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:01:34.055996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"706.514906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:34.056063Z","caller":"traceutil/trace.go:171","msg":"trace[791252852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"706.620629ms","start":"2026-05-28T02:01:33.349423Z","end":"2026-05-28T02:01:34.056043Z","steps":["trace[791252852] 'agreement among raft nodes before linearized reading' (duration: 706.490325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.056096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.098548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-28T02:01:34.056116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.349404Z","time spent":"706.699672ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T02:01:34.05616Z","caller":"traceutil/trace.go:171","msg":"trace[874786445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"918.224252ms","start":"2026-05-28T02:01:33.13792Z","end":"2026-05-28T02:01:34.056144Z","steps":["trace[874786445] 'agreement among raft nodes before linearized reading' (duration: 918.100648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.056197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.137901Z","time spent":"918.287564ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:34.056178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.095087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T02:01:34.056349Z","caller":"traceutil/trace.go:171","msg":"trace[1051652310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"918.380907ms","start":"2026-05-28T02:01:33.13795Z","end":"2026-05-28T02:01:34.056331Z","steps":["trace[1051652310] 'agreement among raft nodes before linearized reading' (duration: 918.039516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.05645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.13792Z","time spent":"918.511901ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T02:01:34.056483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.302907Z","time spent":"753.501727ms","remote":"127.0.0.1:33220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":846,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T02:01:34.056369Z","caller":"traceutil/trace.go:171","msg":"trace[1811139515] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"648.284209ms","start":"2026-05-28T02:01:33.408066Z","end":"2026-05-28T02:01:34.05635Z","steps":["trace[1811139515] 'process raft request' (duration: 647.642688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.056304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"896.279877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T02:01:34.057908Z","caller":"traceutil/trace.go:171","msg":"trace[28687591] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"897.90936ms","start":"2026-05-28T02:01:33.15998Z","end":"2026-05-28T02:01:34.057889Z","steps":["trace[28687591] 'agreement among raft nodes before linearized reading' (duration: 896.0555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.057974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.159946Z","time spent":"898.009293ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T02:01:34.056769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.267861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-v8rrg\" ","response":"range_response_count:1 size:4683"} {"level":"warn","ts":"2026-05-28T02:01:34.057879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.408032Z","time spent":"649.670725ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T02:01:34.058142Z","caller":"traceutil/trace.go:171","msg":"trace[217478203] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-v8rrg; range_end:; response_count:1; response_revision:550; }","duration":"755.596104ms","start":"2026-05-28T02:01:33.302456Z","end":"2026-05-28T02:01:34.058052Z","steps":["trace[217478203] 'agreement among raft nodes before linearized reading' (duration: 753.464075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.059266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.302441Z","time spent":"756.791283ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4706,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-v8rrg\" "} {"level":"warn","ts":"2026-05-28T02:01:34.065794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.373936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:34.065945Z","caller":"traceutil/trace.go:171","msg":"trace[424671234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:552; }","duration":"452.561022ms","start":"2026-05-28T02:01:33.61336Z","end":"2026-05-28T02:01:34.065921Z","steps":["trace[424671234] 'agreement among raft nodes before linearized reading' (duration: 452.280293ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:34.065999Z","caller":"traceutil/trace.go:171","msg":"trace[1548747087] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"404.040312ms","start":"2026-05-28T02:01:33.661835Z","end":"2026-05-28T02:01:34.065875Z","steps":["trace[1548747087] 'process raft request' (duration: 397.778189ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.066103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.613349Z","time spent":"452.699986ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:34.066171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.661811Z","time spent":"404.270539ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:34.066742Z","caller":"traceutil/trace.go:171","msg":"trace[1044198316] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"457.75606ms","start":"2026-05-28T02:01:33.608954Z","end":"2026-05-28T02:01:34.066651Z","steps":["trace[1044198316] 'process raft request' (duration: 450.418633ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.066838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:33.608933Z","time spent":"457.850424ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:34.311225Z","caller":"traceutil/trace.go:171","msg":"trace[1297003801] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"239.40695ms","start":"2026-05-28T02:01:34.071786Z","end":"2026-05-28T02:01:34.311193Z","steps":["trace[1297003801] 'process raft request' (duration: 206.032672ms)","trace[1297003801] 'compare' (duration: 33.097899ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:34.31125Z","caller":"traceutil/trace.go:171","msg":"trace[512944328] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"237.306642ms","start":"2026-05-28T02:01:34.073916Z","end":"2026-05-28T02:01:34.311222Z","steps":["trace[512944328] 'process raft request' (duration: 237.138097ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:34.311879Z","caller":"traceutil/trace.go:171","msg":"trace[226033357] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"214.463615ms","start":"2026-05-28T02:01:34.097399Z","end":"2026-05-28T02:01:34.311862Z","steps":["trace[226033357] 'process raft request' (duration: 213.740021ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:34.312699Z","caller":"traceutil/trace.go:171","msg":"trace[1438591831] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"159.159139ms","start":"2026-05-28T02:01:34.153503Z","end":"2026-05-28T02:01:34.312662Z","steps":["trace[1438591831] 'process raft request' (duration: 158.291731ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:34.312699Z","caller":"traceutil/trace.go:171","msg":"trace[1160413130] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"145.804578ms","start":"2026-05-28T02:01:34.166864Z","end":"2026-05-28T02:01:34.312669Z","steps":["trace[1160413130] 'process raft request' (duration: 145.582661ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:34.931065Z","caller":"traceutil/trace.go:171","msg":"trace[445551152] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"213.192064ms","start":"2026-05-28T02:01:34.71785Z","end":"2026-05-28T02:01:34.931042Z","steps":["trace[445551152] 'read index received' (duration: 211.653454ms)","trace[445551152] 'applied index is now lower than readState.Index' (duration: 1.5377ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:34.931191Z","caller":"traceutil/trace.go:171","msg":"trace[1075688414] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"245.430274ms","start":"2026-05-28T02:01:34.68575Z","end":"2026-05-28T02:01:34.931181Z","steps":["trace[1075688414] 'process raft request' (duration: 244.68338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:34.931461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.596647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:34.931505Z","caller":"traceutil/trace.go:171","msg":"trace[2147375603] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:591; }","duration":"213.651968ms","start":"2026-05-28T02:01:34.717841Z","end":"2026-05-28T02:01:34.931493Z","steps":["trace[2147375603] 'agreement among raft nodes before linearized reading' (duration: 213.555435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:35.849114Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158764996,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:01:36.301103Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.075872154s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T02:01:36.301959Z","caller":"traceutil/trace.go:171","msg":"trace[461189565] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"1.076976529s","start":"2026-05-28T02:01:35.224949Z","end":"2026-05-28T02:01:36.301926Z","steps":["trace[461189565] 'process raft request' (duration: 1.076492824s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.302161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.224927Z","time spent":"1.077133444s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T02:01:36.317692Z","caller":"traceutil/trace.go:171","msg":"trace[437367231] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"992.535472ms","start":"2026-05-28T02:01:35.325115Z","end":"2026-05-28T02:01:36.31765Z","steps":["trace[437367231] 'process raft request' (duration: 992.237362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.317844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.325099Z","time spent":"992.681656ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-28T02:01:36.318171Z","caller":"traceutil/trace.go:171","msg":"trace[682074308] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:618; }","duration":"969.808254ms","start":"2026-05-28T02:01:35.348337Z","end":"2026-05-28T02:01:36.318146Z","steps":["trace[682074308] 'read index received' (duration: 953.292779ms)","trace[682074308] 'applied index is now lower than readState.Index' (duration: 16.514335ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:36.31832Z","caller":"traceutil/trace.go:171","msg":"trace[1840626844] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"959.309158ms","start":"2026-05-28T02:01:35.359003Z","end":"2026-05-28T02:01:36.318313Z","steps":["trace[1840626844] 'process raft request' (duration: 958.645667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.318445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.358981Z","time spent":"959.3673ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:36.318607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.255549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-05-28T02:01:36.31866Z","caller":"traceutil/trace.go:171","msg":"trace[1003047913] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:598; }","duration":"970.353102ms","start":"2026-05-28T02:01:35.348293Z","end":"2026-05-28T02:01:36.318647Z","steps":["trace[1003047913] 'agreement among raft nodes before linearized reading' (duration: 970.077103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.31869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.348269Z","time spent":"970.414984ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":93,"response count":1,"response size":570128,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-05-28T02:01:36.318954Z","caller":"traceutil/trace.go:171","msg":"trace[337848729] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"627.113005ms","start":"2026-05-28T02:01:35.691825Z","end":"2026-05-28T02:01:36.318938Z","steps":["trace[337848729] 'process raft request' (duration: 626.266858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.137775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-28T02:01:36.319066Z","caller":"traceutil/trace.go:171","msg":"trace[106723921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:598; }","duration":"215.207988ms","start":"2026-05-28T02:01:36.103844Z","end":"2026-05-28T02:01:36.319052Z","steps":["trace[106723921] 'agreement among raft nodes before linearized reading' (duration: 215.133176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.353748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T02:01:36.319201Z","caller":"traceutil/trace.go:171","msg":"trace[1741342009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"241.39873ms","start":"2026-05-28T02:01:36.077795Z","end":"2026-05-28T02:01:36.319194Z","steps":["trace[1741342009] 'agreement among raft nodes before linearized reading' (duration: 241.336848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.6316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:36.319335Z","caller":"traceutil/trace.go:171","msg":"trace[1246135135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"970.685902ms","start":"2026-05-28T02:01:35.348641Z","end":"2026-05-28T02:01:36.319326Z","steps":["trace[1246135135] 'agreement among raft nodes before linearized reading' (duration: 970.6271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.31936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.348634Z","time spent":"970.722063ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:01:36.319388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.959858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-05-28T02:01:36.319428Z","caller":"traceutil/trace.go:171","msg":"trace[1923016784] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:598; }","duration":"402.03047ms","start":"2026-05-28T02:01:35.917392Z","end":"2026-05-28T02:01:36.319423Z","steps":["trace[1923016784] 'agreement among raft nodes before linearized reading' (duration: 401.929767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.917375Z","time spent":"402.065612ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-05-28T02:01:36.319471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.63417ms","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-05-28T02:01:36.319498Z","caller":"traceutil/trace.go:171","msg":"trace[2054316059] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"601.695901ms","start":"2026-05-28T02:01:35.717794Z","end":"2026-05-28T02:01:36.31949Z","steps":["trace[2054316059] 'agreement among raft nodes before linearized reading' (duration: 601.66075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.717777Z","time spent":"601.737352ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-05-28T02:01:36.31903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.691802Z","time spent":"627.186257ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:36.319611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.056031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T02:01:36.31963Z","caller":"traceutil/trace.go:171","msg":"trace[889993515] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:598; }","duration":"242.113373ms","start":"2026-05-28T02:01:36.077511Z","end":"2026-05-28T02:01:36.319625Z","steps":["trace[889993515] 'agreement among raft nodes before linearized reading' (duration: 242.03291ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.226198ms","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":"warn","ts":"2026-05-28T02:01:36.318953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.203034ms","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-05-28T02:01:36.319672Z","caller":"traceutil/trace.go:171","msg":"trace[986376339] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:598; }","duration":"801.30533ms","start":"2026-05-28T02:01:35.518356Z","end":"2026-05-28T02:01:36.319661Z","steps":["trace[986376339] 'agreement among raft nodes before linearized reading' (duration: 801.258728ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:36.3197Z","caller":"traceutil/trace.go:171","msg":"trace[1119706441] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:598; }","duration":"201.984059ms","start":"2026-05-28T02:01:36.117703Z","end":"2026-05-28T02:01:36.319687Z","steps":["trace[1119706441] 'agreement among raft nodes before linearized reading' (duration: 201.187453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:36.319701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:35.518335Z","time spent":"801.360442ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-28T02:01:37.989034Z","caller":"traceutil/trace.go:171","msg":"trace[457462018] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"288.015782ms","start":"2026-05-28T02:01:37.700988Z","end":"2026-05-28T02:01:37.989004Z","steps":["trace[457462018] 'process raft request' (duration: 287.702643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.491949Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.503288744s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T02:01:39.492352Z","caller":"traceutil/trace.go:171","msg":"trace[1500897684] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:645; }","duration":"1.652954643s","start":"2026-05-28T02:01:37.839373Z","end":"2026-05-28T02:01:39.492328Z","steps":["trace[1500897684] 'read index received' (duration: 149.245466ms)","trace[1500897684] 'applied index is now lower than readState.Index' (duration: 1.503708297s)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:39.492441Z","caller":"traceutil/trace.go:171","msg":"trace[1414449127] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.742932325s","start":"2026-05-28T02:01:37.749496Z","end":"2026-05-28T02:01:39.492429Z","steps":["trace[1414449127] 'process raft request' (duration: 1.742647636s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.492573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:37.749469Z","time spent":"1.743022406s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-28T02:01:39.492754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154999584s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-28T02:01:39.492778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.139472621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T02:01:39.4928Z","caller":"traceutil/trace.go:171","msg":"trace[1204702618] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:622; }","duration":"1.155068747s","start":"2026-05-28T02:01:38.337716Z","end":"2026-05-28T02:01:39.492784Z","steps":["trace[1204702618] 'agreement among raft nodes before linearized reading' (duration: 1.154984934s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.492842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.337704Z","time spent":"1.155127918s","remote":"127.0.0.1:33416","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":"info","ts":"2026-05-28T02:01:39.492847Z","caller":"traceutil/trace.go:171","msg":"trace[1932221470] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:622; }","duration":"1.139559323s","start":"2026-05-28T02:01:38.353267Z","end":"2026-05-28T02:01:39.492826Z","steps":["trace[1932221470] 'agreement among raft nodes before linearized reading' (duration: 1.139339466s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.492883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175556116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-05-28T02:01:39.492769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574714561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-05-28T02:01:39.492936Z","caller":"traceutil/trace.go:171","msg":"trace[1825058638] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"1.175622918s","start":"2026-05-28T02:01:38.317293Z","end":"2026-05-28T02:01:39.492916Z","steps":["trace[1825058638] 'agreement among raft nodes before linearized reading' (duration: 1.175526805s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.492989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.317279Z","time spent":"1.175700881s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9343,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-05-28T02:01:39.493064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14426964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:01:39.493079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029113857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T02:01:39.493111Z","caller":"traceutil/trace.go:171","msg":"trace[452921959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.144334542s","start":"2026-05-28T02:01:38.348763Z","end":"2026-05-28T02:01:39.493097Z","steps":["trace[452921959] 'agreement among raft nodes before linearized reading' (duration: 1.14426495s)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:39.49312Z","caller":"traceutil/trace.go:171","msg":"trace[1172541690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:622; }","duration":"1.029168249s","start":"2026-05-28T02:01:38.46394Z","end":"2026-05-28T02:01:39.493108Z","steps":["trace[1172541690] 'agreement among raft nodes before linearized reading' (duration: 1.029021264s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.493149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.372360786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-05-28T02:01:39.49315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.348755Z","time spent":"1.144385184s","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:01:39.493081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.72864ms","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":"warn","ts":"2026-05-28T02:01:39.493265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132480812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T02:01:39.493157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.463932Z","time spent":"1.029214661s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T02:01:39.49331Z","caller":"traceutil/trace.go:171","msg":"trace[2064278614] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:622; }","duration":"1.132547784s","start":"2026-05-28T02:01:38.360749Z","end":"2026-05-28T02:01:39.493297Z","steps":["trace[2064278614] 'agreement among raft nodes before linearized reading' (duration: 1.132431021s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.493366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.145858079s","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-05-28T02:01:39.49337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.360739Z","time spent":"1.132619146s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T02:01:39.492938Z","caller":"traceutil/trace.go:171","msg":"trace[1225465502] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"1.574938958s","start":"2026-05-28T02:01:37.917982Z","end":"2026-05-28T02:01:39.492921Z","steps":["trace[1225465502] 'agreement among raft nodes before linearized reading' (duration: 1.574563856s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.493439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.654063257s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:01:39.493516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:37.917921Z","time spent":"1.575585458s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13252,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"info","ts":"2026-05-28T02:01:39.493535Z","caller":"traceutil/trace.go:171","msg":"trace[24079060] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:622; }","duration":"1.654193021s","start":"2026-05-28T02:01:37.839322Z","end":"2026-05-28T02:01:39.493515Z","steps":["trace[24079060] 'agreement among raft nodes before linearized reading' (duration: 1.654031296s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.492889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.353256Z","time spent":"1.139621845s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:39.493933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:37.839301Z","time spent":"1.654540652s","remote":"127.0.0.1:33358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-05-28T02:01:39.493406Z","caller":"traceutil/trace.go:171","msg":"trace[1421121536] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:622; }","duration":"1.145918021s","start":"2026-05-28T02:01:38.347477Z","end":"2026-05-28T02:01:39.493395Z","steps":["trace[1421121536] 'agreement among raft nodes before linearized reading' (duration: 1.145853189s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.494163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.347464Z","time spent":"1.146678345s","remote":"127.0.0.1:33416","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-05-28T02:01:39.493271Z","caller":"traceutil/trace.go:171","msg":"trace[1827578347] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"974.997139ms","start":"2026-05-28T02:01:38.518244Z","end":"2026-05-28T02:01:39.493241Z","steps":["trace[1827578347] 'agreement among raft nodes before linearized reading' (duration: 974.39764ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.494451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.518237Z","time spent":"976.204166ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-28T02:01:39.49318Z","caller":"traceutil/trace.go:171","msg":"trace[1307856120] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"1.372426258s","start":"2026-05-28T02:01:38.120745Z","end":"2026-05-28T02:01:39.493171Z","steps":["trace[1307856120] 'agreement among raft nodes before linearized reading' (duration: 1.372348495s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.494581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.120724Z","time spent":"1.373849232s","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-05-28T02:01:39.493629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.973389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-28T02:01:39.494697Z","caller":"traceutil/trace.go:171","msg":"trace[1230424640] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:622; }","duration":"776.120524ms","start":"2026-05-28T02:01:38.718558Z","end":"2026-05-28T02:01:39.494679Z","steps":["trace[1230424640] 'agreement among raft nodes before linearized reading' (duration: 774.832494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:39.49475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:38.718532Z","time spent":"776.204567ms","remote":"127.0.0.1:33194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-28T02:01:43.760777Z","caller":"traceutil/trace.go:171","msg":"trace[299536847] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:679; }","duration":"433.669827ms","start":"2026-05-28T02:01:43.32707Z","end":"2026-05-28T02:01:43.76074Z","steps":["trace[299536847] 'read index received' (duration: 432.713778ms)","trace[299536847] 'applied index is now lower than readState.Index' (duration: 954.119µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:43.760833Z","caller":"traceutil/trace.go:171","msg":"trace[1217997859] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"732.437649ms","start":"2026-05-28T02:01:43.028371Z","end":"2026-05-28T02:01:43.760809Z","steps":["trace[1217997859] 'process raft request' (duration: 731.732448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:43.760995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.02834Z","time spent":"732.552933ms","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:43.761057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.286238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:01:43.761081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.006178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fqvh4\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-28T02:01:43.761152Z","caller":"traceutil/trace.go:171","msg":"trace[1673311293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:654; }","duration":"411.408983ms","start":"2026-05-28T02:01:43.349716Z","end":"2026-05-28T02:01:43.761125Z","steps":["trace[1673311293] 'agreement among raft nodes before linearized reading' (duration: 411.243628ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:43.761156Z","caller":"traceutil/trace.go:171","msg":"trace[1003245272] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fqvh4; range_end:; response_count:1; response_revision:654; }","duration":"434.107371ms","start":"2026-05-28T02:01:43.327027Z","end":"2026-05-28T02:01:43.761134Z","steps":["trace[1003245272] 'agreement among raft nodes before linearized reading' (duration: 433.874524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:43.761158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.766771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T02:01:43.761204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.327005Z","time spent":"434.187493ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-fqvh4\" "} {"level":"info","ts":"2026-05-28T02:01:43.76122Z","caller":"traceutil/trace.go:171","msg":"trace[1768298848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:654; }","duration":"164.849493ms","start":"2026-05-28T02:01:43.596356Z","end":"2026-05-28T02:01:43.761205Z","steps":["trace[1768298848] 'agreement among raft nodes before linearized reading' (duration: 164.690238ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:43.7612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.349704Z","time spent":"411.486885ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:01:43.761328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.143882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T02:01:43.761422Z","caller":"traceutil/trace.go:171","msg":"trace[1214010987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:654; }","duration":"165.245256ms","start":"2026-05-28T02:01:43.596149Z","end":"2026-05-28T02:01:43.761395Z","steps":["trace[1214010987] 'agreement among raft nodes before linearized reading' (duration: 165.086531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:43.761176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.047709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T02:01:43.761504Z","caller":"traceutil/trace.go:171","msg":"trace[858575143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:654; }","duration":"165.40681ms","start":"2026-05-28T02:01:43.596062Z","end":"2026-05-28T02:01:43.761468Z","steps":["trace[858575143] 'agreement among raft nodes before linearized reading' (duration: 164.932216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:43.761677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.936273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T02:01:43.761716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.818702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T02:01:43.761749Z","caller":"traceutil/trace.go:171","msg":"trace[723605783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"214.046566ms","start":"2026-05-28T02:01:43.547691Z","end":"2026-05-28T02:01:43.761737Z","steps":["trace[723605783] 'agreement among raft nodes before linearized reading' (duration: 213.85059ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:43.761752Z","caller":"traceutil/trace.go:171","msg":"trace[1186666097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:654; }","duration":"165.898925ms","start":"2026-05-28T02:01:43.595843Z","end":"2026-05-28T02:01:43.761742Z","steps":["trace[1186666097] 'agreement among raft nodes before linearized reading' (duration: 165.38759ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:44.088712Z","caller":"traceutil/trace.go:171","msg":"trace[1780540989] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"321.062758ms","start":"2026-05-28T02:01:43.767621Z","end":"2026-05-28T02:01:44.088684Z","steps":["trace[1780540989] 'process raft request' (duration: 320.911463ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:44.088781Z","caller":"traceutil/trace.go:171","msg":"trace[432773727] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"321.609344ms","start":"2026-05-28T02:01:43.767122Z","end":"2026-05-28T02:01:44.088732Z","steps":["trace[432773727] 'process raft request' (duration: 321.247584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.088984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.767616Z","time spent":"321.241933ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:44.089037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.767106Z","time spent":"321.796279ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:44.08897Z","caller":"traceutil/trace.go:171","msg":"trace[177361310] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"320.429178ms","start":"2026-05-28T02:01:43.768508Z","end":"2026-05-28T02:01:44.088937Z","steps":["trace[177361310] 'process raft request' (duration: 320.13623ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:44.089198Z","caller":"traceutil/trace.go:171","msg":"trace[288895029] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"319.841201ms","start":"2026-05-28T02:01:43.769284Z","end":"2026-05-28T02:01:44.089125Z","steps":["trace[288895029] 'process raft request' (duration: 319.47783ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.089283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.768501Z","time spent":"320.669265ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:44.089527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.769257Z","time spent":"320.062368ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:44.089572Z","caller":"traceutil/trace.go:171","msg":"trace[722732104] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"311.566943ms","start":"2026-05-28T02:01:43.777987Z","end":"2026-05-28T02:01:44.089554Z","steps":["trace[722732104] 'process raft request' (duration: 311.101229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.090205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:43.777963Z","time spent":"312.168371ms","remote":"127.0.0.1:33348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:44.105665Z","caller":"traceutil/trace.go:171","msg":"trace[1031652664] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"288.278012ms","start":"2026-05-28T02:01:43.817368Z","end":"2026-05-28T02:01:44.105646Z","steps":["trace[1031652664] 'process raft request' (duration: 288.160409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.882549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.689519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:01:44.882595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.0219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:44.882629Z","caller":"traceutil/trace.go:171","msg":"trace[521914221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"515.805582ms","start":"2026-05-28T02:01:44.366805Z","end":"2026-05-28T02:01:44.882611Z","steps":["trace[521914221] 'range keys from in-memory index tree' (duration: 515.599556ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.882671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:44.366789Z","time spent":"515.872364ms","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-28T02:01:44.882671Z","caller":"traceutil/trace.go:171","msg":"trace[258708462] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:661; }","duration":"766.187514ms","start":"2026-05-28T02:01:44.116463Z","end":"2026-05-28T02:01:44.88265Z","steps":["trace[258708462] 'range keys from in-memory index tree' (duration: 765.907036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:44.882713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:44.116443Z","time spent":"766.260847ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-28T02:01:46.305368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158765159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:01:46.805541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158765159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:01:47.305981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158765159,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:01:47.502346Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.2212114s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T02:01:47.504109Z","caller":"traceutil/trace.go:171","msg":"trace[1023797521] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"2.224032301s","start":"2026-05-28T02:01:45.280053Z","end":"2026-05-28T02:01:47.504085Z","steps":["trace[1023797521] 'process raft request' (duration: 2.222781285s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.504656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:45.280005Z","time spent":"2.224163406s","remote":"127.0.0.1:33236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:47.5207Z","caller":"traceutil/trace.go:171","msg":"trace[1095191487] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:687; }","duration":"1.715589752s","start":"2026-05-28T02:01:45.805073Z","end":"2026-05-28T02:01:47.520663Z","steps":["trace[1095191487] 'read index received' (duration: 1.697545609s)","trace[1095191487] 'applied index is now lower than readState.Index' (duration: 18.043553ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:47.520907Z","caller":"traceutil/trace.go:171","msg":"trace[966633970] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"866.174183ms","start":"2026-05-28T02:01:46.654726Z","end":"2026-05-28T02:01:47.5209Z","steps":["trace[966633970] 'process raft request' (duration: 865.808772ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.520994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.654695Z","time spent":"866.237554ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:01:47.521158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.716090346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T02:01:47.52118Z","caller":"traceutil/trace.go:171","msg":"trace[1071111320] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.716140137s","start":"2026-05-28T02:01:45.805034Z","end":"2026-05-28T02:01:47.521174Z","steps":["trace[1071111320] 'agreement among raft nodes before linearized reading' (duration: 1.716065365s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:45.805019Z","time spent":"1.716171849s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.42612959s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T02:01:47.521317Z","caller":"traceutil/trace.go:171","msg":"trace[1812114832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.426195071s","start":"2026-05-28T02:01:46.095117Z","end":"2026-05-28T02:01:47.521312Z","steps":["trace[1812114832] 'agreement among raft nodes before linearized reading' (duration: 1.4261417s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.095097Z","time spent":"1.426231243s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015127773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.140\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-28T02:01:47.521493Z","caller":"traceutil/trace.go:171","msg":"trace[1107462206] range","detail":"{range_begin:/registry/masterleases/199.204.45.140; range_end:; response_count:1; response_revision:663; }","duration":"1.015187735s","start":"2026-05-28T02:01:46.5063Z","end":"2026-05-28T02:01:47.521488Z","steps":["trace[1107462206] 'agreement among raft nodes before linearized reading' (duration: 1.015122453s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.506281Z","time spent":"1.015223336s","remote":"127.0.0.1:33172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.140\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.172911932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:47.521618Z","caller":"traceutil/trace.go:171","msg":"trace[980011753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"1.172944923s","start":"2026-05-28T02:01:46.348669Z","end":"2026-05-28T02:01:47.521614Z","steps":["trace[980011753] 'agreement among raft nodes before linearized reading' (duration: 1.172903632s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.52163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.348605Z","time spent":"1.173022396s","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426203601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T02:01:47.521742Z","caller":"traceutil/trace.go:171","msg":"trace[2063032948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"1.426241383s","start":"2026-05-28T02:01:46.095496Z","end":"2026-05-28T02:01:47.521737Z","steps":["trace[2063032948] 'agreement among raft nodes before linearized reading' (duration: 1.426198322s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.095485Z","time spent":"1.426265973s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426498161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-28T02:01:47.52185Z","caller":"traceutil/trace.go:171","msg":"trace[1394203741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"1.426547142s","start":"2026-05-28T02:01:46.095299Z","end":"2026-05-28T02:01:47.521846Z","steps":["trace[1394203741] 'agreement among raft nodes before linearized reading' (duration: 1.426506531s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.095276Z","time spent":"1.426583182s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-28T02:01:47.521936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426665325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T02:01:47.521952Z","caller":"traceutil/trace.go:171","msg":"trace[730392022] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:663; }","duration":"1.426702606s","start":"2026-05-28T02:01:46.095246Z","end":"2026-05-28T02:01:47.521949Z","steps":["trace[730392022] 'agreement among raft nodes before linearized reading' (duration: 1.426665955s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:47.521965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:46.095187Z","time spent":"1.426773978s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-28T02:01:50.524687Z","caller":"traceutil/trace.go:171","msg":"trace[757594050] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"131.601035ms","start":"2026-05-28T02:01:50.393055Z","end":"2026-05-28T02:01:50.524656Z","steps":["trace[757594050] 'process raft request' (duration: 129.894507ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:57.192265Z","caller":"traceutil/trace.go:171","msg":"trace[1164948177] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"160.887692ms","start":"2026-05-28T02:01:57.031353Z","end":"2026-05-28T02:01:57.192241Z","steps":["trace[1164948177] 'process raft request' (duration: 160.751518ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:57.535983Z","caller":"traceutil/trace.go:171","msg":"trace[689785425] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"184.150117ms","start":"2026-05-28T02:01:57.35177Z","end":"2026-05-28T02:01:57.53592Z","steps":["trace[689785425] 'read index received' (duration: 183.850719ms)","trace[689785425] 'applied index is now lower than readState.Index' (duration: 298.538µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:57.535989Z","caller":"traceutil/trace.go:171","msg":"trace[1840367474] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"198.477682ms","start":"2026-05-28T02:01:57.337481Z","end":"2026-05-28T02:01:57.535959Z","steps":["trace[1840367474] 'process raft request' (duration: 198.263456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:57.536174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.397953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:57.536272Z","caller":"traceutil/trace.go:171","msg":"trace[394745205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"184.563708ms","start":"2026-05-28T02:01:57.351695Z","end":"2026-05-28T02:01:57.536259Z","steps":["trace[394745205] 'agreement among raft nodes before linearized reading' (duration: 184.411604ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:57.678621Z","caller":"traceutil/trace.go:171","msg":"trace[1266816690] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"105.859803ms","start":"2026-05-28T02:01:57.572736Z","end":"2026-05-28T02:01:57.678596Z","steps":["trace[1266816690] 'process raft request' (duration: 63.423414ms)","trace[1266816690] 'compare' (duration: 42.310586ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:57.68054Z","caller":"traceutil/trace.go:171","msg":"trace[439876721] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"101.900747ms","start":"2026-05-28T02:01:57.578621Z","end":"2026-05-28T02:01:57.680522Z","steps":["trace[439876721] 'process raft request' (duration: 101.66279ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:57.847704Z","caller":"traceutil/trace.go:171","msg":"trace[985801526] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"104.004564ms","start":"2026-05-28T02:01:57.74368Z","end":"2026-05-28T02:01:57.847685Z","steps":["trace[985801526] 'process raft request' (duration: 75.992272ms)","trace[985801526] 'compare' (duration: 27.825597ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:58.046741Z","caller":"traceutil/trace.go:171","msg":"trace[2016421776] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"188.208318ms","start":"2026-05-28T02:01:57.858508Z","end":"2026-05-28T02:01:58.046716Z","steps":["trace[2016421776] 'process raft request' (duration: 185.66411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:59.02607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.845526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:01:59.026262Z","caller":"traceutil/trace.go:171","msg":"trace[1955091339] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"182.099333ms","start":"2026-05-28T02:01:58.844129Z","end":"2026-05-28T02:01:59.026229Z","steps":["trace[1955091339] 'process raft request' (duration: 56.930799ms)","trace[1955091339] 'compare' (duration: 124.698311ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:59.069343Z","caller":"traceutil/trace.go:171","msg":"trace[1958570770] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"220.631122ms","start":"2026-05-28T02:01:58.848682Z","end":"2026-05-28T02:01:59.069313Z","steps":["trace[1958570770] 'process raft request' (duration: 220.512229ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:59.278161Z","caller":"traceutil/trace.go:171","msg":"trace[1487320952] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"243.302651ms","start":"2026-05-28T02:01:59.034746Z","end":"2026-05-28T02:01:59.278048Z","steps":["trace[1487320952] 'process raft request' (duration: 225.889881ms)","trace[1487320952] 'compare' (duration: 17.253586ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:59.456835Z","caller":"traceutil/trace.go:171","msg":"trace[1556663054] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"107.183207ms","start":"2026-05-28T02:01:59.349623Z","end":"2026-05-28T02:01:59.456806Z","steps":["trace[1556663054] 'read index received' (duration: 105.745839ms)","trace[1556663054] 'applied index is now lower than readState.Index' (duration: 1.435968ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:01:59.45692Z","caller":"traceutil/trace.go:171","msg":"trace[490627842] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"363.73958ms","start":"2026-05-28T02:01:59.093145Z","end":"2026-05-28T02:01:59.456885Z","steps":["trace[490627842] 'process raft request' (duration: 362.306052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:59.457054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.383322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:01:59.457183Z","caller":"traceutil/trace.go:171","msg":"trace[144153061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"107.616999ms","start":"2026-05-28T02:01:59.349548Z","end":"2026-05-28T02:01:59.457165Z","steps":["trace[144153061] 'agreement among raft nodes before linearized reading' (duration: 107.385642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:01:59.457076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:01:59.093124Z","time spent":"363.867663ms","remote":"127.0.0.1:33342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:01:59.950435Z","caller":"traceutil/trace.go:171","msg":"trace[129241386] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"184.616847ms","start":"2026-05-28T02:01:59.76578Z","end":"2026-05-28T02:01:59.950397Z","steps":["trace[129241386] 'process raft request' (duration: 184.318409ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:59.950517Z","caller":"traceutil/trace.go:171","msg":"trace[1881838750] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"184.371551ms","start":"2026-05-28T02:01:59.766136Z","end":"2026-05-28T02:01:59.950508Z","steps":["trace[1881838750] 'process raft request' (duration: 184.204327ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:01:59.950428Z","caller":"traceutil/trace.go:171","msg":"trace[605094378] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"184.551046ms","start":"2026-05-28T02:01:59.765829Z","end":"2026-05-28T02:01:59.95038Z","steps":["trace[605094378] 'process raft request' (duration: 184.468673ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:01.641316Z","caller":"traceutil/trace.go:171","msg":"trace[920077726] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"129.165841ms","start":"2026-05-28T02:02:01.512112Z","end":"2026-05-28T02:02:01.641278Z","steps":["trace[920077726] 'process raft request' (duration: 81.759094ms)","trace[920077726] 'compare' (duration: 47.233683ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:01.641426Z","caller":"traceutil/trace.go:171","msg":"trace[407870096] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"129.298525ms","start":"2026-05-28T02:02:01.512109Z","end":"2026-05-28T02:02:01.641407Z","steps":["trace[407870096] 'process raft request' (duration: 129.097649ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:01.672646Z","caller":"traceutil/trace.go:171","msg":"trace[1739141446] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"159.619597ms","start":"2026-05-28T02:02:01.513011Z","end":"2026-05-28T02:02:01.67263Z","steps":["trace[1739141446] 'process raft request' (duration: 159.487973ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:01.888238Z","caller":"traceutil/trace.go:171","msg":"trace[1378920963] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"202.705291ms","start":"2026-05-28T02:02:01.685508Z","end":"2026-05-28T02:02:01.888213Z","steps":["trace[1378920963] 'process raft request' (duration: 177.135233ms)","trace[1378920963] 'compare' (duration: 25.225769ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:01.888366Z","caller":"traceutil/trace.go:171","msg":"trace[613555398] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"184.30197ms","start":"2026-05-28T02:02:01.704047Z","end":"2026-05-28T02:02:01.888349Z","steps":["trace[613555398] 'read index received' (duration: 158.543248ms)","trace[613555398] 'applied index is now lower than readState.Index' (duration: 25.756352ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:01.888689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.451845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:01.888745Z","caller":"traceutil/trace.go:171","msg":"trace[1488639961] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:772; }","duration":"184.566807ms","start":"2026-05-28T02:02:01.704165Z","end":"2026-05-28T02:02:01.888732Z","steps":["trace[1488639961] 'agreement among raft nodes before linearized reading' (duration: 184.460145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:01.889117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.729676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-28T02:02:01.889117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.151797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:01.889158Z","caller":"traceutil/trace.go:171","msg":"trace[1409253886] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"124.785777ms","start":"2026-05-28T02:02:01.764362Z","end":"2026-05-28T02:02:01.889148Z","steps":["trace[1409253886] 'agreement among raft nodes before linearized reading' (duration: 124.660784ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:01.889166Z","caller":"traceutil/trace.go:171","msg":"trace[74296634] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:772; }","duration":"184.234409ms","start":"2026-05-28T02:02:01.704918Z","end":"2026-05-28T02:02:01.889153Z","steps":["trace[74296634] 'agreement among raft nodes before linearized reading' (duration: 184.169368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:01.88977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.694698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:01.889835Z","caller":"traceutil/trace.go:171","msg":"trace[1971868144] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:772; }","duration":"185.7872ms","start":"2026-05-28T02:02:01.704034Z","end":"2026-05-28T02:02:01.889821Z","steps":["trace[1971868144] 'agreement among raft nodes before linearized reading' (duration: 184.499886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.20223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.099634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:02:02.202328Z","caller":"traceutil/trace.go:171","msg":"trace[2060127181] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:803; }","duration":"296.264407ms","start":"2026-05-28T02:02:01.906055Z","end":"2026-05-28T02:02:02.202319Z","steps":["trace[2060127181] 'read index received' (duration: 196.029989ms)","trace[2060127181] 'applied index is now lower than readState.Index' (duration: 100.233908ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:02.20238Z","caller":"traceutil/trace.go:171","msg":"trace[290209272] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"308.905058ms","start":"2026-05-28T02:02:01.89347Z","end":"2026-05-28T02:02:02.202375Z","steps":["trace[290209272] 'process raft request' (duration: 208.605068ms)","trace[290209272] 'compare' (duration: 99.93918ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:02.20242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:01.893457Z","time spent":"308.945308ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:02:02.202468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.56879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.202974Z","caller":"traceutil/trace.go:171","msg":"trace[681918033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:774; }","duration":"296.058372ms","start":"2026-05-28T02:02:01.906886Z","end":"2026-05-28T02:02:02.202944Z","steps":["trace[681918033] 'agreement among raft nodes before linearized reading' (duration: 295.56647ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.424451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.20257Z","caller":"traceutil/trace.go:171","msg":"trace[711016688] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"307.695936ms","start":"2026-05-28T02:02:01.894857Z","end":"2026-05-28T02:02:02.202553Z","steps":["trace[711016688] 'process raft request' (duration: 307.432989ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:02.20322Z","caller":"traceutil/trace.go:171","msg":"trace[360893127] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:774; }","duration":"297.173461ms","start":"2026-05-28T02:02:01.906029Z","end":"2026-05-28T02:02:02.203202Z","steps":["trace[360893127] 'agreement among raft nodes before linearized reading' (duration: 296.407361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.525085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:02:02.20335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:01.894844Z","time spent":"308.455045ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:02:02.203393Z","caller":"traceutil/trace.go:171","msg":"trace[428069814] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:774; }","duration":"297.349206ms","start":"2026-05-28T02:02:01.906029Z","end":"2026-05-28T02:02:02.203378Z","steps":["trace[428069814] 'agreement among raft nodes before linearized reading' (duration: 296.534194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.720612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T02:02:02.202728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.743223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T02:02:02.203574Z","caller":"traceutil/trace.go:171","msg":"trace[1383917162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:774; }","duration":"245.578985ms","start":"2026-05-28T02:02:01.957917Z","end":"2026-05-28T02:02:02.203496Z","steps":["trace[1383917162] 'agreement among raft nodes before linearized reading' (duration: 244.690842ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:02.203598Z","caller":"traceutil/trace.go:171","msg":"trace[1085861491] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:774; }","duration":"245.614775ms","start":"2026-05-28T02:02:01.957972Z","end":"2026-05-28T02:02:02.203587Z","steps":["trace[1085861491] 'agreement among raft nodes before linearized reading' (duration: 244.729042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.251568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.203682Z","caller":"traceutil/trace.go:171","msg":"trace[1059474942] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:774; }","duration":"133.208633ms","start":"2026-05-28T02:02:02.070459Z","end":"2026-05-28T02:02:02.203668Z","steps":["trace[1059474942] 'agreement among raft nodes before linearized reading' (duration: 132.282429ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.141235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.203754Z","caller":"traceutil/trace.go:171","msg":"trace[1067820335] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:774; }","duration":"133.174952ms","start":"2026-05-28T02:02:02.070569Z","end":"2026-05-28T02:02:02.203744Z","steps":["trace[1067820335] 'agreement among raft nodes before linearized reading' (duration: 132.155816ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.769215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.203843Z","caller":"traceutil/trace.go:171","msg":"trace[1274089537] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:774; }","duration":"201.985296ms","start":"2026-05-28T02:02:02.001851Z","end":"2026-05-28T02:02:02.203836Z","steps":["trace[1274089537] 'agreement among raft nodes before linearized reading' (duration: 200.749584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.799176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.203965Z","caller":"traceutil/trace.go:171","msg":"trace[289491296] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:774; }","duration":"133.021508ms","start":"2026-05-28T02:02:02.070926Z","end":"2026-05-28T02:02:02.203947Z","steps":["trace[289491296] 'agreement among raft nodes before linearized reading' (duration: 131.802417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.202682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.781559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T02:02:02.204171Z","caller":"traceutil/trace.go:171","msg":"trace[731851923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:774; }","duration":"242.271778ms","start":"2026-05-28T02:02:01.961861Z","end":"2026-05-28T02:02:02.204133Z","steps":["trace[731851923] 'agreement among raft nodes before linearized reading' (duration: 240.746319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.597915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.242774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-28T02:02:02.598086Z","caller":"traceutil/trace.go:171","msg":"trace[1598937599] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:805; }","duration":"276.761503ms","start":"2026-05-28T02:02:02.321298Z","end":"2026-05-28T02:02:02.59806Z","steps":["trace[1598937599] 'read index received' (duration: 55.273802ms)","trace[1598937599] 'applied index is now lower than readState.Index' (duration: 221.486671ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:02.598214Z","caller":"traceutil/trace.go:171","msg":"trace[1034014073] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"390.015208ms","start":"2026-05-28T02:02:02.208174Z","end":"2026-05-28T02:02:02.59819Z","steps":["trace[1034014073] 'process raft request' (duration: 389.866485ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.598377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.075882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:02:02.598378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:02.208166Z","time spent":"390.120092ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:02:02.598467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.899527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.5985Z","caller":"traceutil/trace.go:171","msg":"trace[611797592] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:777; }","duration":"276.961859ms","start":"2026-05-28T02:02:02.321519Z","end":"2026-05-28T02:02:02.598481Z","steps":["trace[611797592] 'agreement among raft nodes before linearized reading' (duration: 276.935338ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:02.598732Z","caller":"traceutil/trace.go:171","msg":"trace[1425333580] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"391.169988ms","start":"2026-05-28T02:02:02.207554Z","end":"2026-05-28T02:02:02.598724Z","steps":["trace[1425333580] 'process raft request' (duration: 169.050991ms)","trace[1425333580] 'compare' (duration: 221.146492ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:02.598819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:02.207532Z","time spent":"391.242131ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:02:02.598962Z","caller":"traceutil/trace.go:171","msg":"trace[1676228118] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"391.072886ms","start":"2026-05-28T02:02:02.207883Z","end":"2026-05-28T02:02:02.598955Z","steps":["trace[1676228118] 'process raft request' (duration: 390.128012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.599035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:02.207875Z","time spent":"391.125767ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:02:02.598434Z","caller":"traceutil/trace.go:171","msg":"trace[1324594399] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:777; }","duration":"277.125543ms","start":"2026-05-28T02:02:02.321268Z","end":"2026-05-28T02:02:02.598394Z","steps":["trace[1324594399] 'agreement among raft nodes before linearized reading' (duration: 277.084432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.599464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.5336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.599545Z","caller":"traceutil/trace.go:171","msg":"trace[1121243215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:777; }","duration":"197.645853ms","start":"2026-05-28T02:02:02.401892Z","end":"2026-05-28T02:02:02.599538Z","steps":["trace[1121243215] 'agreement among raft nodes before linearized reading' (duration: 197.540671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.599812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.33028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.600201Z","caller":"traceutil/trace.go:171","msg":"trace[1756147897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:777; }","duration":"105.756201ms","start":"2026-05-28T02:02:02.494438Z","end":"2026-05-28T02:02:02.600194Z","steps":["trace[1756147897] 'agreement among raft nodes before linearized reading' (duration: 105.357341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.600374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.984477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.600408Z","caller":"traceutil/trace.go:171","msg":"trace[557308955] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:777; }","duration":"106.038599ms","start":"2026-05-28T02:02:02.494365Z","end":"2026-05-28T02:02:02.600404Z","steps":["trace[557308955] 'agreement among raft nodes before linearized reading' (duration: 105.991177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.600554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.203332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.600619Z","caller":"traceutil/trace.go:171","msg":"trace[321776193] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:777; }","duration":"106.283835ms","start":"2026-05-28T02:02:02.494331Z","end":"2026-05-28T02:02:02.600614Z","steps":["trace[321776193] 'agreement among raft nodes before linearized reading' (duration: 106.208063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.60074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.472468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.605636Z","caller":"traceutil/trace.go:171","msg":"trace[667936083] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:777; }","duration":"131.371275ms","start":"2026-05-28T02:02:02.474254Z","end":"2026-05-28T02:02:02.605626Z","steps":["trace[667936083] 'agreement among raft nodes before linearized reading' (duration: 126.471558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.605858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.642442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.605923Z","caller":"traceutil/trace.go:171","msg":"trace[1653047743] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:777; }","duration":"131.737754ms","start":"2026-05-28T02:02:02.474179Z","end":"2026-05-28T02:02:02.605917Z","steps":["trace[1653047743] 'agreement among raft nodes before linearized reading' (duration: 131.661742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.606044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.477407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.606106Z","caller":"traceutil/trace.go:171","msg":"trace[1369843826] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:777; }","duration":"168.569899ms","start":"2026-05-28T02:02:02.43753Z","end":"2026-05-28T02:02:02.6061Z","steps":["trace[1369843826] 'agreement among raft nodes before linearized reading' (duration: 168.498297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.606226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.966769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.606287Z","caller":"traceutil/trace.go:171","msg":"trace[298866755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:777; }","duration":"169.068922ms","start":"2026-05-28T02:02:02.437212Z","end":"2026-05-28T02:02:02.606281Z","steps":["trace[298866755] 'agreement among raft nodes before linearized reading' (duration: 168.99885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.60641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.404771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.606468Z","caller":"traceutil/trace.go:171","msg":"trace[18175356] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:777; }","duration":"169.474473ms","start":"2026-05-28T02:02:02.436988Z","end":"2026-05-28T02:02:02.606463Z","steps":["trace[18175356] 'agreement among raft nodes before linearized reading' (duration: 169.408241ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.606761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.424592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.606825Z","caller":"traceutil/trace.go:171","msg":"trace[1548927665] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"257.505644ms","start":"2026-05-28T02:02:02.349314Z","end":"2026-05-28T02:02:02.60682Z","steps":["trace[1548927665] 'agreement among raft nodes before linearized reading' (duration: 257.430782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.606999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.111556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.607062Z","caller":"traceutil/trace.go:171","msg":"trace[2016342633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:777; }","duration":"205.172448ms","start":"2026-05-28T02:02:02.401881Z","end":"2026-05-28T02:02:02.607054Z","steps":["trace[2016342633] 'agreement among raft nodes before linearized reading' (duration: 205.102096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.607189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.179658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.607254Z","caller":"traceutil/trace.go:171","msg":"trace[1886156862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"254.26099ms","start":"2026-05-28T02:02:02.352987Z","end":"2026-05-28T02:02:02.607248Z","steps":["trace[1886156862] 'agreement among raft nodes before linearized reading' (duration: 254.187438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.607368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.296343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.607428Z","caller":"traceutil/trace.go:171","msg":"trace[1630477211] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"256.358564ms","start":"2026-05-28T02:02:02.351064Z","end":"2026-05-28T02:02:02.607423Z","steps":["trace[1630477211] 'agreement among raft nodes before linearized reading' (duration: 256.288583ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:02.607539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.178171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:02.607597Z","caller":"traceutil/trace.go:171","msg":"trace[831529755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"258.245243ms","start":"2026-05-28T02:02:02.349346Z","end":"2026-05-28T02:02:02.607591Z","steps":["trace[831529755] 'agreement among raft nodes before linearized reading' (duration: 258.177571ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:03.068611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.314752ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-28T02:02:04.400456Z","caller":"traceutil/trace.go:171","msg":"trace[720031073] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"186.726663ms","start":"2026-05-28T02:02:04.213711Z","end":"2026-05-28T02:02:04.400438Z","steps":["trace[720031073] 'process raft request' (duration: 186.60427ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:04.424647Z","caller":"traceutil/trace.go:171","msg":"trace[1808591901] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"207.192917ms","start":"2026-05-28T02:02:04.21742Z","end":"2026-05-28T02:02:04.424613Z","steps":["trace[1808591901] 'process raft request' (duration: 206.982992ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:06.734455Z","caller":"traceutil/trace.go:171","msg":"trace[1901066067] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"100.509834ms","start":"2026-05-28T02:02:06.633912Z","end":"2026-05-28T02:02:06.734421Z","steps":["trace[1901066067] 'process raft request' (duration: 86.020049ms)","trace[1901066067] 'compare' (duration: 14.337201ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:06.755383Z","caller":"traceutil/trace.go:171","msg":"trace[1306220459] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"120.341314ms","start":"2026-05-28T02:02:06.635019Z","end":"2026-05-28T02:02:06.75536Z","steps":["trace[1306220459] 'process raft request' (duration: 120.227151ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:08.914515Z","caller":"traceutil/trace.go:171","msg":"trace[2091107294] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"154.700079ms","start":"2026-05-28T02:02:08.759793Z","end":"2026-05-28T02:02:08.914494Z","steps":["trace[2091107294] 'read index received' (duration: 154.582806ms)","trace[2091107294] 'applied index is now lower than readState.Index' (duration: 116.613µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:08.914602Z","caller":"traceutil/trace.go:171","msg":"trace[273007918] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"170.776789ms","start":"2026-05-28T02:02:08.743781Z","end":"2026-05-28T02:02:08.914558Z","steps":["trace[273007918] 'process raft request' (duration: 170.585854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:08.914672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.861463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-28T02:02:08.914701Z","caller":"traceutil/trace.go:171","msg":"trace[574543122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:793; }","duration":"154.944505ms","start":"2026-05-28T02:02:08.759747Z","end":"2026-05-28T02:02:08.914691Z","steps":["trace[574543122] 'agreement among raft nodes before linearized reading' (duration: 154.829902ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:11.91975Z","caller":"traceutil/trace.go:171","msg":"trace[635092905] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"103.094006ms","start":"2026-05-28T02:02:11.816625Z","end":"2026-05-28T02:02:11.919719Z","steps":["trace[635092905] 'read index received' (duration: 19.026734ms)","trace[635092905] 'applied index is now lower than readState.Index' (duration: 84.065292ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:11.919934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.27987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T02:02:11.919982Z","caller":"traceutil/trace.go:171","msg":"trace[680456074] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:800; }","duration":"103.410304ms","start":"2026-05-28T02:02:11.816559Z","end":"2026-05-28T02:02:11.91997Z","steps":["trace[680456074] 'agreement among raft nodes before linearized reading' (duration: 103.288531ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:11.919946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.156958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T02:02:11.92012Z","caller":"traceutil/trace.go:171","msg":"trace[920951116] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:800; }","duration":"103.307961ms","start":"2026-05-28T02:02:11.816768Z","end":"2026-05-28T02:02:11.920076Z","steps":["trace[920951116] 'agreement among raft nodes before linearized reading' (duration: 103.121157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:13.38405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.601646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T02:02:13.384128Z","caller":"traceutil/trace.go:171","msg":"trace[2088662451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"352.722609ms","start":"2026-05-28T02:02:13.031385Z","end":"2026-05-28T02:02:13.384108Z","steps":["trace[2088662451] 'range keys from in-memory index tree' (duration: 352.404591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:13.384113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.053721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-28T02:02:13.38417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:13.031363Z","time spent":"352.79568ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-28T02:02:13.384184Z","caller":"traceutil/trace.go:171","msg":"trace[261329383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"381.188706ms","start":"2026-05-28T02:02:13.002978Z","end":"2026-05-28T02:02:13.384167Z","steps":["trace[261329383] 'range keys from in-memory index tree' (duration: 380.848467ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:13.384215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:13.002937Z","time spent":"381.272188ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-28T02:02:13.578669Z","caller":"traceutil/trace.go:171","msg":"trace[1548654320] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"189.076604ms","start":"2026-05-28T02:02:13.389571Z","end":"2026-05-28T02:02:13.578648Z","steps":["trace[1548654320] 'process raft request' (duration: 182.932096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:15.300526Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158765512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:02:15.80064Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8555606105158765512,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-28T02:02:16.049533Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.469590662s","expected-duration":"1s"} {"level":"info","ts":"2026-05-28T02:02:16.105413Z","caller":"traceutil/trace.go:171","msg":"trace[622240771] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.525558405s","start":"2026-05-28T02:02:14.579831Z","end":"2026-05-28T02:02:16.10539Z","steps":["trace[622240771] 'process raft request' (duration: 1.46991782s)","trace[622240771] 'compare' (duration: 55.520662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:16.105585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:14.579815Z","time spent":"1.525651777s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:02:16.107414Z","caller":"traceutil/trace.go:171","msg":"trace[794854131] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:848; }","duration":"1.307821772s","start":"2026-05-28T02:02:14.79957Z","end":"2026-05-28T02:02:16.107392Z","steps":["trace[794854131] 'read index received' (duration: 1.250130578s)","trace[794854131] 'applied index is now lower than readState.Index' (duration: 57.690184ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:16.107475Z","caller":"traceutil/trace.go:171","msg":"trace[1141610362] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"584.724167ms","start":"2026-05-28T02:02:15.522722Z","end":"2026-05-28T02:02:16.107446Z","steps":["trace[1141610362] 'process raft request' (duration: 584.571383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.107645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:15.522701Z","time spent":"584.850389ms","remote":"127.0.0.1:33394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:02:16.107668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.211152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:16.107712Z","caller":"traceutil/trace.go:171","msg":"trace[2010021161] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"760.295594ms","start":"2026-05-28T02:02:15.347404Z","end":"2026-05-28T02:02:16.107699Z","steps":["trace[2010021161] 'agreement among raft nodes before linearized reading' (duration: 760.220202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.107742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:15.34738Z","time spent":"760.356025ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:02:16.107731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144806039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-28T02:02:16.107781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.544138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-28T02:02:16.107808Z","caller":"traceutil/trace.go:171","msg":"trace[107691505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:816; }","duration":"1.144900861s","start":"2026-05-28T02:02:14.962871Z","end":"2026-05-28T02:02:16.107771Z","steps":["trace[107691505] 'agreement among raft nodes before linearized reading' (duration: 1.144786599s)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:16.107843Z","caller":"traceutil/trace.go:171","msg":"trace[1390708568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"699.648711ms","start":"2026-05-28T02:02:15.408179Z","end":"2026-05-28T02:02:16.107828Z","steps":["trace[1390708568] 'agreement among raft nodes before linearized reading' (duration: 699.428406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.107872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:14.962846Z","time spent":"1.145015634s","remote":"127.0.0.1:33146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-28T02:02:16.107877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:15.408152Z","time spent":"699.718342ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T02:02:16.107932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.116163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-28T02:02:16.107977Z","caller":"traceutil/trace.go:171","msg":"trace[1369608519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"526.157314ms","start":"2026-05-28T02:02:15.581811Z","end":"2026-05-28T02:02:16.107968Z","steps":["trace[1369608519] 'agreement among raft nodes before linearized reading' (duration: 526.080142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.108003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:15.581775Z","time spent":"526.221935ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-28T02:02:16.108015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308443137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-28T02:02:16.108095Z","caller":"traceutil/trace.go:171","msg":"trace[1914568962] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"1.3085642s","start":"2026-05-28T02:02:14.799516Z","end":"2026-05-28T02:02:16.10808Z","steps":["trace[1914568962] 'agreement among raft nodes before linearized reading' (duration: 1.308001927s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.108167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:14.79949Z","time spent":"1.308638361s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-28T02:02:16.107947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221413543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-28T02:02:16.108327Z","caller":"traceutil/trace.go:171","msg":"trace[1783070971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"1.221935215s","start":"2026-05-28T02:02:14.88638Z","end":"2026-05-28T02:02:16.108315Z","steps":["trace[1783070971] 'agreement among raft nodes before linearized reading' (duration: 1.221212198s)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:16.108381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:14.886361Z","time spent":"1.222011497s","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-28T02:02:24.473637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.583426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-28T02:02:24.473669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.786864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-28T02:02:24.473721Z","caller":"traceutil/trace.go:171","msg":"trace[1469545467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:898; }","duration":"326.716079ms","start":"2026-05-28T02:02:24.146983Z","end":"2026-05-28T02:02:24.473699Z","steps":["trace[1469545467] 'range keys from in-memory index tree' (duration: 326.481684ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.47373Z","caller":"traceutil/trace.go:171","msg":"trace[1467222629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"331.879436ms","start":"2026-05-28T02:02:24.141834Z","end":"2026-05-28T02:02:24.473713Z","steps":["trace[1467222629] 'range keys from in-memory index tree' (duration: 331.628511ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:24.47376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:24.146973Z","time spent":"326.777371ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-28T02:02:24.473765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:24.141818Z","time spent":"331.939007ms","remote":"127.0.0.1:33416","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-05-28T02:02:24.473642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.703074ms","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-05-28T02:02:24.473683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.268359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-28T02:02:24.473826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.672134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-28T02:02:24.473831Z","caller":"traceutil/trace.go:171","msg":"trace[28185103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:898; }","duration":"336.97459ms","start":"2026-05-28T02:02:24.136831Z","end":"2026-05-28T02:02:24.473805Z","steps":["trace[28185103] 'range keys from in-memory index tree' (duration: 336.51258ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.473886Z","caller":"traceutil/trace.go:171","msg":"trace[1776862213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:898; }","duration":"297.746146ms","start":"2026-05-28T02:02:24.176128Z","end":"2026-05-28T02:02:24.473874Z","steps":["trace[1776862213] 'range keys from in-memory index tree' (duration: 297.50519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:24.473902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:24.136813Z","time spent":"337.071763ms","remote":"127.0.0.1:33416","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-05-28T02:02:24.473949Z","caller":"traceutil/trace.go:171","msg":"trace[954963703] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:898; }","duration":"326.465573ms","start":"2026-05-28T02:02:24.147388Z","end":"2026-05-28T02:02:24.473854Z","steps":["trace[954963703] 'range keys from in-memory index tree' (duration: 326.171657ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:24.474056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.103015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:24.474097Z","caller":"traceutil/trace.go:171","msg":"trace[1885247343] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:899; }","duration":"181.206628ms","start":"2026-05-28T02:02:24.29288Z","end":"2026-05-28T02:02:24.474087Z","steps":["trace[1885247343] 'agreement among raft nodes before linearized reading' (duration: 181.144866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:24.474075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:24.14738Z","time spent":"326.682458ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-28T02:02:24.473999Z","caller":"traceutil/trace.go:171","msg":"trace[1676026837] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"181.029433ms","start":"2026-05-28T02:02:24.292956Z","end":"2026-05-28T02:02:24.473985Z","steps":["trace[1676026837] 'read index received' (duration: 180.566143ms)","trace[1676026837] 'applied index is now lower than readState.Index' (duration: 462.18µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:24.474008Z","caller":"traceutil/trace.go:171","msg":"trace[1445329915] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"241.841735ms","start":"2026-05-28T02:02:24.232152Z","end":"2026-05-28T02:02:24.473994Z","steps":["trace[1445329915] 'process raft request' (duration: 241.358674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:24.474476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.138043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:24.47452Z","caller":"traceutil/trace.go:171","msg":"trace[2031832416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"125.225165ms","start":"2026-05-28T02:02:24.349283Z","end":"2026-05-28T02:02:24.474509Z","steps":["trace[2031832416] 'agreement among raft nodes before linearized reading' (duration: 125.132513ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.579524Z","caller":"traceutil/trace.go:171","msg":"trace[745338919] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"100.338023ms","start":"2026-05-28T02:02:24.479158Z","end":"2026-05-28T02:02:24.579496Z","steps":["trace[745338919] 'process raft request' (duration: 100.18998ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.579652Z","caller":"traceutil/trace.go:171","msg":"trace[265957061] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"100.147719ms","start":"2026-05-28T02:02:24.479478Z","end":"2026-05-28T02:02:24.579626Z","steps":["trace[265957061] 'process raft request' (duration: 99.927724ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.57952Z","caller":"traceutil/trace.go:171","msg":"trace[361069030] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"101.358596ms","start":"2026-05-28T02:02:24.478123Z","end":"2026-05-28T02:02:24.579482Z","steps":["trace[361069030] 'process raft request' (duration: 100.929136ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:24.580423Z","caller":"traceutil/trace.go:171","msg":"trace[1176056611] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"100.19813ms","start":"2026-05-28T02:02:24.480199Z","end":"2026-05-28T02:02:24.580397Z","steps":["trace[1176056611] 'process raft request' (duration: 99.247919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:25.633253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.374964ms","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":"warn","ts":"2026-05-28T02:02:25.633283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.249487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T02:02:25.633377Z","caller":"traceutil/trace.go:171","msg":"trace[653812801] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:906; }","duration":"159.525148ms","start":"2026-05-28T02:02:25.473795Z","end":"2026-05-28T02:02:25.633321Z","steps":["trace[653812801] 'range keys from in-memory index tree' (duration: 159.253082ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:25.633388Z","caller":"traceutil/trace.go:171","msg":"trace[1638351413] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:906; }","duration":"281.405291ms","start":"2026-05-28T02:02:25.351963Z","end":"2026-05-28T02:02:25.633368Z","steps":["trace[1638351413] 'count revisions from in-memory index tree' (duration: 281.154285ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:25.917678Z","caller":"traceutil/trace.go:171","msg":"trace[117283786] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"146.557277ms","start":"2026-05-28T02:02:25.771101Z","end":"2026-05-28T02:02:25.917658Z","steps":["trace[117283786] 'process raft request' (duration: 126.594989ms)","trace[117283786] 'compare' (duration: 19.872236ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:26.969836Z","caller":"traceutil/trace.go:171","msg":"trace[930946008] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"100.294047ms","start":"2026-05-28T02:02:26.86952Z","end":"2026-05-28T02:02:26.969814Z","steps":["trace[930946008] 'process raft request' (duration: 100.152064ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:27.581758Z","caller":"traceutil/trace.go:171","msg":"trace[1232610171] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"126.981877ms","start":"2026-05-28T02:02:27.454752Z","end":"2026-05-28T02:02:27.581734Z","steps":["trace[1232610171] 'read index received' (duration: 123.799817ms)","trace[1232610171] 'applied index is now lower than readState.Index' (duration: 3.18059ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:27.58181Z","caller":"traceutil/trace.go:171","msg":"trace[835853882] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"146.06568ms","start":"2026-05-28T02:02:27.43572Z","end":"2026-05-28T02:02:27.581786Z","steps":["trace[835853882] 'process raft request' (duration: 142.87543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:27.582024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.263433ms","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:1 size:12751"} {"level":"info","ts":"2026-05-28T02:02:27.582059Z","caller":"traceutil/trace.go:171","msg":"trace[371438388] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:960; }","duration":"127.361515ms","start":"2026-05-28T02:02:27.454688Z","end":"2026-05-28T02:02:27.58205Z","steps":["trace[371438388] 'agreement among raft nodes before linearized reading' (duration: 127.179531ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:27.959171Z","caller":"traceutil/trace.go:171","msg":"trace[582346003] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"173.855765ms","start":"2026-05-28T02:02:27.785299Z","end":"2026-05-28T02:02:27.959154Z","steps":["trace[582346003] 'process raft request' (duration: 173.758063ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:28.317211Z","caller":"traceutil/trace.go:171","msg":"trace[554771202] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"115.061468ms","start":"2026-05-28T02:02:28.202118Z","end":"2026-05-28T02:02:28.31718Z","steps":["trace[554771202] 'process raft request' (duration: 43.706854ms)","trace[554771202] 'compare' (duration: 70.923204ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:28.544759Z","caller":"traceutil/trace.go:171","msg":"trace[459468768] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"220.75267ms","start":"2026-05-28T02:02:28.323983Z","end":"2026-05-28T02:02:28.544736Z","steps":["trace[459468768] 'process raft request' (duration: 220.438253ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:28.544916Z","caller":"traceutil/trace.go:171","msg":"trace[814011873] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1006; }","duration":"194.02317ms","start":"2026-05-28T02:02:28.350864Z","end":"2026-05-28T02:02:28.544887Z","steps":["trace[814011873] 'read index received' (duration: 193.998019ms)","trace[814011873] 'applied index is now lower than readState.Index' (duration: 23.201µs)"],"step_count":2} {"level":"warn","ts":"2026-05-28T02:02:28.545077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.210194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:28.545127Z","caller":"traceutil/trace.go:171","msg":"trace[506118957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"194.293816ms","start":"2026-05-28T02:02:28.350824Z","end":"2026-05-28T02:02:28.545118Z","steps":["trace[506118957] 'agreement among raft nodes before linearized reading' (duration: 194.174483ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:28.744538Z","caller":"traceutil/trace.go:171","msg":"trace[755487934] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"109.328382ms","start":"2026-05-28T02:02:28.635189Z","end":"2026-05-28T02:02:28.744517Z","steps":["trace[755487934] 'process raft request' (duration: 109.204689ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:28.744738Z","caller":"traceutil/trace.go:171","msg":"trace[135171319] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"109.531206ms","start":"2026-05-28T02:02:28.635193Z","end":"2026-05-28T02:02:28.744724Z","steps":["trace[135171319] 'process raft request' (duration: 109.2753ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:28.744846Z","caller":"traceutil/trace.go:171","msg":"trace[603946371] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"109.541757ms","start":"2026-05-28T02:02:28.635294Z","end":"2026-05-28T02:02:28.744836Z","steps":["trace[603946371] 'process raft request' (duration: 109.385303ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:29.820755Z","caller":"traceutil/trace.go:171","msg":"trace[1527034873] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"141.370372ms","start":"2026-05-28T02:02:29.679362Z","end":"2026-05-28T02:02:29.820732Z","steps":["trace[1527034873] 'read index received' (duration: 141.109156ms)","trace[1527034873] 'applied index is now lower than readState.Index' (duration: 260.286µs)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:29.82079Z","caller":"traceutil/trace.go:171","msg":"trace[250356876] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"193.369552ms","start":"2026-05-28T02:02:29.627401Z","end":"2026-05-28T02:02:29.820771Z","steps":["trace[250356876] 'process raft request' (duration: 193.162798ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:29.82089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.507905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-28T02:02:29.820922Z","caller":"traceutil/trace.go:171","msg":"trace[952794204] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:977; }","duration":"141.595297ms","start":"2026-05-28T02:02:29.679318Z","end":"2026-05-28T02:02:29.820913Z","steps":["trace[952794204] 'agreement among raft nodes before linearized reading' (duration: 141.507345ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:30.095209Z","caller":"traceutil/trace.go:171","msg":"trace[1125304294] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"268.694053ms","start":"2026-05-28T02:02:29.8265Z","end":"2026-05-28T02:02:30.095194Z","steps":["trace[1125304294] 'process raft request' (duration: 268.590221ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:30.173216Z","caller":"traceutil/trace.go:171","msg":"trace[1335473072] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"346.340847ms","start":"2026-05-28T02:02:29.826848Z","end":"2026-05-28T02:02:30.173189Z","steps":["trace[1335473072] 'process raft request' (duration: 285.408658ms)","trace[1335473072] 'compare' (duration: 60.697774ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:30.173236Z","caller":"traceutil/trace.go:171","msg":"trace[1884960521] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"345.957459ms","start":"2026-05-28T02:02:29.827264Z","end":"2026-05-28T02:02:30.173221Z","steps":["trace[1884960521] 'process raft request' (duration: 345.790515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:30.173348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:29.826833Z","time spent":"346.44ms","remote":"127.0.0.1:33330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1104,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:02:30.173376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:29.827238Z","time spent":"346.047431ms","remote":"127.0.0.1:33424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-28T02:02:30.173447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.149303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:30.173553Z","caller":"traceutil/trace.go:171","msg":"trace[111396438] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:980; }","duration":"278.276176ms","start":"2026-05-28T02:02:29.895243Z","end":"2026-05-28T02:02:30.173519Z","steps":["trace[111396438] 'agreement among raft nodes before linearized reading' (duration: 278.159763ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:30.173141Z","caller":"traceutil/trace.go:171","msg":"trace[687346679] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1014; }","duration":"277.830346ms","start":"2026-05-28T02:02:29.895287Z","end":"2026-05-28T02:02:30.173117Z","steps":["trace[687346679] 'read index received' (duration: 199.875885ms)","trace[687346679] 'applied index is now lower than readState.Index' (duration: 77.953231ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:30.257616Z","caller":"traceutil/trace.go:171","msg":"trace[1263051057] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"100.738388ms","start":"2026-05-28T02:02:30.156838Z","end":"2026-05-28T02:02:30.257576Z","steps":["trace[1263051057] 'process raft request' (duration: 100.591895ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:31.391346Z","caller":"traceutil/trace.go:171","msg":"trace[1476880133] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"141.658526ms","start":"2026-05-28T02:02:31.249661Z","end":"2026-05-28T02:02:31.39132Z","steps":["trace[1476880133] 'process raft request' (duration: 141.543063ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:33.164994Z","caller":"traceutil/trace.go:171","msg":"trace[1459860156] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"307.40953ms","start":"2026-05-28T02:02:32.857558Z","end":"2026-05-28T02:02:33.164967Z","steps":["trace[1459860156] 'process raft request' (duration: 307.218696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:33.16516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:32.857542Z","time spent":"307.533123ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:02:33.861045Z","caller":"traceutil/trace.go:171","msg":"trace[1625693895] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"129.374938ms","start":"2026-05-28T02:02:33.731618Z","end":"2026-05-28T02:02:33.860993Z","steps":["trace[1625693895] 'process raft request' (duration: 129.240305ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:34.844766Z","caller":"traceutil/trace.go:171","msg":"trace[1287885818] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"115.208272ms","start":"2026-05-28T02:02:34.729524Z","end":"2026-05-28T02:02:34.844732Z","steps":["trace[1287885818] 'read index received' (duration: 105.408342ms)","trace[1287885818] 'applied index is now lower than readState.Index' (duration: 9.79854ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:34.844944Z","caller":"traceutil/trace.go:171","msg":"trace[1434544376] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"118.81984ms","start":"2026-05-28T02:02:34.726094Z","end":"2026-05-28T02:02:34.844914Z","steps":["trace[1434544376] 'process raft request' (duration: 108.877117ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:34.845127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.56471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-679c578f5-p5mx2\" ","response":"range_response_count:1 size:3611"} {"level":"info","ts":"2026-05-28T02:02:34.84518Z","caller":"traceutil/trace.go:171","msg":"trace[77650459] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-679c578f5-p5mx2; range_end:; response_count:1; response_revision:1005; }","duration":"115.669762ms","start":"2026-05-28T02:02:34.729493Z","end":"2026-05-28T02:02:34.845163Z","steps":["trace[77650459] 'agreement among raft nodes before linearized reading' (duration: 115.428417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:35.288834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.734432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:35.288909Z","caller":"traceutil/trace.go:171","msg":"trace[1041301752] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1011; }","duration":"172.838854ms","start":"2026-05-28T02:02:35.116051Z","end":"2026-05-28T02:02:35.28889Z","steps":["trace[1041301752] 'count revisions from in-memory index tree' (duration: 172.573219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:35.288834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.994961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-28T02:02:35.289004Z","caller":"traceutil/trace.go:171","msg":"trace[2129737960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"120.227077ms","start":"2026-05-28T02:02:35.168768Z","end":"2026-05-28T02:02:35.288995Z","steps":["trace[2129737960] 'range keys from in-memory index tree' (duration: 119.800607ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:47.096699Z","caller":"traceutil/trace.go:171","msg":"trace[1071472858] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"100.085061ms","start":"2026-05-28T02:02:46.996586Z","end":"2026-05-28T02:02:47.096671Z","steps":["trace[1071472858] 'process raft request' (duration: 57.545966ms)","trace[1071472858] 'compare' (duration: 42.419412ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:59.463687Z","caller":"traceutil/trace.go:171","msg":"trace[653399511] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"269.564992ms","start":"2026-05-28T02:02:59.194097Z","end":"2026-05-28T02:02:59.463662Z","steps":["trace[653399511] 'process raft request' (duration: 269.419169ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:59.50436Z","caller":"traceutil/trace.go:171","msg":"trace[2093201809] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"280.072082ms","start":"2026-05-28T02:02:59.224265Z","end":"2026-05-28T02:02:59.504337Z","steps":["trace[2093201809] 'read index received' (duration: 239.324404ms)","trace[2093201809] 'applied index is now lower than readState.Index' (duration: 40.746498ms)"],"step_count":2} {"level":"info","ts":"2026-05-28T02:02:59.504421Z","caller":"traceutil/trace.go:171","msg":"trace[1964329270] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"309.807081ms","start":"2026-05-28T02:02:59.194592Z","end":"2026-05-28T02:02:59.504399Z","steps":["trace[1964329270] 'process raft request' (duration: 308.845422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:59.504782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.379643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-28T02:02:59.504619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.334417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-28T02:02:59.504927Z","caller":"traceutil/trace.go:171","msg":"trace[537045288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"280.680275ms","start":"2026-05-28T02:02:59.224236Z","end":"2026-05-28T02:02:59.504916Z","steps":["trace[537045288] 'agreement among raft nodes before linearized reading' (duration: 280.232406ms)"],"step_count":1} {"level":"info","ts":"2026-05-28T02:02:59.504851Z","caller":"traceutil/trace.go:171","msg":"trace[1812823365] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1094; }","duration":"152.483184ms","start":"2026-05-28T02:02:59.352347Z","end":"2026-05-28T02:02:59.50483Z","steps":["trace[1812823365] 'agreement among raft nodes before linearized reading' (duration: 152.346661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:59.504725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.718696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-28T02:02:59.505069Z","caller":"traceutil/trace.go:171","msg":"trace[1770668129] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1094; }","duration":"155.117184ms","start":"2026-05-28T02:02:59.349945Z","end":"2026-05-28T02:02:59.505062Z","steps":["trace[1770668129] 'agreement among raft nodes before linearized reading' (duration: 154.713287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-28T02:02:59.505041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-28T02:02:59.194581Z","time spent":"310.222878ms","remote":"127.0.0.1:33416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-28T02:03:23.278825Z","caller":"traceutil/trace.go:171","msg":"trace[2084573459] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"110.440918ms","start":"2026-05-28T02:03:23.168358Z","end":"2026-05-28T02:03:23.278799Z","steps":["trace[2084573459] 'process raft request' (duration: 110.291206ms)"],"step_count":1}