{"level":"warn","ts":"2026-06-10T08:44:02.982032Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:44:02.982139Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.242:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.242:2380","--initial-cluster=instance=https://199.204.45.242:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.242:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.242:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-10T08:44:02.982213Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-10T08:44:02.982223Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-10T08:44:02.982242Z","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-06-10T08:44:02.983108Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"]} {"level":"info","ts":"2026-06-10T08:44:02.983207Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.242:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-10T08:44:02.990192Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.702107ms"} {"level":"info","ts":"2026-06-10T08:44:03.001663Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b844c110b9783e73","cluster-id":"49dc9693ccd466a9"} {"level":"info","ts":"2026-06-10T08:44:03.001751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=()"} {"level":"info","ts":"2026-06-10T08:44:03.001776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 0"} {"level":"info","ts":"2026-06-10T08:44:03.00179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b844c110b9783e73 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-10T08:44:03.001802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became follower at term 1"} {"level":"info","ts":"2026-06-10T08:44:03.001841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"warn","ts":"2026-06-10T08:44:03.011009Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-10T08:44:03.012478Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-10T08:44:03.013655Z","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-06-10T08:44:03.015569Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b844c110b9783e73","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-10T08:44:03.017175Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b844c110b9783e73","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-10T08:44:03.017248Z","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-06-10T08:44:03.017291Z","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-06-10T08:44:03.017301Z","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-06-10T08:44:03.024202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 switched to configuration voters=(13277949878970367603)"} {"level":"info","ts":"2026-06-10T08:44:03.024459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","added-peer-id":"b844c110b9783e73","added-peer-peer-urls":["https://199.204.45.242:2380"]} {"level":"info","ts":"2026-06-10T08:44:03.024499Z","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-06-10T08:44:03.024675Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-10T08:44:03.024692Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.242:2380"} {"level":"info","ts":"2026-06-10T08:44:03.026009Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b844c110b9783e73","initial-advertise-peer-urls":["https://199.204.45.242:2380"],"listen-peer-urls":["https://199.204.45.242:2380"],"advertise-client-urls":["https://199.204.45.242:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.242:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-10T08:44:03.026047Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-10T08:44:03.70268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 is starting a new election at term 1"} {"level":"info","ts":"2026-06-10T08:44:03.702774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-10T08:44:03.702817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgPreVoteResp from b844c110b9783e73 at term 1"} {"level":"info","ts":"2026-06-10T08:44:03.702837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became candidate at term 2"} {"level":"info","ts":"2026-06-10T08:44:03.702849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 received MsgVoteResp from b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-10T08:44:03.702872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b844c110b9783e73 became leader at term 2"} {"level":"info","ts":"2026-06-10T08:44:03.702887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b844c110b9783e73 elected leader b844c110b9783e73 at term 2"} {"level":"info","ts":"2026-06-10T08:44:03.703997Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b844c110b9783e73","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.242:2379]}","request-path":"/0/members/b844c110b9783e73/attributes","cluster-id":"49dc9693ccd466a9","publish-timeout":"7s"} {"level":"info","ts":"2026-06-10T08:44:03.704073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:44:03.704518Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:44:03.705633Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-10T08:44:03.705831Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"49dc9693ccd466a9","local-member-id":"b844c110b9783e73","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:44:03.706002Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:44:03.706057Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-10T08:44:03.705871Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-10T08:44:03.70609Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-10T08:44:03.708621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.242:2379"} {"level":"info","ts":"2026-06-10T08:44:03.709846Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-10T08:44:08.853862Z","caller":"traceutil/trace.go:171","msg":"trace[1530993909] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"153.500953ms","start":"2026-06-10T08:44:08.700333Z","end":"2026-06-10T08:44:08.853834Z","steps":["trace[1530993909] 'process raft request' (duration: 145.455524ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:08.853942Z","caller":"traceutil/trace.go:171","msg":"trace[48434704] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"151.119108ms","start":"2026-06-10T08:44:08.702787Z","end":"2026-06-10T08:44:08.853907Z","steps":["trace[48434704] 'process raft request' (duration: 150.997765ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:08.85393Z","caller":"traceutil/trace.go:171","msg":"trace[107904816] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"153.054733ms","start":"2026-06-10T08:44:08.700838Z","end":"2026-06-10T08:44:08.853893Z","steps":["trace[107904816] 'process raft request' (duration: 152.89206ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:08.854618Z","caller":"traceutil/trace.go:171","msg":"trace[1222445604] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:286; }","duration":"152.08301ms","start":"2026-06-10T08:44:08.702513Z","end":"2026-06-10T08:44:08.854596Z","steps":["trace[1222445604] 'read index received' (duration: 143.292803ms)","trace[1222445604] 'applied index is now lower than readState.Index' (duration: 8.789107ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:08.855406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.634342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:08.855516Z","caller":"traceutil/trace.go:171","msg":"trace[2132516298] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:288; }","duration":"149.780905ms","start":"2026-06-10T08:44:08.705712Z","end":"2026-06-10T08:44:08.855493Z","steps":["trace[2132516298] 'agreement among raft nodes before linearized reading' (duration: 149.593361ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:08.855615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.086713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-06-10T08:44:08.855664Z","caller":"traceutil/trace.go:171","msg":"trace[1974789103] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:288; }","duration":"153.173625ms","start":"2026-06-10T08:44:08.702478Z","end":"2026-06-10T08:44:08.855652Z","steps":["trace[1974789103] 'agreement among raft nodes before linearized reading' (duration: 152.457678ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:10.615273Z","caller":"traceutil/trace.go:171","msg":"trace[341884250] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"220.301261ms","start":"2026-06-10T08:44:10.394943Z","end":"2026-06-10T08:44:10.615244Z","steps":["trace[341884250] 'process raft request' (duration: 220.142177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:13.502155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.944023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:13.502294Z","caller":"traceutil/trace.go:171","msg":"trace[545711950] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"246.641069ms","start":"2026-06-10T08:44:13.255634Z","end":"2026-06-10T08:44:13.502275Z","steps":["trace[545711950] 'process raft request' (duration: 91.712533ms)","trace[545711950] 'compare' (duration: 153.80527ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:13.50251Z","caller":"traceutil/trace.go:171","msg":"trace[1083344941] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"220.21337ms","start":"2026-06-10T08:44:13.282285Z","end":"2026-06-10T08:44:13.502499Z","steps":["trace[1083344941] 'read index received' (duration: 64.941955ms)","trace[1083344941] 'applied index is now lower than readState.Index' (duration: 155.269985ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:13.502687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.372064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-06-10T08:44:13.502725Z","caller":"traceutil/trace.go:171","msg":"trace[435610094] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:373; }","duration":"220.468546ms","start":"2026-06-10T08:44:13.282245Z","end":"2026-06-10T08:44:13.502714Z","steps":["trace[435610094] 'agreement among raft nodes before linearized reading' (duration: 220.364043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:13.502822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.167975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:44:13.502896Z","caller":"traceutil/trace.go:171","msg":"trace[322361580] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:373; }","duration":"101.330058ms","start":"2026-06-10T08:44:13.401547Z","end":"2026-06-10T08:44:13.502877Z","steps":["trace[322361580] 'agreement among raft nodes before linearized reading' (duration: 101.177805ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:19.132475Z","caller":"traceutil/trace.go:171","msg":"trace[700519607] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"355.665889ms","start":"2026-06-10T08:44:18.776793Z","end":"2026-06-10T08:44:19.132459Z","steps":["trace[700519607] 'read index received' (duration: 355.475564ms)","trace[700519607] 'applied index is now lower than readState.Index' (duration: 189.755µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:19.132605Z","caller":"traceutil/trace.go:171","msg":"trace[860703252] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"393.078538ms","start":"2026-06-10T08:44:18.73952Z","end":"2026-06-10T08:44:19.132598Z","steps":["trace[860703252] 'process raft request' (duration: 392.793331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:19.132695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:18.739499Z","time spent":"393.133559ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4649,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:19.132809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.005826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:19.132882Z","caller":"traceutil/trace.go:171","msg":"trace[1049397509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"356.15438ms","start":"2026-06-10T08:44:18.776712Z","end":"2026-06-10T08:44:19.132866Z","steps":["trace[1049397509] 'agreement among raft nodes before linearized reading' (duration: 356.012927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:19.132926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:18.776691Z","time spent":"356.226041ms","remote":"127.0.0.1:39642","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-06-10T08:44:19.964089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.900027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:19.964196Z","caller":"traceutil/trace.go:171","msg":"trace[434152683] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"828.286502ms","start":"2026-06-10T08:44:19.135889Z","end":"2026-06-10T08:44:19.964176Z","steps":["trace[434152683] 'process raft request' (duration: 284.217171ms)","trace[434152683] 'compare' (duration: 543.453306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:19.964259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.135877Z","time spent":"828.349883ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:20.744965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.462013ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:20.745193Z","caller":"traceutil/trace.go:171","msg":"trace[1350554116] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:418; }","duration":"1.549547701s","start":"2026-06-10T08:44:19.195628Z","end":"2026-06-10T08:44:20.745176Z","steps":["trace[1350554116] 'read index received' (duration: 224.480479ms)","trace[1350554116] 'applied index is now lower than readState.Index' (duration: 1.325066142s)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:20.745427Z","caller":"traceutil/trace.go:171","msg":"trace[893984789] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"1.574846635s","start":"2026-06-10T08:44:19.170522Z","end":"2026-06-10T08:44:20.745369Z","steps":["trace[893984789] 'process raft request' (duration: 1.574552828s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.745456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549821687s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:20.745539Z","caller":"traceutil/trace.go:171","msg":"trace[1803009937] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:411; }","duration":"1.54990633s","start":"2026-06-10T08:44:19.19562Z","end":"2026-06-10T08:44:20.745527Z","steps":["trace[1803009937] 'agreement among raft nodes before linearized reading' (duration: 1.549808527s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.745596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159931386s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:44:20.745613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.600461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:44:20.745641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.170493Z","time spent":"1.57503263s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:44:20.745709Z","caller":"traceutil/trace.go:171","msg":"trace[1338819023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"828.736953ms","start":"2026-06-10T08:44:19.916948Z","end":"2026-06-10T08:44:20.745685Z","steps":["trace[1338819023] 'agreement among raft nodes before linearized reading' (duration: 828.578659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.745754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.916926Z","time spent":"828.819005ms","remote":"127.0.0.1:39642","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-06-10T08:44:20.745744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.384556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:20.745804Z","caller":"traceutil/trace.go:171","msg":"trace[1990603536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"918.495728ms","start":"2026-06-10T08:44:19.827292Z","end":"2026-06-10T08:44:20.745788Z","steps":["trace[1990603536] 'agreement among raft nodes before linearized reading' (duration: 918.363085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.745782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045600462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:44:20.745848Z","caller":"traceutil/trace.go:171","msg":"trace[1598127550] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"1.603186121s","start":"2026-06-10T08:44:19.142647Z","end":"2026-06-10T08:44:20.745833Z","steps":["trace[1598127550] 'process raft request' (duration: 1.161767007s)","trace[1598127550] 'compare' (duration: 440.216106ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:20.745831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.827275Z","time spent":"918.5515ms","remote":"127.0.0.1:39656","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-06-10T08:44:20.745691Z","caller":"traceutil/trace.go:171","msg":"trace[983012749] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"1.160033728s","start":"2026-06-10T08:44:19.585632Z","end":"2026-06-10T08:44:20.745666Z","steps":["trace[983012749] 'agreement among raft nodes before linearized reading' (duration: 1.159853733s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.74773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.585576Z","time spent":"1.162131355s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:44:20.74776Z","caller":"traceutil/trace.go:171","msg":"trace[320683110] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:411; }","duration":"1.045745716s","start":"2026-06-10T08:44:19.70012Z","end":"2026-06-10T08:44:20.745866Z","steps":["trace[320683110] 'agreement among raft nodes before linearized reading' (duration: 1.045539391s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.747836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.700098Z","time spent":"1.047719499s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:44:20.748281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:19.14263Z","time spent":"1.603253002s","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:20.756402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.819538ms","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-06-10T08:44:20.75644Z","caller":"traceutil/trace.go:171","msg":"trace[235637252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:411; }","duration":"716.910371ms","start":"2026-06-10T08:44:20.039519Z","end":"2026-06-10T08:44:20.756429Z","steps":["trace[235637252] 'agreement among raft nodes before linearized reading' (duration: 706.367783ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:20.756469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:20.039497Z","time spent":"716.963003ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:44:24.135804Z","caller":"traceutil/trace.go:171","msg":"trace[1676029407] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"111.890174ms","start":"2026-06-10T08:44:24.023889Z","end":"2026-06-10T08:44:24.135779Z","steps":["trace[1676029407] 'process raft request' (duration: 111.763771ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.416137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443556225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:44:43.53169Z","caller":"traceutil/trace.go:171","msg":"trace[148312702] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"636.528542ms","start":"2026-06-10T08:44:42.895127Z","end":"2026-06-10T08:44:43.531655Z","steps":["trace[148312702] 'process raft request' (duration: 636.282696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.532032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:42.895106Z","time spent":"636.747947ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:44:43.538873Z","caller":"traceutil/trace.go:171","msg":"trace[1773740266] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"622.795991ms","start":"2026-06-10T08:44:42.916049Z","end":"2026-06-10T08:44:43.538845Z","steps":["trace[1773740266] 'read index received' (duration: 615.362443ms)","trace[1773740266] 'applied index is now lower than readState.Index' (duration: 7.432348ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:43.539019Z","caller":"traceutil/trace.go:171","msg":"trace[2112527419] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"643.46827ms","start":"2026-06-10T08:44:42.895538Z","end":"2026-06-10T08:44:43.539006Z","steps":["trace[2112527419] 'process raft request' (duration: 636.659496ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.539215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:42.895516Z","time spent":"643.588252ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:43.539309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.221831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:43.539443Z","caller":"traceutil/trace.go:171","msg":"trace[1244520839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:531; }","duration":"623.395834ms","start":"2026-06-10T08:44:42.91602Z","end":"2026-06-10T08:44:43.539416Z","steps":["trace[1244520839] 'agreement among raft nodes before linearized reading' (duration: 623.190779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.53941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.233767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:44:43.539505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:42.916008Z","time spent":"623.486457ms","remote":"127.0.0.1:39656","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-06-10T08:44:43.539545Z","caller":"traceutil/trace.go:171","msg":"trace[1716727039] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:531; }","duration":"591.39868ms","start":"2026-06-10T08:44:42.94811Z","end":"2026-06-10T08:44:43.539508Z","steps":["trace[1716727039] 'agreement among raft nodes before linearized reading' (duration: 591.191555ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.539608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:42.948092Z","time spent":"591.503983ms","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-06-10T08:44:43.541849Z","caller":"traceutil/trace.go:171","msg":"trace[1210364787] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"645.459944ms","start":"2026-06-10T08:44:42.896364Z","end":"2026-06-10T08:44:43.541824Z","steps":["trace[1210364787] 'process raft request' (duration: 642.438575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:43.542002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:42.896351Z","time spent":"645.558147ms","remote":"127.0.0.1:39908","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-06-10T08:44:43.919375Z","caller":"traceutil/trace.go:171","msg":"trace[296884200] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"120.374405ms","start":"2026-06-10T08:44:43.798967Z","end":"2026-06-10T08:44:43.919341Z","steps":["trace[296884200] 'process raft request' (duration: 120.081068ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:43.919619Z","caller":"traceutil/trace.go:171","msg":"trace[948285579] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"107.166976ms","start":"2026-06-10T08:44:43.81244Z","end":"2026-06-10T08:44:43.919607Z","steps":["trace[948285579] 'process raft request' (duration: 106.840249ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:43.919945Z","caller":"traceutil/trace.go:171","msg":"trace[1752348921] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"103.117115ms","start":"2026-06-10T08:44:43.816712Z","end":"2026-06-10T08:44:43.919829Z","steps":["trace[1752348921] 'process raft request' (duration: 102.715196ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:43.919509Z","caller":"traceutil/trace.go:171","msg":"trace[58514574] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"120.414786ms","start":"2026-06-10T08:44:43.799061Z","end":"2026-06-10T08:44:43.919476Z","steps":["trace[58514574] 'process raft request' (duration: 120.169351ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:43.920443Z","caller":"traceutil/trace.go:171","msg":"trace[224913821] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"103.245048ms","start":"2026-06-10T08:44:43.816795Z","end":"2026-06-10T08:44:43.92004Z","steps":["trace[224913821] 'process raft request' (duration: 102.986892ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:44.523412Z","caller":"traceutil/trace.go:171","msg":"trace[1069551412] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"109.345406ms","start":"2026-06-10T08:44:44.414028Z","end":"2026-06-10T08:44:44.523374Z","steps":["trace[1069551412] 'process raft request' (duration: 79.91225ms)","trace[1069551412] 'compare' (duration: 29.261832ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:44.533606Z","caller":"traceutil/trace.go:171","msg":"trace[663298063] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"112.685091ms","start":"2026-06-10T08:44:44.420884Z","end":"2026-06-10T08:44:44.533569Z","steps":["trace[663298063] 'process raft request' (duration: 112.387614ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:44.533612Z","caller":"traceutil/trace.go:171","msg":"trace[931162354] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"110.754797ms","start":"2026-06-10T08:44:44.422824Z","end":"2026-06-10T08:44:44.533578Z","steps":["trace[931162354] 'process raft request' (duration: 110.625304ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:44.672944Z","caller":"traceutil/trace.go:171","msg":"trace[887324285] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"118.190716ms","start":"2026-06-10T08:44:44.554716Z","end":"2026-06-10T08:44:44.672907Z","steps":["trace[887324285] 'process raft request' (duration: 90.658883ms)","trace[887324285] 'compare' (duration: 27.247747ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:44.683445Z","caller":"traceutil/trace.go:171","msg":"trace[508566388] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"120.024737ms","start":"2026-06-10T08:44:44.563389Z","end":"2026-06-10T08:44:44.683414Z","steps":["trace[508566388] 'process raft request' (duration: 119.423283ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:44.829386Z","caller":"traceutil/trace.go:171","msg":"trace[681903264] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"109.998622ms","start":"2026-06-10T08:44:44.719336Z","end":"2026-06-10T08:44:44.829334Z","steps":["trace[681903264] 'process raft request' (duration: 107.123037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:45.427377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.297679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:45.427526Z","caller":"traceutil/trace.go:171","msg":"trace[594867542] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"404.307033ms","start":"2026-06-10T08:44:45.02319Z","end":"2026-06-10T08:44:45.427497Z","steps":["trace[594867542] 'process raft request' (duration: 160.762639ms)","trace[594867542] 'compare' (duration: 243.137375ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:45.427615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:45.02316Z","time spent":"404.412036ms","remote":"127.0.0.1:39690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:44:45.626298Z","caller":"traceutil/trace.go:171","msg":"trace[935360272] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"506.407566ms","start":"2026-06-10T08:44:45.119852Z","end":"2026-06-10T08:44:45.626259Z","steps":["trace[935360272] 'process raft request' (duration: 414.819412ms)","trace[935360272] 'compare' (duration: 91.332808ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:45.626498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:45.119822Z","time spent":"506.57392ms","remote":"127.0.0.1:39690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:44:45.627215Z","caller":"traceutil/trace.go:171","msg":"trace[647446014] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"491.502818ms","start":"2026-06-10T08:44:45.135698Z","end":"2026-06-10T08:44:45.627201Z","steps":["trace[647446014] 'process raft request' (duration: 490.562637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:45.627313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:45.135665Z","time spent":"491.59964ms","remote":"127.0.0.1:39690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:44:45.627415Z","caller":"traceutil/trace.go:171","msg":"trace[567550465] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"170.694774ms","start":"2026-06-10T08:44:45.456715Z","end":"2026-06-10T08:44:45.62741Z","steps":["trace[567550465] 'process raft request' (duration: 170.397607ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:45.775963Z","caller":"traceutil/trace.go:171","msg":"trace[1235446686] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"138.528547ms","start":"2026-06-10T08:44:45.637412Z","end":"2026-06-10T08:44:45.775941Z","steps":["trace[1235446686] 'process raft request' (duration: 133.608646ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:45.776119Z","caller":"traceutil/trace.go:171","msg":"trace[559809927] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"135.624831ms","start":"2026-06-10T08:44:45.640481Z","end":"2026-06-10T08:44:45.776106Z","steps":["trace[559809927] 'process raft request' (duration: 135.346535ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:45.77636Z","caller":"traceutil/trace.go:171","msg":"trace[1200833624] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"105.359576ms","start":"2026-06-10T08:44:45.670994Z","end":"2026-06-10T08:44:45.776354Z","steps":["trace[1200833624] 'process raft request' (duration: 104.943286ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:45.942132Z","caller":"traceutil/trace.go:171","msg":"trace[809185577] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"115.74026ms","start":"2026-06-10T08:44:45.826302Z","end":"2026-06-10T08:44:45.942042Z","steps":["trace[809185577] 'process raft request' (duration: 65.85311ms)","trace[809185577] 'compare' (duration: 49.746967ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:46.384444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.60736ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:46.384579Z","caller":"traceutil/trace.go:171","msg":"trace[1534206554] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"206.494855ms","start":"2026-06-10T08:44:46.178065Z","end":"2026-06-10T08:44:46.38456Z","steps":["trace[1534206554] 'read index received' (duration: 74.65326ms)","trace[1534206554] 'applied index is now lower than readState.Index' (duration: 131.839935ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:46.384713Z","caller":"traceutil/trace.go:171","msg":"trace[870437844] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"218.545168ms","start":"2026-06-10T08:44:46.166154Z","end":"2026-06-10T08:44:46.384699Z","steps":["trace[870437844] 'process raft request' (duration: 86.55386ms)","trace[870437844] 'compare' (duration: 131.429306ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:46.384893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.796642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-06-10T08:44:46.384983Z","caller":"traceutil/trace.go:171","msg":"trace[1625427082] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"206.909455ms","start":"2026-06-10T08:44:46.178052Z","end":"2026-06-10T08:44:46.384961Z","steps":["trace[1625427082] 'agreement among raft nodes before linearized reading' (duration: 206.69555ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:46.397808Z","caller":"traceutil/trace.go:171","msg":"trace[664489586] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"174.746937ms","start":"2026-06-10T08:44:46.223034Z","end":"2026-06-10T08:44:46.397781Z","steps":["trace[664489586] 'process raft request' (duration: 174.571862ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:46.426259Z","caller":"traceutil/trace.go:171","msg":"trace[2048434411] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"159.082832ms","start":"2026-06-10T08:44:46.267159Z","end":"2026-06-10T08:44:46.426241Z","steps":["trace[2048434411] 'process raft request' (duration: 158.752705ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:50.569987Z","caller":"traceutil/trace.go:171","msg":"trace[823728145] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"188.863287ms","start":"2026-06-10T08:44:50.38109Z","end":"2026-06-10T08:44:50.569954Z","steps":["trace[823728145] 'process raft request' (duration: 188.655352ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:50.989693Z","caller":"traceutil/trace.go:171","msg":"trace[2042657184] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:652; }","duration":"548.283144ms","start":"2026-06-10T08:44:50.441375Z","end":"2026-06-10T08:44:50.989659Z","steps":["trace[2042657184] 'read index received' (duration: 128.320875ms)","trace[2042657184] 'applied index is now lower than readState.Index' (duration: 419.961059ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:50.989833Z","caller":"traceutil/trace.go:171","msg":"trace[137100812] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"606.770058ms","start":"2026-06-10T08:44:50.382999Z","end":"2026-06-10T08:44:50.989769Z","steps":["trace[137100812] 'process raft request' (duration: 605.439888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:50.990057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.643923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-10T08:44:50.990132Z","caller":"traceutil/trace.go:171","msg":"trace[1287153250] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:632; }","duration":"548.780305ms","start":"2026-06-10T08:44:50.441337Z","end":"2026-06-10T08:44:50.990117Z","steps":["trace[1287153250] 'agreement among raft nodes before linearized reading' (duration: 548.589731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:50.990196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:50.441319Z","time spent":"548.843247ms","remote":"127.0.0.1:39750","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-06-10T08:44:50.990481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.682651ms","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-06-10T08:44:50.990542Z","caller":"traceutil/trace.go:171","msg":"trace[1524728096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"532.766153ms","start":"2026-06-10T08:44:50.457761Z","end":"2026-06-10T08:44:50.990528Z","steps":["trace[1524728096] 'agreement among raft nodes before linearized reading' (duration: 532.373605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:50.990591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:50.457747Z","time spent":"532.833814ms","remote":"127.0.0.1:39908","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-06-10T08:44:50.990561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.361793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h7tbb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-10T08:44:50.991925Z","caller":"traceutil/trace.go:171","msg":"trace[1029678792] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h7tbb; range_end:; response_count:1; response_revision:632; }","duration":"119.754073ms","start":"2026-06-10T08:44:50.871054Z","end":"2026-06-10T08:44:50.990808Z","steps":["trace[1029678792] 'agreement among raft nodes before linearized reading' (duration: 119.219761ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:50.993083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:50.382972Z","time spent":"610.007032ms","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:52.084865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.15148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:52.085004Z","caller":"traceutil/trace.go:171","msg":"trace[26145577] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"711.150432ms","start":"2026-06-10T08:44:51.373833Z","end":"2026-06-10T08:44:52.084984Z","steps":["trace[26145577] 'process raft request' (duration: 265.781148ms)","trace[26145577] 'compare' (duration: 444.971655ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:52.08506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:51.373792Z","time spent":"711.246314ms","remote":"127.0.0.1:39908","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-06-10T08:44:52.105696Z","caller":"traceutil/trace.go:171","msg":"trace[1862759021] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"381.16601ms","start":"2026-06-10T08:44:51.724494Z","end":"2026-06-10T08:44:52.10566Z","steps":["trace[1862759021] 'read index received' (duration: 379.342089ms)","trace[1862759021] 'applied index is now lower than readState.Index' (duration: 1.822621ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:52.105827Z","caller":"traceutil/trace.go:171","msg":"trace[140000323] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"582.363916ms","start":"2026-06-10T08:44:51.523417Z","end":"2026-06-10T08:44:52.105781Z","steps":["trace[140000323] 'process raft request' (duration: 581.101108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:52.106027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:51.523378Z","time spent":"582.539619ms","remote":"127.0.0.1:39742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:52.10611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.61431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:44:52.106181Z","caller":"traceutil/trace.go:171","msg":"trace[1036084061] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:637; }","duration":"381.687351ms","start":"2026-06-10T08:44:51.724476Z","end":"2026-06-10T08:44:52.106164Z","steps":["trace[1036084061] 'agreement among raft nodes before linearized reading' (duration: 381.400985ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:52.106224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:51.724384Z","time spent":"381.831995ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:44:53.003648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.849307ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-06-10T08:44:53.003868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.34834ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:53.004006Z","caller":"traceutil/trace.go:171","msg":"trace[588561879] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:637; }","duration":"523.493833ms","start":"2026-06-10T08:44:52.480487Z","end":"2026-06-10T08:44:53.003981Z","steps":["trace[588561879] 'range keys from in-memory index tree' (duration: 523.330088ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:53.004008Z","caller":"traceutil/trace.go:171","msg":"trace[236153316] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"898.136886ms","start":"2026-06-10T08:44:52.105849Z","end":"2026-06-10T08:44:53.003986Z","steps":["trace[236153316] 'read index received' (duration: 30.973192ms)","trace[236153316] 'applied index is now lower than readState.Index' (duration: 867.161904ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:53.004499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201241318s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:44:53.004548Z","caller":"traceutil/trace.go:171","msg":"trace[887256329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:637; }","duration":"1.20134717s","start":"2026-06-10T08:44:51.803186Z","end":"2026-06-10T08:44:53.004533Z","steps":["trace[887256329] 'agreement among raft nodes before linearized reading' (duration: 1.201175566s)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:53.004557Z","caller":"traceutil/trace.go:171","msg":"trace[35508634] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"893.386417ms","start":"2026-06-10T08:44:52.111147Z","end":"2026-06-10T08:44:53.004533Z","steps":["trace[35508634] 'process raft request' (duration: 892.662951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.00459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:51.803156Z","time spent":"1.201424822s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:44:53.004732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:52.111123Z","time spent":"893.49732ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:53.121244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.634861ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-06-10T08:44:53.121302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306395789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:44:53.121393Z","caller":"traceutil/trace.go:171","msg":"trace[11930830] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"959.603887ms","start":"2026-06-10T08:44:52.161749Z","end":"2026-06-10T08:44:53.121353Z","steps":["trace[11930830] 'process raft request' (duration: 842.766142ms)","trace[11930830] 'compare' (duration: 116.514328ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:44:53.121478Z","caller":"traceutil/trace.go:171","msg":"trace[1094803584] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:660; }","duration":"117.415639ms","start":"2026-06-10T08:44:53.004048Z","end":"2026-06-10T08:44:53.121464Z","steps":["trace[1094803584] 'read index received' (duration: 355.888µs)","trace[1094803584] 'applied index is now lower than readState.Index' (duration: 117.058621ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:53.121516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:52.161715Z","time spent":"959.760031ms","remote":"127.0.0.1:39998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-10T08:44:53.121563Z","caller":"traceutil/trace.go:171","msg":"trace[528798789] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"1.306641884s","start":"2026-06-10T08:44:51.814842Z","end":"2026-06-10T08:44:53.121484Z","steps":["trace[528798789] 'agreement among raft nodes before linearized reading' (duration: 1.189655015s)","trace[528798789] 'range keys from in-memory index tree' (duration: 116.492478ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:53.121704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.700893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:53.121741Z","caller":"traceutil/trace.go:171","msg":"trace[2139270479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"203.814925ms","start":"2026-06-10T08:44:52.917916Z","end":"2026-06-10T08:44:53.121731Z","steps":["trace[2139270479] 'agreement among raft nodes before linearized reading' (duration: 203.742513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.121839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:51.814831Z","time spent":"1.306963651s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:44:53.121438Z","caller":"traceutil/trace.go:171","msg":"trace[796485606] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"110.609025ms","start":"2026-06-10T08:44:53.010817Z","end":"2026-06-10T08:44:53.121426Z","steps":["trace[796485606] 'process raft request' (duration: 110.550484ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.122003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.167887ms","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-06-10T08:44:53.12205Z","caller":"traceutil/trace.go:171","msg":"trace[576375400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"111.254509ms","start":"2026-06-10T08:44:53.010785Z","end":"2026-06-10T08:44:53.122039Z","steps":["trace[576375400] 'agreement among raft nodes before linearized reading' (duration: 111.088655ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.631232Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443556417,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:44:53.751248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.038323ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:44:53.751363Z","caller":"traceutil/trace.go:171","msg":"trace[1449673869] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"625.112485ms","start":"2026-06-10T08:44:53.126224Z","end":"2026-06-10T08:44:53.751336Z","steps":["trace[1449673869] 'process raft request' (duration: 513.845315ms)","trace[1449673869] 'compare' (duration: 110.713906ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:53.751443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:53.126191Z","time spent":"625.217507ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:44:53.764016Z","caller":"traceutil/trace.go:171","msg":"trace[1218879685] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"633.576126ms","start":"2026-06-10T08:44:53.130415Z","end":"2026-06-10T08:44:53.763992Z","steps":["trace[1218879685] 'read index received' (duration: 509.681591ms)","trace[1218879685] 'applied index is now lower than readState.Index' (duration: 123.892895ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:44:53.764173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.735939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:44:53.764252Z","caller":"traceutil/trace.go:171","msg":"trace[1879272276] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:642; }","duration":"633.859021ms","start":"2026-06-10T08:44:53.130378Z","end":"2026-06-10T08:44:53.764237Z","steps":["trace[1879272276] 'agreement among raft nodes before linearized reading' (duration: 633.695608ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.76429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:53.13037Z","time spent":"633.910113ms","remote":"127.0.0.1:40010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-06-10T08:44:53.764413Z","caller":"traceutil/trace.go:171","msg":"trace[204740070] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"636.607244ms","start":"2026-06-10T08:44:53.1277Z","end":"2026-06-10T08:44:53.764307Z","steps":["trace[204740070] 'process raft request' (duration: 636.196465ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:44:53.764788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:44:53.127693Z","time spent":"636.857579ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:44:55.3086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.638935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:44:55.30869Z","caller":"traceutil/trace.go:171","msg":"trace[2103889926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"182.815089ms","start":"2026-06-10T08:44:55.125856Z","end":"2026-06-10T08:44:55.308671Z","steps":["trace[2103889926] 'range keys from in-memory index tree' (duration: 182.375999ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:44:55.356493Z","caller":"traceutil/trace.go:171","msg":"trace[547485349] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"183.825632ms","start":"2026-06-10T08:44:55.172578Z","end":"2026-06-10T08:44:55.356403Z","steps":["trace[547485349] 'process raft request' (duration: 183.215299ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:05.612851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.896676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:45:05.612978Z","caller":"traceutil/trace.go:171","msg":"trace[246269164] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"158.130971ms","start":"2026-06-10T08:45:05.454824Z","end":"2026-06-10T08:45:05.612955Z","steps":["trace[246269164] 'process raft request' (duration: 49.051651ms)","trace[246269164] 'compare' (duration: 108.680831ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:45:08.095049Z","caller":"traceutil/trace.go:171","msg":"trace[1926838723] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"247.358821ms","start":"2026-06-10T08:45:07.847661Z","end":"2026-06-10T08:45:08.09502Z","steps":["trace[1926838723] 'process raft request' (duration: 247.171157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:08.521997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.107245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:45:08.522106Z","caller":"traceutil/trace.go:171","msg":"trace[1283880778] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"604.587859ms","start":"2026-06-10T08:45:07.917501Z","end":"2026-06-10T08:45:08.522089Z","steps":["trace[1283880778] 'read index received' (duration: 177.315105ms)","trace[1283880778] 'applied index is now lower than readState.Index' (duration: 427.271434ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:08.522209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.706222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:45:08.522237Z","caller":"traceutil/trace.go:171","msg":"trace[126288767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:799; }","duration":"604.766483ms","start":"2026-06-10T08:45:07.917461Z","end":"2026-06-10T08:45:08.522227Z","steps":["trace[126288767] 'agreement among raft nodes before linearized reading' (duration: 604.718482ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:45:08.522186Z","caller":"traceutil/trace.go:171","msg":"trace[1592263630] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"674.123853ms","start":"2026-06-10T08:45:07.848006Z","end":"2026-06-10T08:45:08.52213Z","steps":["trace[1592263630] 'process raft request' (duration: 566.788093ms)","trace[1592263630] 'compare' (duration: 106.994812ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:08.522275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:07.917447Z","time spent":"604.820344ms","remote":"127.0.0.1:39656","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-06-10T08:45:08.522381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.769216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v7cbg\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-06-10T08:45:08.52243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:07.847983Z","time spent":"674.318967ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:45:08.52246Z","caller":"traceutil/trace.go:171","msg":"trace[1386496863] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v7cbg; range_end:; response_count:1; response_revision:799; }","duration":"541.877499ms","start":"2026-06-10T08:45:07.980563Z","end":"2026-06-10T08:45:08.52244Z","steps":["trace[1386496863] 'agreement among raft nodes before linearized reading' (duration: 541.667674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:08.522507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:07.980551Z","time spent":"541.94609ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-v7cbg\" "} {"level":"info","ts":"2026-06-10T08:45:43.053527Z","caller":"traceutil/trace.go:171","msg":"trace[448225963] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"137.635364ms","start":"2026-06-10T08:45:42.915872Z","end":"2026-06-10T08:45:43.053507Z","steps":["trace[448225963] 'read index received' (duration: 137.4798ms)","trace[448225963] 'applied index is now lower than readState.Index' (duration: 154.913µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:43.053637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.748546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:45:43.053663Z","caller":"traceutil/trace.go:171","msg":"trace[1204335540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"137.824768ms","start":"2026-06-10T08:45:42.915829Z","end":"2026-06-10T08:45:43.053654Z","steps":["trace[1204335540] 'agreement among raft nodes before linearized reading' (duration: 137.756266ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:45:43.053713Z","caller":"traceutil/trace.go:171","msg":"trace[1404363575] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"328.330404ms","start":"2026-06-10T08:45:42.725354Z","end":"2026-06-10T08:45:43.053684Z","steps":["trace[1404363575] 'process raft request' (duration: 328.048008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:43.053857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:42.725341Z","time spent":"328.437277ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:45:44.080229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.713574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:45:44.080316Z","caller":"traceutil/trace.go:171","msg":"trace[1545742103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1014; }","duration":"741.840807ms","start":"2026-06-10T08:45:43.338454Z","end":"2026-06-10T08:45:44.080295Z","steps":["trace[1545742103] 'range keys from in-memory index tree' (duration: 741.574511ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.080313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.653163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-06-10T08:45:44.08036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:43.338437Z","time spent":"741.911979ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:45:44.080441Z","caller":"traceutil/trace.go:171","msg":"trace[1014701947] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"712.921739ms","start":"2026-06-10T08:45:43.367497Z","end":"2026-06-10T08:45:44.080419Z","steps":["trace[1014701947] 'process raft request' (duration: 315.083482ms)","trace[1014701947] 'compare' (duration: 397.446328ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:45:44.080497Z","caller":"traceutil/trace.go:171","msg":"trace[974828299] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"216.946728ms","start":"2026-06-10T08:45:43.863534Z","end":"2026-06-10T08:45:44.08048Z","steps":["trace[974828299] 'process raft request' (duration: 216.868207ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.080548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:43.367479Z","time spent":"713.011182ms","remote":"127.0.0.1:39908","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-06-10T08:45:44.080612Z","caller":"traceutil/trace.go:171","msg":"trace[716284810] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"447.376744ms","start":"2026-06-10T08:45:43.633226Z","end":"2026-06-10T08:45:44.080603Z","steps":["trace[716284810] 'read index received' (duration: 49.285212ms)","trace[716284810] 'applied index is now lower than readState.Index' (duration: 398.090692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:44.080801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.579439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:45:44.080828Z","caller":"traceutil/trace.go:171","msg":"trace[2120836265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1016; }","duration":"447.643751ms","start":"2026-06-10T08:45:43.633176Z","end":"2026-06-10T08:45:44.08082Z","steps":["trace[2120836265] 'agreement among raft nodes before linearized reading' (duration: 447.545498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.080859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:43.633156Z","time spent":"447.695913ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:45:44.080969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.977813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:45:44.081021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.318202ms","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-06-10T08:45:44.081027Z","caller":"traceutil/trace.go:171","msg":"trace[295079046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1016; }","duration":"164.073765ms","start":"2026-06-10T08:45:43.916937Z","end":"2026-06-10T08:45:44.081011Z","steps":["trace[295079046] 'agreement among raft nodes before linearized reading' (duration: 163.989863ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:45:44.081068Z","caller":"traceutil/trace.go:171","msg":"trace[130371586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1016; }","duration":"226.440215ms","start":"2026-06-10T08:45:43.854614Z","end":"2026-06-10T08:45:44.081054Z","steps":["trace[130371586] 'agreement among raft nodes before linearized reading' (duration: 226.311502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.917044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.091982ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:45:44.917151Z","caller":"traceutil/trace.go:171","msg":"trace[129092222] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"832.617775ms","start":"2026-06-10T08:45:44.084514Z","end":"2026-06-10T08:45:44.917132Z","steps":["trace[129092222] 'process raft request' (duration: 517.372709ms)","trace[129092222] 'compare' (duration: 314.824836ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:44.917205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:44.084498Z","time spent":"832.680337ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:45:44.938724Z","caller":"traceutil/trace.go:171","msg":"trace[452478697] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"853.536361ms","start":"2026-06-10T08:45:44.085171Z","end":"2026-06-10T08:45:44.938708Z","steps":["trace[452478697] 'process raft request' (duration: 853.387498ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:45:44.938795Z","caller":"traceutil/trace.go:171","msg":"trace[1524007404] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1053; }","duration":"663.912245ms","start":"2026-06-10T08:45:44.274868Z","end":"2026-06-10T08:45:44.93878Z","steps":["trace[1524007404] 'read index received' (duration: 327.025396ms)","trace[1524007404] 'applied index is now lower than readState.Index' (duration: 336.885889ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:45:44.93882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:44.085152Z","time spent":"853.623804ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:45:44.938839Z","caller":"traceutil/trace.go:171","msg":"trace[2116899925] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"853.328987ms","start":"2026-06-10T08:45:44.085494Z","end":"2026-06-10T08:45:44.938823Z","steps":["trace[2116899925] 'process raft request' (duration: 853.143263ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.938909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:44.085486Z","time spent":"853.389668ms","remote":"127.0.0.1:39908","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-06-10T08:45:44.93894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.068228ms","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-06-10T08:45:44.938979Z","caller":"traceutil/trace.go:171","msg":"trace[358783466] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"664.14405ms","start":"2026-06-10T08:45:44.274825Z","end":"2026-06-10T08:45:44.938969Z","steps":["trace[358783466] 'agreement among raft nodes before linearized reading' (duration: 664.003237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:45:44.939007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:45:44.274805Z","time spent":"664.193761ms","remote":"127.0.0.1:39908","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-06-10T08:46:11.607612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443557494,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-10T08:46:11.922003Z","caller":"traceutil/trace.go:171","msg":"trace[654198682] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"814.979134ms","start":"2026-06-10T08:46:11.106942Z","end":"2026-06-10T08:46:11.921921Z","steps":["trace[654198682] 'read index received' (duration: 814.756429ms)","trace[654198682] 'applied index is now lower than readState.Index' (duration: 221.165µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:11.922163Z","caller":"traceutil/trace.go:171","msg":"trace[917337864] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"829.402902ms","start":"2026-06-10T08:46:11.092747Z","end":"2026-06-10T08:46:11.92215Z","steps":["trace[917337864] 'process raft request' (duration: 828.986023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:11.922304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:11.092721Z","time spent":"829.466433ms","remote":"127.0.0.1:39908","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-06-10T08:46:11.92239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.788696ms","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-06-10T08:46:11.922484Z","caller":"traceutil/trace.go:171","msg":"trace[128616257] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"813.919969ms","start":"2026-06-10T08:46:11.108538Z","end":"2026-06-10T08:46:11.922458Z","steps":["trace[128616257] 'agreement among raft nodes before linearized reading' (duration: 813.642913ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:11.922528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:11.108524Z","time spent":"813.99638ms","remote":"127.0.0.1:39908","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-06-10T08:46:11.92256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.042886ms","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-06-10T08:46:11.922643Z","caller":"traceutil/trace.go:171","msg":"trace[1820432393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"750.192339ms","start":"2026-06-10T08:46:11.172435Z","end":"2026-06-10T08:46:11.922627Z","steps":["trace[1820432393] 'agreement among raft nodes before linearized reading' (duration: 749.979774ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:11.92265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.919863ms","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-06-10T08:46:11.922714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:11.172406Z","time spent":"750.297722ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-10T08:46:11.922731Z","caller":"traceutil/trace.go:171","msg":"trace[1008255424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1103; }","duration":"783.058237ms","start":"2026-06-10T08:46:11.13966Z","end":"2026-06-10T08:46:11.922718Z","steps":["trace[1008255424] 'agreement among raft nodes before linearized reading' (duration: 782.813691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:11.922762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:11.139642Z","time spent":"783.113748ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-10T08:46:11.922713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.70134ms","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-06-10T08:46:11.922817Z","caller":"traceutil/trace.go:171","msg":"trace[1353664271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1103; }","duration":"815.913384ms","start":"2026-06-10T08:46:11.106879Z","end":"2026-06-10T08:46:11.922793Z","steps":["trace[1353664271] 'agreement among raft nodes before linearized reading' (duration: 815.562837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:11.922866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:11.10686Z","time spent":"815.996136ms","remote":"127.0.0.1:39908","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-06-10T08:46:12.053969Z","caller":"traceutil/trace.go:171","msg":"trace[881843438] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"125.549458ms","start":"2026-06-10T08:46:11.928388Z","end":"2026-06-10T08:46:12.053938Z","steps":["trace[881843438] 'process raft request' (duration: 107.846425ms)","trace[881843438] 'compare' (duration: 17.56804ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:12.069595Z","caller":"traceutil/trace.go:171","msg":"trace[744312943] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1145; }","duration":"128.555596ms","start":"2026-06-10T08:46:11.941008Z","end":"2026-06-10T08:46:12.069563Z","steps":["trace[744312943] 'read index received' (duration: 95.245518ms)","trace[744312943] 'applied index is now lower than readState.Index' (duration: 33.306368ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:12.069715Z","caller":"traceutil/trace.go:171","msg":"trace[1733361506] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"140.957759ms","start":"2026-06-10T08:46:11.928701Z","end":"2026-06-10T08:46:12.069658Z","steps":["trace[1733361506] 'process raft request' (duration: 140.761475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:12.069795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.74598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:12.069841Z","caller":"traceutil/trace.go:171","msg":"trace[84224035] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1107; }","duration":"128.850903ms","start":"2026-06-10T08:46:11.940977Z","end":"2026-06-10T08:46:12.069828Z","steps":["trace[84224035] 'agreement among raft nodes before linearized reading' (duration: 128.734151ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:12.070033Z","caller":"traceutil/trace.go:171","msg":"trace[1767903555] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"141.572643ms","start":"2026-06-10T08:46:11.92844Z","end":"2026-06-10T08:46:12.070012Z","steps":["trace[1767903555] 'process raft request' (duration: 140.867198ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:12.069899Z","caller":"traceutil/trace.go:171","msg":"trace[2055404494] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"141.026711ms","start":"2026-06-10T08:46:11.928782Z","end":"2026-06-10T08:46:12.069808Z","steps":["trace[2055404494] 'process raft request' (duration: 140.734434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:15.043719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.846852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:15.043813Z","caller":"traceutil/trace.go:171","msg":"trace[1081502072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1115; }","duration":"125.019196ms","start":"2026-06-10T08:46:14.918774Z","end":"2026-06-10T08:46:15.043793Z","steps":["trace[1081502072] 'range keys from in-memory index tree' (duration: 124.74161ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:16.296932Z","caller":"traceutil/trace.go:171","msg":"trace[50788000] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"153.045104ms","start":"2026-06-10T08:46:16.143854Z","end":"2026-06-10T08:46:16.296899Z","steps":["trace[50788000] 'process raft request' (duration: 152.85818ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:16.318088Z","caller":"traceutil/trace.go:171","msg":"trace[189918041] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"173.572632ms","start":"2026-06-10T08:46:16.144484Z","end":"2026-06-10T08:46:16.318057Z","steps":["trace[189918041] 'process raft request' (duration: 173.458899ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:16.318125Z","caller":"traceutil/trace.go:171","msg":"trace[1778828671] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"173.723444ms","start":"2026-06-10T08:46:16.144372Z","end":"2026-06-10T08:46:16.318095Z","steps":["trace[1778828671] 'process raft request' (duration: 173.359536ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:16.318407Z","caller":"traceutil/trace.go:171","msg":"trace[1311201576] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"173.918539ms","start":"2026-06-10T08:46:16.144476Z","end":"2026-06-10T08:46:16.318395Z","steps":["trace[1311201576] 'process raft request' (duration: 173.428488ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:27.052342Z","caller":"traceutil/trace.go:171","msg":"trace[1538812803] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"102.971655ms","start":"2026-06-10T08:46:26.949338Z","end":"2026-06-10T08:46:27.052309Z","steps":["trace[1538812803] 'process raft request' (duration: 70.586837ms)","trace[1538812803] 'compare' (duration: 32.219274ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:30.272383Z","caller":"traceutil/trace.go:171","msg":"trace[578661475] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"177.567525ms","start":"2026-06-10T08:46:30.094793Z","end":"2026-06-10T08:46:30.27236Z","steps":["trace[578661475] 'process raft request' (duration: 177.431262ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:30.517617Z","caller":"traceutil/trace.go:171","msg":"trace[2008424139] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"120.714199ms","start":"2026-06-10T08:46:30.396866Z","end":"2026-06-10T08:46:30.51758Z","steps":["trace[2008424139] 'read index received' (duration: 83.072518ms)","trace[2008424139] 'applied index is now lower than readState.Index' (duration: 37.640661ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:30.517717Z","caller":"traceutil/trace.go:171","msg":"trace[44541294] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"154.33083ms","start":"2026-06-10T08:46:30.363367Z","end":"2026-06-10T08:46:30.517697Z","steps":["trace[44541294] 'process raft request' (duration: 116.660498ms)","trace[44541294] 'compare' (duration: 37.241322ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:46:30.517819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.935544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-06-10T08:46:30.517851Z","caller":"traceutil/trace.go:171","msg":"trace[1091062819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"121.019606ms","start":"2026-06-10T08:46:30.396822Z","end":"2026-06-10T08:46:30.517841Z","steps":["trace[1091062819] 'agreement among raft nodes before linearized reading' (duration: 120.888153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:30.518012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.76654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:46:30.518044Z","caller":"traceutil/trace.go:171","msg":"trace[283270120] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"120.829942ms","start":"2026-06-10T08:46:30.397205Z","end":"2026-06-10T08:46:30.518035Z","steps":["trace[283270120] 'agreement among raft nodes before linearized reading' (duration: 120.73464ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:30.51817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.129791ms","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-06-10T08:46:30.518201Z","caller":"traceutil/trace.go:171","msg":"trace[310984847] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1215; }","duration":"114.195163ms","start":"2026-06-10T08:46:30.403997Z","end":"2026-06-10T08:46:30.518192Z","steps":["trace[310984847] 'agreement among raft nodes before linearized reading' (duration: 114.109701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:30.518329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.288785ms","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-06-10T08:46:30.518361Z","caller":"traceutil/trace.go:171","msg":"trace[2051626742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1215; }","duration":"114.354136ms","start":"2026-06-10T08:46:30.403997Z","end":"2026-06-10T08:46:30.518351Z","steps":["trace[2051626742] 'agreement among raft nodes before linearized reading' (duration: 114.263844ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:32.810689Z","caller":"traceutil/trace.go:171","msg":"trace[1768831606] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"284.376961ms","start":"2026-06-10T08:46:32.526296Z","end":"2026-06-10T08:46:32.810673Z","steps":["trace[1768831606] 'read index received' (duration: 284.219597ms)","trace[1768831606] 'applied index is now lower than readState.Index' (duration: 156.684µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:32.810769Z","caller":"traceutil/trace.go:171","msg":"trace[1952781127] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"287.88304ms","start":"2026-06-10T08:46:32.522865Z","end":"2026-06-10T08:46:32.810748Z","steps":["trace[1952781127] 'process raft request' (duration: 287.685845ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:32.810856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.557715ms","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-06-10T08:46:32.810886Z","caller":"traceutil/trace.go:171","msg":"trace[699143170] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"284.629367ms","start":"2026-06-10T08:46:32.526248Z","end":"2026-06-10T08:46:32.810877Z","steps":["trace[699143170] 'agreement among raft nodes before linearized reading' (duration: 284.533605ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:32.810931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.512398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:46:32.811008Z","caller":"traceutil/trace.go:171","msg":"trace[1465945894] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"282.620891ms","start":"2026-06-10T08:46:32.52837Z","end":"2026-06-10T08:46:32.810991Z","steps":["trace[1465945894] 'agreement among raft nodes before linearized reading' (duration: 282.439177ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:34.725882Z","caller":"traceutil/trace.go:171","msg":"trace[267652048] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"438.24464ms","start":"2026-06-10T08:46:34.287617Z","end":"2026-06-10T08:46:34.725861Z","steps":["trace[267652048] 'process raft request' (duration: 438.131558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:34.725997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:34.287589Z","time spent":"438.345492ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:46:47.186587Z","caller":"traceutil/trace.go:171","msg":"trace[1686971095] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"399.699527ms","start":"2026-06-10T08:46:46.786861Z","end":"2026-06-10T08:46:47.18656Z","steps":["trace[1686971095] 'process raft request' (duration: 399.531744ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.186825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.786835Z","time spent":"399.881272ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:46:47.825272Z","caller":"traceutil/trace.go:171","msg":"trace[1148612912] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"949.200534ms","start":"2026-06-10T08:46:46.876055Z","end":"2026-06-10T08:46:47.825255Z","steps":["trace[1148612912] 'process raft request' (duration: 946.917563ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.876023Z","time spent":"949.300197ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-10T08:46:47.825278Z","caller":"traceutil/trace.go:171","msg":"trace[55553725] linearizableReadLoop","detail":"{readStateIndex:1318; appliedIndex:1316; }","duration":"936.508377ms","start":"2026-06-10T08:46:46.888743Z","end":"2026-06-10T08:46:47.825251Z","steps":["trace[55553725] 'read index received' (duration: 297.675611ms)","trace[55553725] 'applied index is now lower than readState.Index' (duration: 638.831536ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:46:47.825424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.689881ms","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-06-10T08:46:47.825463Z","caller":"traceutil/trace.go:171","msg":"trace[505451929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1267; }","duration":"936.779313ms","start":"2026-06-10T08:46:46.888674Z","end":"2026-06-10T08:46:47.825454Z","steps":["trace[505451929] 'agreement among raft nodes before linearized reading' (duration: 936.64072ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.888651Z","time spent":"936.836435ms","remote":"127.0.0.1:39908","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-06-10T08:46:47.8256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.782048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:47.825665Z","caller":"traceutil/trace.go:171","msg":"trace[336345173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1267; }","duration":"907.8753ms","start":"2026-06-10T08:46:46.91777Z","end":"2026-06-10T08:46:47.825645Z","steps":["trace[336345173] 'agreement among raft nodes before linearized reading' (duration: 907.773158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.650158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-10T08:46:47.825703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.917756Z","time spent":"907.940041ms","remote":"127.0.0.1:39656","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-06-10T08:46:47.825724Z","caller":"traceutil/trace.go:171","msg":"trace[1752078871] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1267; }","duration":"836.69937ms","start":"2026-06-10T08:46:46.989018Z","end":"2026-06-10T08:46:47.825717Z","steps":["trace[1752078871] 'agreement among raft nodes before linearized reading' (duration: 836.632568ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.989003Z","time spent":"836.73899ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-10T08:46:47.825787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.74032ms","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-06-10T08:46:47.825834Z","caller":"traceutil/trace.go:171","msg":"trace[78372205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1267; }","duration":"862.869662ms","start":"2026-06-10T08:46:46.962954Z","end":"2026-06-10T08:46:47.825823Z","steps":["trace[78372205] 'agreement among raft nodes before linearized reading' (duration: 862.739939ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.96641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:46:47.82585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"854.470202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:46:47.825862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.962923Z","time spent":"862.930894ms","remote":"127.0.0.1:39908","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-06-10T08:46:47.825872Z","caller":"traceutil/trace.go:171","msg":"trace[1812863758] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1267; }","duration":"799.98906ms","start":"2026-06-10T08:46:47.025877Z","end":"2026-06-10T08:46:47.825866Z","steps":["trace[1812863758] 'agreement among raft nodes before linearized reading' (duration: 799.956809ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:47.025772Z","time spent":"800.114712ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-10T08:46:47.825889Z","caller":"traceutil/trace.go:171","msg":"trace[1678343188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"854.533543ms","start":"2026-06-10T08:46:46.971346Z","end":"2026-06-10T08:46:47.825879Z","steps":["trace[1678343188] 'agreement among raft nodes before linearized reading' (duration: 854.437691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.825925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.971332Z","time spent":"854.587885ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:46:47.82595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.532779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-10T08:46:47.826042Z","caller":"traceutil/trace.go:171","msg":"trace[1099377963] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1267; }","duration":"883.668663ms","start":"2026-06-10T08:46:46.942348Z","end":"2026-06-10T08:46:47.826016Z","steps":["trace[1099377963] 'agreement among raft nodes before linearized reading' (duration: 883.425577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:47.826083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:46.942325Z","time spent":"883.748945ms","remote":"127.0.0.1:39680","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.242\" "} {"level":"info","ts":"2026-06-10T08:46:49.792936Z","caller":"traceutil/trace.go:171","msg":"trace[1643361851] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"105.621698ms","start":"2026-06-10T08:46:49.687275Z","end":"2026-06-10T08:46:49.792897Z","steps":["trace[1643361851] 'process raft request' (duration: 104.803889ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:50.215588Z","caller":"traceutil/trace.go:171","msg":"trace[1988301581] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"379.653405ms","start":"2026-06-10T08:46:49.835912Z","end":"2026-06-10T08:46:50.215565Z","steps":["trace[1988301581] 'read index received' (duration: 379.45485ms)","trace[1988301581] 'applied index is now lower than readState.Index' (duration: 197.655µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:50.215758Z","caller":"traceutil/trace.go:171","msg":"trace[472719580] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"384.459405ms","start":"2026-06-10T08:46:49.831248Z","end":"2026-06-10T08:46:50.215707Z","steps":["trace[472719580] 'process raft request' (duration: 384.164337ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.215825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.894661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:46:50.215843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.684708ms","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-06-10T08:46:50.21586Z","caller":"traceutil/trace.go:171","msg":"trace[507469095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"379.981993ms","start":"2026-06-10T08:46:49.835869Z","end":"2026-06-10T08:46:50.215851Z","steps":["trace[507469095] 'agreement among raft nodes before linearized reading' (duration: 379.84515ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:50.215876Z","caller":"traceutil/trace.go:171","msg":"trace[1775748608] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"376.739169ms","start":"2026-06-10T08:46:49.839129Z","end":"2026-06-10T08:46:50.215868Z","steps":["trace[1775748608] 'agreement among raft nodes before linearized reading' (duration: 376.645977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.215899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:49.839119Z","time spent":"376.775031ms","remote":"127.0.0.1:39908","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-06-10T08:46:50.215896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:49.83585Z","time spent":"380.038105ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:46:50.215972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:49.831221Z","time spent":"384.633739ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:46:50.21602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.732429ms","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-06-10T08:46:50.21604Z","caller":"traceutil/trace.go:171","msg":"trace[107380106] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1275; }","duration":"204.788491ms","start":"2026-06-10T08:46:50.011245Z","end":"2026-06-10T08:46:50.216034Z","steps":["trace[107380106] 'agreement among raft nodes before linearized reading' (duration: 204.76399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.215994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.438175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:50.216131Z","caller":"traceutil/trace.go:171","msg":"trace[1183436940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1275; }","duration":"178.609269ms","start":"2026-06-10T08:46:50.037505Z","end":"2026-06-10T08:46:50.216115Z","steps":["trace[1183436940] 'agreement among raft nodes before linearized reading' (duration: 178.468886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.216174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.212428ms","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-06-10T08:46:50.216196Z","caller":"traceutil/trace.go:171","msg":"trace[84054510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"376.237538ms","start":"2026-06-10T08:46:49.839953Z","end":"2026-06-10T08:46:50.216191Z","steps":["trace[84054510] 'agreement among raft nodes before linearized reading' (duration: 376.179617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.216212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:49.839945Z","time spent":"376.263169ms","remote":"127.0.0.1:39908","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-06-10T08:46:50.216281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.654898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:46:50.216299Z","caller":"traceutil/trace.go:171","msg":"trace[480658446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"376.686559ms","start":"2026-06-10T08:46:49.839608Z","end":"2026-06-10T08:46:50.216294Z","steps":["trace[480658446] 'agreement among raft nodes before linearized reading' (duration: 376.643138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:50.216313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:49.839601Z","time spent":"376.709419ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:46:50.382785Z","caller":"traceutil/trace.go:171","msg":"trace[178409835] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"162.336721ms","start":"2026-06-10T08:46:50.22042Z","end":"2026-06-10T08:46:50.382756Z","steps":["trace[178409835] 'process raft request' (duration: 112.480994ms)","trace[178409835] 'compare' (duration: 49.635582ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:50.383042Z","caller":"traceutil/trace.go:171","msg":"trace[419931578] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"162.502504ms","start":"2026-06-10T08:46:50.220519Z","end":"2026-06-10T08:46:50.383021Z","steps":["trace[419931578] 'process raft request' (duration: 162.408682ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:50.3831Z","caller":"traceutil/trace.go:171","msg":"trace[314327056] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"162.628057ms","start":"2026-06-10T08:46:50.220438Z","end":"2026-06-10T08:46:50.383066Z","steps":["trace[314327056] 'process raft request' (duration: 162.248829ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:51.415586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443557902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:46:51.916791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443557902,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:46:52.109023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292106018s","expected-duration":"1s"} {"level":"info","ts":"2026-06-10T08:46:52.109328Z","caller":"traceutil/trace.go:171","msg":"trace[78169484] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"1.194627745s","start":"2026-06-10T08:46:50.914685Z","end":"2026-06-10T08:46:52.109313Z","steps":["trace[78169484] 'read index received' (duration: 1.19446445s)","trace[78169484] 'applied index is now lower than readState.Index' (duration: 162.655µs)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:46:52.109456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.194756557s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:52.109417Z","caller":"traceutil/trace.go:171","msg":"trace[310868391] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"1.292527739s","start":"2026-06-10T08:46:50.816832Z","end":"2026-06-10T08:46:52.10936Z","steps":["trace[310868391] 'process raft request' (duration: 1.292368844s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.109567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.301392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-06-10T08:46:52.109586Z","caller":"traceutil/trace.go:171","msg":"trace[265334591] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1281; }","duration":"928.348504ms","start":"2026-06-10T08:46:51.181233Z","end":"2026-06-10T08:46:52.109582Z","steps":["trace[265334591] 'agreement among raft nodes before linearized reading' (duration: 928.258392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.109583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:50.816814Z","time spent":"1.292696712s","remote":"127.0.0.1:39714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-10T08:46:52.109609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:51.18122Z","time spent":"928.385034ms","remote":"127.0.0.1:39808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5354,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-10T08:46:52.109804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.677395ms","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-06-10T08:46:52.109823Z","caller":"traceutil/trace.go:171","msg":"trace[1216836370] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1281; }","duration":"891.716675ms","start":"2026-06-10T08:46:51.218101Z","end":"2026-06-10T08:46:52.109818Z","steps":["trace[1216836370] 'agreement among raft nodes before linearized reading' (duration: 891.655654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.109839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:51.218091Z","time spent":"891.744196ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-10T08:46:52.109477Z","caller":"traceutil/trace.go:171","msg":"trace[708759839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"1.19485513s","start":"2026-06-10T08:46:50.914615Z","end":"2026-06-10T08:46:52.10947Z","steps":["trace[708759839] 'agreement among raft nodes before linearized reading' (duration: 1.194765928s)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.109914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:46:50.9146Z","time spent":"1.1952981s","remote":"127.0.0.1:39656","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-06-10T08:46:52.382604Z","caller":"traceutil/trace.go:171","msg":"trace[2023236044] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"264.699995ms","start":"2026-06-10T08:46:52.117869Z","end":"2026-06-10T08:46:52.382569Z","steps":["trace[2023236044] 'process raft request' (duration: 235.587837ms)","trace[2023236044] 'compare' (duration: 28.623547ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:52.419602Z","caller":"traceutil/trace.go:171","msg":"trace[383127943] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"198.328275ms","start":"2026-06-10T08:46:52.221249Z","end":"2026-06-10T08:46:52.419577Z","steps":["trace[383127943] 'process raft request' (duration: 198.12019ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:52.420092Z","caller":"traceutil/trace.go:171","msg":"trace[933322229] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1334; }","duration":"227.691249ms","start":"2026-06-10T08:46:52.192385Z","end":"2026-06-10T08:46:52.420077Z","steps":["trace[933322229] 'read index received' (duration: 161.081143ms)","trace[933322229] 'applied index is now lower than readState.Index' (duration: 66.608126ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:46:52.420576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.173291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:52.42068Z","caller":"traceutil/trace.go:171","msg":"trace[491762807] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1284; }","duration":"228.321673ms","start":"2026-06-10T08:46:52.192346Z","end":"2026-06-10T08:46:52.420668Z","steps":["trace[491762807] 'agreement among raft nodes before linearized reading' (duration: 228.150109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.458907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.307146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:46:52.458969Z","caller":"traceutil/trace.go:171","msg":"trace[1317970684] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"195.405248ms","start":"2026-06-10T08:46:52.263552Z","end":"2026-06-10T08:46:52.458957Z","steps":["trace[1317970684] 'agreement among raft nodes before linearized reading' (duration: 195.229595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:46:52.459744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.97966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:46:52.459789Z","caller":"traceutil/trace.go:171","msg":"trace[1938121713] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1285; }","duration":"130.051642ms","start":"2026-06-10T08:46:52.329724Z","end":"2026-06-10T08:46:52.459775Z","steps":["trace[1938121713] 'agreement among raft nodes before linearized reading' (duration: 129.99198ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:52.582384Z","caller":"traceutil/trace.go:171","msg":"trace[1960725267] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"115.961772ms","start":"2026-06-10T08:46:52.466404Z","end":"2026-06-10T08:46:52.582366Z","steps":["trace[1960725267] 'process raft request' (duration: 115.925511ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:52.582561Z","caller":"traceutil/trace.go:171","msg":"trace[2081530103] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"118.016568ms","start":"2026-06-10T08:46:52.464511Z","end":"2026-06-10T08:46:52.582528Z","steps":["trace[2081530103] 'process raft request' (duration: 117.707141ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:52.582609Z","caller":"traceutil/trace.go:171","msg":"trace[156479065] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"117.348483ms","start":"2026-06-10T08:46:52.465249Z","end":"2026-06-10T08:46:52.582597Z","steps":["trace[156479065] 'process raft request' (duration: 117.049186ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:46:52.582519Z","caller":"traceutil/trace.go:171","msg":"trace[1883165324] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"118.585881ms","start":"2026-06-10T08:46:52.463892Z","end":"2026-06-10T08:46:52.582478Z","steps":["trace[1883165324] 'process raft request' (duration: 42.183904ms)","trace[1883165324] 'compare' (duration: 76.052359ms)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:46:54.316436Z","caller":"traceutil/trace.go:171","msg":"trace[251923806] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"124.424653ms","start":"2026-06-10T08:46:54.191992Z","end":"2026-06-10T08:46:54.316416Z","steps":["trace[251923806] 'process raft request' (duration: 124.293011ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:47:06.273955Z","caller":"traceutil/trace.go:171","msg":"trace[1149648858] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1443; }","duration":"366.794342ms","start":"2026-06-10T08:47:05.907133Z","end":"2026-06-10T08:47:06.273927Z","steps":["trace[1149648858] 'read index received' (duration: 366.608918ms)","trace[1149648858] 'applied index is now lower than readState.Index' (duration: 184.004µs)"],"step_count":2} {"level":"info","ts":"2026-06-10T08:47:06.274141Z","caller":"traceutil/trace.go:171","msg":"trace[1781322734] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"407.432841ms","start":"2026-06-10T08:47:05.866689Z","end":"2026-06-10T08:47:06.274121Z","steps":["trace[1781322734] 'process raft request' (duration: 407.059612ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:06.274559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:05.866634Z","time spent":"407.856641ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:47:06.274366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.221851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-10T08:47:06.274876Z","caller":"traceutil/trace.go:171","msg":"trace[896064580] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1389; }","duration":"367.761173ms","start":"2026-06-10T08:47:05.907073Z","end":"2026-06-10T08:47:06.274834Z","steps":["trace[896064580] 'agreement among raft nodes before linearized reading' (duration: 367.118049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:06.275029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:05.907051Z","time spent":"367.932078ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-10T08:47:06.805358Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4500114934443558106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-10T08:47:07.285862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.973183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-10T08:47:07.286064Z","caller":"traceutil/trace.go:171","msg":"trace[58673953] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1444; }","duration":"981.622465ms","start":"2026-06-10T08:47:06.304425Z","end":"2026-06-10T08:47:07.286048Z","steps":["trace[58673953] 'read index received' (duration: 796.498569ms)","trace[58673953] 'applied index is now lower than readState.Index' (duration: 185.122956ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:47:07.286288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.8369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-10T08:47:07.286368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.731397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-10T08:47:07.286382Z","caller":"traceutil/trace.go:171","msg":"trace[2084125346] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1391; }","duration":"981.974923ms","start":"2026-06-10T08:47:06.304384Z","end":"2026-06-10T08:47:07.286359Z","steps":["trace[2084125346] 'agreement among raft nodes before linearized reading' (duration: 981.809759ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:47:07.286406Z","caller":"traceutil/trace.go:171","msg":"trace[526878022] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1391; }","duration":"912.815559ms","start":"2026-06-10T08:47:06.373579Z","end":"2026-06-10T08:47:07.286394Z","steps":["trace[526878022] 'agreement among raft nodes before linearized reading' (duration: 912.691406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.373555Z","time spent":"912.885421ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-10T08:47:07.286469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.30436Z","time spent":"982.068845ms","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-06-10T08:47:07.286474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.107603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-10T08:47:07.286553Z","caller":"traceutil/trace.go:171","msg":"trace[712515325] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1391; }","duration":"491.251347ms","start":"2026-06-10T08:47:06.79528Z","end":"2026-06-10T08:47:07.286531Z","steps":["trace[712515325] 'agreement among raft nodes before linearized reading' (duration: 491.059023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.223745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.242\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-10T08:47:07.28663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.795248Z","time spent":"491.370189ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-10T08:47:07.286737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.384786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-10T08:47:07.286766Z","caller":"traceutil/trace.go:171","msg":"trace[2013566070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"624.437898ms","start":"2026-06-10T08:47:06.662322Z","end":"2026-06-10T08:47:07.28676Z","steps":["trace[2013566070] 'agreement among raft nodes before linearized reading' (duration: 624.368576ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.832587ms","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-06-10T08:47:07.286729Z","caller":"traceutil/trace.go:171","msg":"trace[1468496344] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"820.740036ms","start":"2026-06-10T08:47:06.465826Z","end":"2026-06-10T08:47:07.286566Z","steps":["trace[1468496344] 'process raft request' (duration: 820.138712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.662308Z","time spent":"624.481889ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-10T08:47:07.286807Z","caller":"traceutil/trace.go:171","msg":"trace[797644354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1391; }","duration":"624.898898ms","start":"2026-06-10T08:47:06.661898Z","end":"2026-06-10T08:47:07.286797Z","steps":["trace[797644354] 'agreement among raft nodes before linearized reading' (duration: 624.797315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.661881Z","time spent":"624.9607ms","remote":"127.0.0.1:39908","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-06-10T08:47:07.28687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.837062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-10T08:47:07.286639Z","caller":"traceutil/trace.go:171","msg":"trace[1882292257] range","detail":"{range_begin:/registry/masterleases/199.204.45.242; range_end:; response_count:1; response_revision:1391; }","duration":"341.361788ms","start":"2026-06-10T08:47:06.945263Z","end":"2026-06-10T08:47:07.286625Z","steps":["trace[1882292257] 'agreement among raft nodes before linearized reading' (duration: 341.116552ms)"],"step_count":1} {"level":"info","ts":"2026-06-10T08:47:07.286904Z","caller":"traceutil/trace.go:171","msg":"trace[1192849822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"369.894643ms","start":"2026-06-10T08:47:06.917Z","end":"2026-06-10T08:47:07.286895Z","steps":["trace[1192849822] 'agreement among raft nodes before linearized reading' (duration: 369.840902ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.28692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.945229Z","time spent":"341.677605ms","remote":"127.0.0.1:39680","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.242\" "} {"level":"warn","ts":"2026-06-10T08:47:07.286964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.46579Z","time spent":"821.042112ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-10T08:47:07.287013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.015155ms","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-06-10T08:47:07.287042Z","caller":"traceutil/trace.go:171","msg":"trace[1088509368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1391; }","duration":"623.054326ms","start":"2026-06-10T08:47:06.663981Z","end":"2026-06-10T08:47:07.287035Z","steps":["trace[1088509368] 'agreement among raft nodes before linearized reading' (duration: 622.974294ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.286983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.940404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-10T08:47:07.287079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.663974Z","time spent":"623.098447ms","remote":"127.0.0.1:39908","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-06-10T08:47:07.287087Z","caller":"traceutil/trace.go:171","msg":"trace[254213418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1391; }","duration":"627.078617ms","start":"2026-06-10T08:47:06.660001Z","end":"2026-06-10T08:47:07.287079Z","steps":["trace[254213418] 'agreement among raft nodes before linearized reading' (duration: 626.920813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.287116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.659979Z","time spent":"627.129529ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-10T08:47:07.286932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.916939Z","time spent":"369.987306ms","remote":"127.0.0.1:39656","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-06-10T08:47:07.287213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.874891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-10T08:47:07.287238Z","caller":"traceutil/trace.go:171","msg":"trace[1283880011] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1391; }","duration":"514.936262ms","start":"2026-06-10T08:47:06.772292Z","end":"2026-06-10T08:47:07.287228Z","steps":["trace[1283880011] 'agreement among raft nodes before linearized reading' (duration: 514.879421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-10T08:47:07.287262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.77227Z","time spent":"514.987464ms","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-10T08:47:07.286331Z","caller":"traceutil/trace.go:171","msg":"trace[219837718] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"1.006533649s","start":"2026-06-10T08:47:06.279716Z","end":"2026-06-10T08:47:07.28625Z","steps":["trace[219837718] 'process raft request' (duration: 821.094585ms)","trace[219837718] 'compare' (duration: 184.609145ms)"],"step_count":2} {"level":"warn","ts":"2026-06-10T08:47:07.287759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-10T08:47:06.279686Z","time spent":"1.00793997s","remote":"127.0.0.1:39908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}