{"level":"warn","ts":"2026-06-15T20:00:42.459282Z","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-15T20:00:42.459383Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.177: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.177:2380","--initial-cluster=instance=https://199.204.45.177:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.177:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.177: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-15T20:00:42.459448Z","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-15T20:00:42.459461Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-15T20:00:42.459477Z","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-15T20:00:42.460116Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"]} {"level":"info","ts":"2026-06-15T20:00:42.460188Z","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.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.177: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-15T20:00:42.471241Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.857783ms"} {"level":"info","ts":"2026-06-15T20:00:42.481518Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7c53a18098324dc9","cluster-id":"4717e8aa221973c8"} {"level":"info","ts":"2026-06-15T20:00:42.482129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=()"} {"level":"info","ts":"2026-06-15T20:00:42.482211Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 0"} {"level":"info","ts":"2026-06-15T20:00:42.482257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7c53a18098324dc9 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-15T20:00:42.48229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became follower at term 1"} {"level":"info","ts":"2026-06-15T20:00:42.482344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"warn","ts":"2026-06-15T20:00:42.489182Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-15T20:00:42.49119Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-15T20:00:42.493038Z","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-15T20:00:42.494932Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7c53a18098324dc9","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-15T20:00:42.495172Z","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-15T20:00:42.495279Z","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-15T20:00:42.495307Z","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-15T20:00:42.495467Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7c53a18098324dc9","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-15T20:00:42.49656Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 switched to configuration voters=(8958681657451367881)"} {"level":"info","ts":"2026-06-15T20:00:42.501224Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","added-peer-id":"7c53a18098324dc9","added-peer-peer-urls":["https://199.204.45.177:2380"]} {"level":"info","ts":"2026-06-15T20:00:42.503338Z","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-15T20:00:42.503546Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-15T20:00:42.505091Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.177:2380"} {"level":"info","ts":"2026-06-15T20:00:42.505368Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-15T20:00:42.505311Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7c53a18098324dc9","initial-advertise-peer-urls":["https://199.204.45.177:2380"],"listen-peer-urls":["https://199.204.45.177:2380"],"advertise-client-urls":["https://199.204.45.177:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.177:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-15T20:00:43.083788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 is starting a new election at term 1"} {"level":"info","ts":"2026-06-15T20:00:43.083832Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-15T20:00:43.083855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgPreVoteResp from 7c53a18098324dc9 at term 1"} {"level":"info","ts":"2026-06-15T20:00:43.083866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became candidate at term 2"} {"level":"info","ts":"2026-06-15T20:00:43.083871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 received MsgVoteResp from 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-15T20:00:43.083879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7c53a18098324dc9 became leader at term 2"} {"level":"info","ts":"2026-06-15T20:00:43.083887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7c53a18098324dc9 elected leader 7c53a18098324dc9 at term 2"} {"level":"info","ts":"2026-06-15T20:00:43.154718Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7c53a18098324dc9","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.177:2379]}","request-path":"/0/members/7c53a18098324dc9/attributes","cluster-id":"4717e8aa221973c8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-15T20:00:43.154771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:00:43.154807Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:00:43.154863Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-15T20:00:43.15519Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-15T20:00:43.155269Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-15T20:00:43.158208Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.177:2379"} {"level":"info","ts":"2026-06-15T20:00:43.159334Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-15T20:00:43.222588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4717e8aa221973c8","local-member-id":"7c53a18098324dc9","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:00:43.226735Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:00:43.226876Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-15T20:00:46.528286Z","caller":"traceutil/trace.go:171","msg":"trace[1889783702] transaction","detail":"{read_only:false; response_revision:196; number_of_response:1; }","duration":"279.346284ms","start":"2026-06-15T20:00:46.248911Z","end":"2026-06-15T20:00:46.528257Z","steps":["trace[1889783702] 'process raft request' (duration: 271.289709ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:05.195703Z","caller":"traceutil/trace.go:171","msg":"trace[1638708289] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"381.500047ms","start":"2026-06-15T20:01:04.814163Z","end":"2026-06-15T20:01:05.195663Z","steps":["trace[1638708289] 'process raft request' (duration: 381.313833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:05.196517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:04.814131Z","time spent":"381.692592ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:05.308253Z","caller":"traceutil/trace.go:171","msg":"trace[1539530890] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"492.594298ms","start":"2026-06-15T20:01:04.815631Z","end":"2026-06-15T20:01:05.308225Z","steps":["trace[1539530890] 'process raft request' (duration: 451.573224ms)","trace[1539530890] 'compare' (duration: 40.534501ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:05.30853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:04.815623Z","time spent":"492.69043ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:01:21.97781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.321709ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:01:21.978253Z","caller":"traceutil/trace.go:171","msg":"trace[865803098] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"682.062055ms","start":"2026-06-15T20:01:21.296172Z","end":"2026-06-15T20:01:21.978234Z","steps":["trace[865803098] 'process raft request' (duration: 308.10168ms)","trace[865803098] 'compare' (duration: 373.134205ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:21.97832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.296169Z","time spent":"682.117696ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:21.978523Z","caller":"traceutil/trace.go:171","msg":"trace[268030942] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:572; }","duration":"678.503089ms","start":"2026-06-15T20:01:21.29999Z","end":"2026-06-15T20:01:21.978493Z","steps":["trace[268030942] 'read index received' (duration: 2.030599ms)","trace[268030942] 'applied index is now lower than readState.Index' (duration: 676.47039ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:21.978688Z","caller":"traceutil/trace.go:171","msg":"trace[1019677469] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"680.120207ms","start":"2026-06-15T20:01:21.298555Z","end":"2026-06-15T20:01:21.978675Z","steps":["trace[1019677469] 'process raft request' (duration: 679.616225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.97877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.766365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T20:01:21.978821Z","caller":"traceutil/trace.go:171","msg":"trace[62541101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:552; }","duration":"678.841557ms","start":"2026-06-15T20:01:21.299968Z","end":"2026-06-15T20:01:21.978809Z","steps":["trace[62541101] 'agreement among raft nodes before linearized reading' (duration: 678.732485ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.97887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.299961Z","time spent":"678.901179ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:01:21.97903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.298542Z","time spent":"680.253231ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:21.987122Z","caller":"traceutil/trace.go:171","msg":"trace[575395434] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"679.887502ms","start":"2026-06-15T20:01:21.307212Z","end":"2026-06-15T20:01:21.9871Z","steps":["trace[575395434] 'process raft request' (duration: 679.698948ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:21.987232Z","caller":"traceutil/trace.go:171","msg":"trace[1845520010] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"679.933673ms","start":"2026-06-15T20:01:21.307278Z","end":"2026-06-15T20:01:21.987212Z","steps":["trace[1845520010] 'process raft request' (duration: 679.730188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.987239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.307203Z","time spent":"679.980014ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:01:21.987346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.307272Z","time spent":"680.014625ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:21.987701Z","caller":"traceutil/trace.go:171","msg":"trace[237076785] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"678.262614ms","start":"2026-06-15T20:01:21.309423Z","end":"2026-06-15T20:01:21.987686Z","steps":["trace[237076785] 'process raft request' (duration: 677.745241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.987761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.309403Z","time spent":"678.333316ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-15T20:01:21.987906Z","caller":"traceutil/trace.go:171","msg":"trace[45582890] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"675.476636ms","start":"2026-06-15T20:01:21.312421Z","end":"2026-06-15T20:01:21.987897Z","steps":["trace[45582890] 'process raft request' (duration: 675.107947ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.987945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.312415Z","time spent":"675.508977ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:21.988167Z","caller":"traceutil/trace.go:171","msg":"trace[575656682] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"665.477904ms","start":"2026-06-15T20:01:21.32268Z","end":"2026-06-15T20:01:21.988158Z","steps":["trace[575656682] 'process raft request' (duration: 664.927821ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.988207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.322672Z","time spent":"665.516485ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:21.98837Z","caller":"traceutil/trace.go:171","msg":"trace[820580113] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"574.621383ms","start":"2026-06-15T20:01:21.413737Z","end":"2026-06-15T20:01:21.988358Z","steps":["trace[820580113] 'process raft request' (duration: 574.143952ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.988418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.413715Z","time spent":"574.680265ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:01:21.988571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.739062ms","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-15T20:01:21.988609Z","caller":"traceutil/trace.go:171","msg":"trace[644905104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"606.816614ms","start":"2026-06-15T20:01:21.381783Z","end":"2026-06-15T20:01:21.9886Z","steps":["trace[644905104] 'agreement among raft nodes before linearized reading' (duration: 606.734312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.98863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.381764Z","time spent":"606.861704ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:01:21.988736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.126842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:21.988758Z","caller":"traceutil/trace.go:171","msg":"trace[565660961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"484.191984ms","start":"2026-06-15T20:01:21.504561Z","end":"2026-06-15T20:01:21.988753Z","steps":["trace[565660961] 'agreement among raft nodes before linearized reading' (duration: 484.160474ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:21.988791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:21.504538Z","time spent":"484.249325ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:01:22.960029Z","caller":"traceutil/trace.go:171","msg":"trace[2120771986] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"288.391533ms","start":"2026-06-15T20:01:22.671612Z","end":"2026-06-15T20:01:22.960003Z","steps":["trace[2120771986] 'read index received' (duration: 287.728176ms)","trace[2120771986] 'applied index is now lower than readState.Index' (duration: 662.497µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:22.960371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.731841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.960454Z","caller":"traceutil/trace.go:171","msg":"trace[1988663888] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:599; }","duration":"288.852393ms","start":"2026-06-15T20:01:22.671584Z","end":"2026-06-15T20:01:22.960436Z","steps":["trace[1988663888] 'agreement among raft nodes before linearized reading' (duration: 288.651579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.960468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.705161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.960605Z","caller":"traceutil/trace.go:171","msg":"trace[1733679870] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:599; }","duration":"288.885835ms","start":"2026-06-15T20:01:22.671684Z","end":"2026-06-15T20:01:22.96057Z","steps":["trace[1733679870] 'agreement among raft nodes before linearized reading' (duration: 288.631869ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:22.96074Z","caller":"traceutil/trace.go:171","msg":"trace[954384227] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"308.712474ms","start":"2026-06-15T20:01:22.652015Z","end":"2026-06-15T20:01:22.960728Z","steps":["trace[954384227] 'process raft request' (duration: 307.526886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.960821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:22.65199Z","time spent":"308.783507ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-15T20:01:22.961026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.3546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.961083Z","caller":"traceutil/trace.go:171","msg":"trace[55327281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:599; }","duration":"164.441322ms","start":"2026-06-15T20:01:22.796629Z","end":"2026-06-15T20:01:22.96107Z","steps":["trace[55327281] 'agreement among raft nodes before linearized reading' (duration: 164.301108ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.2241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.961177Z","caller":"traceutil/trace.go:171","msg":"trace[625604840] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:599; }","duration":"165.288882ms","start":"2026-06-15T20:01:22.795875Z","end":"2026-06-15T20:01:22.961164Z","steps":["trace[625604840] 'agreement among raft nodes before linearized reading' (duration: 165.21029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.464206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:01:22.961365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.945655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.962677Z","caller":"traceutil/trace.go:171","msg":"trace[307214300] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:599; }","duration":"112.242077ms","start":"2026-06-15T20:01:22.850401Z","end":"2026-06-15T20:01:22.962643Z","steps":["trace[307214300] 'agreement among raft nodes before linearized reading' (duration: 110.937225ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:22.961361Z","caller":"traceutil/trace.go:171","msg":"trace[1582552659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:599; }","duration":"165.510027ms","start":"2026-06-15T20:01:22.795834Z","end":"2026-06-15T20:01:22.961344Z","steps":["trace[1582552659] 'agreement among raft nodes before linearized reading' (duration: 165.445106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.798333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.96314Z","caller":"traceutil/trace.go:171","msg":"trace[1797163266] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:599; }","duration":"195.545935ms","start":"2026-06-15T20:01:22.767579Z","end":"2026-06-15T20:01:22.963125Z","steps":["trace[1797163266] 'agreement among raft nodes before linearized reading' (duration: 193.843653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.935006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963294Z","caller":"traceutil/trace.go:171","msg":"trace[1143830905] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:599; }","duration":"195.720189ms","start":"2026-06-15T20:01:22.767563Z","end":"2026-06-15T20:01:22.963283Z","steps":["trace[1143830905] 'agreement among raft nodes before linearized reading' (duration: 193.927266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.601741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963507Z","caller":"traceutil/trace.go:171","msg":"trace[1841105388] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:599; }","duration":"196.516208ms","start":"2026-06-15T20:01:22.766947Z","end":"2026-06-15T20:01:22.963463Z","steps":["trace[1841105388] 'agreement among raft nodes before linearized reading' (duration: 194.57984ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.369014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963593Z","caller":"traceutil/trace.go:171","msg":"trace[1731443828] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:599; }","duration":"210.370323ms","start":"2026-06-15T20:01:22.753208Z","end":"2026-06-15T20:01:22.963578Z","steps":["trace[1731443828] 'agreement among raft nodes before linearized reading' (duration: 208.377155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.63251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963779Z","caller":"traceutil/trace.go:171","msg":"trace[720561482] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:599; }","duration":"212.752341ms","start":"2026-06-15T20:01:22.751016Z","end":"2026-06-15T20:01:22.963768Z","steps":["trace[720561482] 'agreement among raft nodes before linearized reading' (duration: 210.623989ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.713952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963855Z","caller":"traceutil/trace.go:171","msg":"trace[1971973775] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:599; }","duration":"212.891715ms","start":"2026-06-15T20:01:22.750956Z","end":"2026-06-15T20:01:22.963848Z","steps":["trace[1971973775] 'agreement among raft nodes before linearized reading' (duration: 210.720052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.009572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.963926Z","caller":"traceutil/trace.go:171","msg":"trace[1042603053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:599; }","duration":"254.192515ms","start":"2026-06-15T20:01:22.709719Z","end":"2026-06-15T20:01:22.963912Z","steps":["trace[1042603053] 'agreement among raft nodes before linearized reading' (duration: 252.011622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.093504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964061Z","caller":"traceutil/trace.go:171","msg":"trace[2041242247] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:599; }","duration":"254.346969ms","start":"2026-06-15T20:01:22.709683Z","end":"2026-06-15T20:01:22.96403Z","steps":["trace[2041242247] 'agreement among raft nodes before linearized reading' (duration: 252.082204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.161586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964296Z","caller":"traceutil/trace.go:171","msg":"trace[1582267543] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:599; }","duration":"254.658206ms","start":"2026-06-15T20:01:22.709621Z","end":"2026-06-15T20:01:22.964279Z","steps":["trace[1582267543] 'agreement among raft nodes before linearized reading' (duration: 252.199906ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.96198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.346494ms","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-15T20:01:22.964405Z","caller":"traceutil/trace.go:171","msg":"trace[144054915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"274.833854ms","start":"2026-06-15T20:01:22.68956Z","end":"2026-06-15T20:01:22.964394Z","steps":["trace[144054915] 'agreement among raft nodes before linearized reading' (duration: 272.309873ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.961993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.511672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964588Z","caller":"traceutil/trace.go:171","msg":"trace[579461028] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:599; }","duration":"143.122495ms","start":"2026-06-15T20:01:22.821453Z","end":"2026-06-15T20:01:22.964575Z","steps":["trace[579461028] 'agreement among raft nodes before linearized reading' (duration: 140.500712ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.962048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.405222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964703Z","caller":"traceutil/trace.go:171","msg":"trace[1899791718] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:599; }","duration":"115.070806ms","start":"2026-06-15T20:01:22.849619Z","end":"2026-06-15T20:01:22.96469Z","steps":["trace[1899791718] 'agreement among raft nodes before linearized reading' (duration: 112.412662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.962075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.486603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964874Z","caller":"traceutil/trace.go:171","msg":"trace[283434084] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:599; }","duration":"115.300101ms","start":"2026-06-15T20:01:22.849564Z","end":"2026-06-15T20:01:22.964864Z","steps":["trace[283434084] 'agreement among raft nodes before linearized reading' (duration: 112.498163ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.962103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.635595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.964977Z","caller":"traceutil/trace.go:171","msg":"trace[1433660392] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:599; }","duration":"143.543426ms","start":"2026-06-15T20:01:22.821424Z","end":"2026-06-15T20:01:22.964968Z","steps":["trace[1433660392] 'agreement among raft nodes before linearized reading' (duration: 140.662016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:22.9622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.779968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:22.965081Z","caller":"traceutil/trace.go:171","msg":"trace[1021484873] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:599; }","duration":"143.69642ms","start":"2026-06-15T20:01:22.821378Z","end":"2026-06-15T20:01:22.965074Z","steps":["trace[1021484873] 'agreement among raft nodes before linearized reading' (duration: 140.796199ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:28.232525Z","caller":"traceutil/trace.go:171","msg":"trace[1704334289] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:648; }","duration":"180.226475ms","start":"2026-06-15T20:01:28.052265Z","end":"2026-06-15T20:01:28.232491Z","steps":["trace[1704334289] 'read index received' (duration: 180.01943ms)","trace[1704334289] 'applied index is now lower than readState.Index' (duration: 205.935µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:28.232587Z","caller":"traceutil/trace.go:171","msg":"trace[1047283577] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"209.485372ms","start":"2026-06-15T20:01:28.02306Z","end":"2026-06-15T20:01:28.232545Z","steps":["trace[1047283577] 'process raft request' (duration: 209.188505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:28.232709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.411409ms","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-15T20:01:28.232747Z","caller":"traceutil/trace.go:171","msg":"trace[803419071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"180.517652ms","start":"2026-06-15T20:01:28.052218Z","end":"2026-06-15T20:01:28.232735Z","steps":["trace[803419071] 'agreement among raft nodes before linearized reading' (duration: 180.390589ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:28.24047Z","caller":"traceutil/trace.go:171","msg":"trace[306134800] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"181.730581ms","start":"2026-06-15T20:01:28.058715Z","end":"2026-06-15T20:01:28.240445Z","steps":["trace[306134800] 'process raft request' (duration: 181.093365ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:29.639454Z","caller":"traceutil/trace.go:171","msg":"trace[2068367357] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"345.479574ms","start":"2026-06-15T20:01:29.293942Z","end":"2026-06-15T20:01:29.639421Z","steps":["trace[2068367357] 'read index received' (duration: 344.333917ms)","trace[2068367357] 'applied index is now lower than readState.Index' (duration: 1.144287ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:01:29.639782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.808243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pwmxm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-15T20:01:29.639857Z","caller":"traceutil/trace.go:171","msg":"trace[875453969] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pwmxm; range_end:; response_count:1; response_revision:637; }","duration":"345.955216ms","start":"2026-06-15T20:01:29.293885Z","end":"2026-06-15T20:01:29.63984Z","steps":["trace[875453969] 'agreement among raft nodes before linearized reading' (duration: 345.771042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:29.639936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:29.293864Z","time spent":"346.059608ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-pwmxm\" "} {"level":"info","ts":"2026-06-15T20:01:29.639968Z","caller":"traceutil/trace.go:171","msg":"trace[1864234446] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"417.393287ms","start":"2026-06-15T20:01:29.22255Z","end":"2026-06-15T20:01:29.639944Z","steps":["trace[1864234446] 'process raft request' (duration: 416.119047ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:29.640115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:29.222516Z","time spent":"417.51267ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:29.82458Z","caller":"traceutil/trace.go:171","msg":"trace[1449447662] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"159.228506ms","start":"2026-06-15T20:01:29.665321Z","end":"2026-06-15T20:01:29.82455Z","steps":["trace[1449447662] 'process raft request' (duration: 159.043862ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:29.836983Z","caller":"traceutil/trace.go:171","msg":"trace[134352047] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"142.89764ms","start":"2026-06-15T20:01:29.694021Z","end":"2026-06-15T20:01:29.836919Z","steps":["trace[134352047] 'process raft request' (duration: 142.715235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:30.818177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995059069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:01:30.917315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.069747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-15T20:01:30.91738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.873292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T20:01:30.917408Z","caller":"traceutil/trace.go:171","msg":"trace[2018754397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"673.215251ms","start":"2026-06-15T20:01:30.244171Z","end":"2026-06-15T20:01:30.917386Z","steps":["trace[2018754397] 'range keys from in-memory index tree' (duration: 672.879383ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:30.917449Z","caller":"traceutil/trace.go:171","msg":"trace[1858473406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"679.997656ms","start":"2026-06-15T20:01:30.237432Z","end":"2026-06-15T20:01:30.91743Z","steps":["trace[1858473406] 'range keys from in-memory index tree' (duration: 679.650676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:30.917461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:30.24414Z","time spent":"673.309113ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:01:30.917504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:30.237399Z","time spent":"680.097148ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T20:01:30.959334Z","caller":"traceutil/trace.go:171","msg":"trace[1920930504] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"641.407681ms","start":"2026-06-15T20:01:30.317906Z","end":"2026-06-15T20:01:30.959314Z","steps":["trace[1920930504] 'read index received' (duration: 640.699144ms)","trace[1920930504] 'applied index is now lower than readState.Index' (duration: 707.407µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:30.959451Z","caller":"traceutil/trace.go:171","msg":"trace[1021946698] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"659.050538ms","start":"2026-06-15T20:01:30.300391Z","end":"2026-06-15T20:01:30.959441Z","steps":["trace[1021946698] 'process raft request' (duration: 658.332851ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:30.959551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:30.300353Z","time spent":"659.12247ms","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:01:30.959784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.887612ms","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-15T20:01:30.959814Z","caller":"traceutil/trace.go:171","msg":"trace[1396522262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"641.968904ms","start":"2026-06-15T20:01:30.317836Z","end":"2026-06-15T20:01:30.959805Z","steps":["trace[1396522262] 'agreement among raft nodes before linearized reading' (duration: 641.904363ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:30.959842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:30.317799Z","time spent":"642.036415ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:01:30.960892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.663293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:30.96103Z","caller":"traceutil/trace.go:171","msg":"trace[1899010599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:642; }","duration":"455.831647ms","start":"2026-06-15T20:01:30.505176Z","end":"2026-06-15T20:01:30.961008Z","steps":["trace[1899010599] 'agreement among raft nodes before linearized reading' (duration: 455.602812ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:30.961071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:30.505077Z","time spent":"455.985361ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:01:31.665722Z","caller":"traceutil/trace.go:171","msg":"trace[770424740] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"108.541958ms","start":"2026-06-15T20:01:31.557153Z","end":"2026-06-15T20:01:31.665695Z","steps":["trace[770424740] 'process raft request' (duration: 107.962164ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:32.759499Z","caller":"traceutil/trace.go:171","msg":"trace[1646232516] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"183.443192ms","start":"2026-06-15T20:01:32.576021Z","end":"2026-06-15T20:01:32.759464Z","steps":["trace[1646232516] 'process raft request' (duration: 183.221077ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:32.769922Z","caller":"traceutil/trace.go:171","msg":"trace[1059125285] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"168.284026ms","start":"2026-06-15T20:01:32.601608Z","end":"2026-06-15T20:01:32.769892Z","steps":["trace[1059125285] 'process raft request' (duration: 167.570988ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:36.133355Z","caller":"traceutil/trace.go:171","msg":"trace[563700624] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"388.058866ms","start":"2026-06-15T20:01:35.745255Z","end":"2026-06-15T20:01:36.133314Z","steps":["trace[563700624] 'process raft request' (duration: 386.829788ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:36.133682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.659008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"warn","ts":"2026-06-15T20:01:36.133723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:35.745215Z","time spent":"388.267261ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:01:36.133808Z","caller":"traceutil/trace.go:171","msg":"trace[1961127369] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"358.832512ms","start":"2026-06-15T20:01:35.77493Z","end":"2026-06-15T20:01:36.133762Z","steps":["trace[1961127369] 'agreement among raft nodes before linearized reading' (duration: 358.375312ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:36.133923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:35.774908Z","time spent":"358.950035ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4355,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"info","ts":"2026-06-15T20:01:36.133991Z","caller":"traceutil/trace.go:171","msg":"trace[1019742192] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"358.916044ms","start":"2026-06-15T20:01:35.774981Z","end":"2026-06-15T20:01:36.133897Z","steps":["trace[1019742192] 'read index received' (duration: 358.046875ms)","trace[1019742192] 'applied index is now lower than readState.Index' (duration: 267.586µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:36.211479Z","caller":"traceutil/trace.go:171","msg":"trace[722017061] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"201.976252ms","start":"2026-06-15T20:01:36.009473Z","end":"2026-06-15T20:01:36.211449Z","steps":["trace[722017061] 'process raft request' (duration: 201.128612ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:01:36.211812Z","caller":"traceutil/trace.go:171","msg":"trace[757366898] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"181.199292ms","start":"2026-06-15T20:01:36.030599Z","end":"2026-06-15T20:01:36.211799Z","steps":["trace[757366898] 'process raft request' (duration: 180.250061ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.00574Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995059383,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:01:46.353545Z","caller":"traceutil/trace.go:171","msg":"trace[2008337272] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"848.213636ms","start":"2026-06-15T20:01:45.505305Z","end":"2026-06-15T20:01:46.353519Z","steps":["trace[2008337272] 'read index received' (duration: 847.98385ms)","trace[2008337272] 'applied index is now lower than readState.Index' (duration: 228.436µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:01:46.353643Z","caller":"traceutil/trace.go:171","msg":"trace[1402053542] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"921.517398ms","start":"2026-06-15T20:01:45.43209Z","end":"2026-06-15T20:01:46.353607Z","steps":["trace[1402053542] 'process raft request' (duration: 921.268082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.353694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"848.37531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:01:46.353747Z","caller":"traceutil/trace.go:171","msg":"trace[380249932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:807; }","duration":"848.497423ms","start":"2026-06-15T20:01:45.50524Z","end":"2026-06-15T20:01:46.353737Z","steps":["trace[380249932] 'agreement among raft nodes before linearized reading' (duration: 848.40093ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:01:46.353788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:45.50521Z","time spent":"848.570686ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:01:46.353918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:01:45.432067Z","time spent":"921.657102ms","remote":"127.0.0.1:45618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:01:46.706884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.337767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T20:01:46.706976Z","caller":"traceutil/trace.go:171","msg":"trace[118279443] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:809; }","duration":"136.503281ms","start":"2026-06-15T20:01:46.57045Z","end":"2026-06-15T20:01:46.706954Z","steps":["trace[118279443] 'range keys from in-memory index tree' (duration: 136.115342ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.161689Z","caller":"traceutil/trace.go:171","msg":"trace[709233313] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"130.80889ms","start":"2026-06-15T20:02:16.030857Z","end":"2026-06-15T20:02:16.161666Z","steps":["trace[709233313] 'read index received' (duration: 130.721228ms)","trace[709233313] 'applied index is now lower than readState.Index' (duration: 86.642µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:16.161825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.944913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:02:16.161785Z","caller":"traceutil/trace.go:171","msg":"trace[469185025] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"223.260593ms","start":"2026-06-15T20:02:15.938481Z","end":"2026-06-15T20:02:16.161742Z","steps":["trace[469185025] 'process raft request' (duration: 223.025198ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:16.161871Z","caller":"traceutil/trace.go:171","msg":"trace[1016930485] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1002; }","duration":"131.052895ms","start":"2026-06-15T20:02:16.030808Z","end":"2026-06-15T20:02:16.161861Z","steps":["trace[1016930485] 'agreement among raft nodes before linearized reading' (duration: 130.956683ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:22.176496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.30399ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:22.176598Z","caller":"traceutil/trace.go:171","msg":"trace[646417219] linearizableReadLoop","detail":"{readStateIndex:1061; appliedIndex:1060; }","duration":"830.417646ms","start":"2026-06-15T20:02:21.346168Z","end":"2026-06-15T20:02:22.176585Z","steps":["trace[646417219] 'read index received' (duration: 221.882321ms)","trace[646417219] 'applied index is now lower than readState.Index' (duration: 608.534655ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:22.177597Z","caller":"traceutil/trace.go:171","msg":"trace[745839153] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"908.913437ms","start":"2026-06-15T20:02:21.268641Z","end":"2026-06-15T20:02:22.177555Z","steps":["trace[745839153] 'process raft request' (duration: 299.489632ms)","trace[745839153] 'compare' (duration: 608.200128ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:22.177778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.268612Z","time spent":"909.082232ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:22.177885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.701316ms","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-15T20:02:22.17794Z","caller":"traceutil/trace.go:171","msg":"trace[206841326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"831.794178ms","start":"2026-06-15T20:02:21.346132Z","end":"2026-06-15T20:02:22.177927Z","steps":["trace[206841326] 'agreement among raft nodes before linearized reading' (duration: 831.600123ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:22.177978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.346117Z","time spent":"831.85415ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:02:23.505935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000318088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-15T20:02:23.506053Z","caller":"traceutil/trace.go:171","msg":"trace[860502385] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000493812s","start":"2026-06-15T20:02:21.505539Z","end":"2026-06-15T20:02:23.506032Z","steps":["trace[860502385] 'agreement among raft nodes before linearized reading' (duration: 2.000349229s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:23.506105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.505519Z","time spent":"2.000575894s","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:02:23.74569Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.266028181s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-15T20:02:24.006843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.54594589s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-15T20:02:24.007058Z","caller":"traceutil/trace.go:171","msg":"trace[818673193] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"1.830416517s","start":"2026-06-15T20:02:22.176615Z","end":"2026-06-15T20:02:24.007032Z","steps":["trace[818673193] 'read index received' (duration: 284.276442ms)","trace[818673193] 'applied index is now lower than readState.Index' (duration: 1.546139295s)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.007209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.305759589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:02:24.007306Z","caller":"traceutil/trace.go:171","msg":"trace[2129284957] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1024; }","duration":"2.305890911s","start":"2026-06-15T20:02:21.701369Z","end":"2026-06-15T20:02:24.00726Z","steps":["trace[2129284957] 'agreement among raft nodes before linearized reading' (duration: 2.305778359s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.007352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.701344Z","time spent":"2.305996874s","remote":"127.0.0.1:45528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-06-15T20:02:24.007381Z","caller":"traceutil/trace.go:171","msg":"trace[324524714] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"1.825240988s","start":"2026-06-15T20:02:22.182109Z","end":"2026-06-15T20:02:24.00735Z","steps":["trace[324524714] 'process raft request' (duration: 1.824835317s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:24.007485Z","caller":"traceutil/trace.go:171","msg":"trace[879991348] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"1.825186886s","start":"2026-06-15T20:02:22.182278Z","end":"2026-06-15T20:02:24.007465Z","steps":["trace[879991348] 'process raft request' (duration: 1.824960951s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:24.007526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:22.182086Z","time spent":"1.825364411s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:24.007573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:22.182246Z","time spent":"1.825271528s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:24.076446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.338902233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:24.076538Z","caller":"traceutil/trace.go:171","msg":"trace[81131267] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1026; }","duration":"2.339034686s","start":"2026-06-15T20:02:21.737476Z","end":"2026-06-15T20:02:24.076511Z","steps":["trace[81131267] 'agreement among raft nodes before linearized reading' (duration: 2.26984242s)","trace[81131267] 'count revisions from in-memory index tree' (duration: 69.063234ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.076505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.788237ms","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-15T20:02:24.076667Z","caller":"traceutil/trace.go:171","msg":"trace[1598954956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"809.001432ms","start":"2026-06-15T20:02:23.267619Z","end":"2026-06-15T20:02:24.076621Z","steps":["trace[1598954956] 'agreement among raft nodes before linearized reading' (duration: 739.860046ms)","trace[1598954956] 'range keys from in-memory index tree' (duration: 68.759166ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.076748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:23.267599Z","time spent":"809.133404ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:24.076805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.263051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:24.076848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.730208544s","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-15T20:02:24.076887Z","caller":"traceutil/trace.go:171","msg":"trace[613362931] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"2.730301106s","start":"2026-06-15T20:02:21.346573Z","end":"2026-06-15T20:02:24.076874Z","steps":["trace[613362931] 'agreement among raft nodes before linearized reading' (duration: 2.660631018s)","trace[613362931] 'range keys from in-memory index tree' (duration: 68.91223ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:24.076891Z","caller":"traceutil/trace.go:171","msg":"trace[243916267] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"570.377724ms","start":"2026-06-15T20:02:23.506489Z","end":"2026-06-15T20:02:24.076867Z","steps":["trace[243916267] 'agreement among raft nodes before linearized reading' (duration: 500.963543ms)","trace[243916267] 'range keys from in-memory index tree' (duration: 69.294939ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.076925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.346546Z","time spent":"2.730371818s","remote":"127.0.0.1:45686","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-15T20:02:24.076937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:23.506472Z","time spent":"570.456426ms","remote":"127.0.0.1:45426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:02:24.076583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:21.737461Z","time spent":"2.339111148s","remote":"127.0.0.1:40948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:24.077266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409886091s","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-15T20:02:24.077284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.229723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:02:24.077316Z","caller":"traceutil/trace.go:171","msg":"trace[1369060251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"1.409983663s","start":"2026-06-15T20:02:22.667318Z","end":"2026-06-15T20:02:24.077301Z","steps":["trace[1369060251] 'agreement among raft nodes before linearized reading' (duration: 1.34010526s)","trace[1369060251] 'range keys from in-memory index tree' (duration: 69.73513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.077355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:22.667293Z","time spent":"1.410052945s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T20:02:24.077367Z","caller":"traceutil/trace.go:171","msg":"trace[1578858012] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1026; }","duration":"645.360416ms","start":"2026-06-15T20:02:23.43198Z","end":"2026-06-15T20:02:24.077341Z","steps":["trace[1578858012] 'agreement among raft nodes before linearized reading' (duration: 575.484583ms)","trace[1578858012] 'count revisions from in-memory index tree' (duration: 69.748261ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:24.077422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:23.431959Z","time spent":"645.448158ms","remote":"127.0.0.1:45794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:26.60451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995059879,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:02:26.66676Z","caller":"traceutil/trace.go:171","msg":"trace[465299388] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1071; }","duration":"563.25951ms","start":"2026-06-15T20:02:26.103478Z","end":"2026-06-15T20:02:26.666737Z","steps":["trace[465299388] 'read index received' (duration: 563.006435ms)","trace[465299388] 'applied index is now lower than readState.Index' (duration: 252.035µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:26.666965Z","caller":"traceutil/trace.go:171","msg":"trace[1698377227] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"567.843537ms","start":"2026-06-15T20:02:26.099076Z","end":"2026-06-15T20:02:26.666919Z","steps":["trace[1698377227] 'process raft request' (duration: 567.506469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.667102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.527346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-15T20:02:26.667176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.663892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-15T20:02:26.667198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.533697ms","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-15T20:02:26.667236Z","caller":"traceutil/trace.go:171","msg":"trace[334306396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"460.763934ms","start":"2026-06-15T20:02:26.206451Z","end":"2026-06-15T20:02:26.667215Z","steps":["trace[334306396] 'agreement among raft nodes before linearized reading' (duration: 460.56692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.667232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.099042Z","time spent":"568.042611ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:26.667229Z","caller":"traceutil/trace.go:171","msg":"trace[306834165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"563.782632ms","start":"2026-06-15T20:02:26.103428Z","end":"2026-06-15T20:02:26.66721Z","steps":["trace[306834165] 'agreement among raft nodes before linearized reading' (duration: 563.456495ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.66709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.774447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:26.667292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.206441Z","time spent":"460.843026ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T20:02:26.667344Z","caller":"traceutil/trace.go:171","msg":"trace[1533834793] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1034; }","duration":"163.043323ms","start":"2026-06-15T20:02:26.504281Z","end":"2026-06-15T20:02:26.667324Z","steps":["trace[1533834793] 'agreement among raft nodes before linearized reading' (duration: 162.711895ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:26.667366Z","caller":"traceutil/trace.go:171","msg":"trace[1885500101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"563.6762ms","start":"2026-06-15T20:02:26.103602Z","end":"2026-06-15T20:02:26.667279Z","steps":["trace[1885500101] 'agreement among raft nodes before linearized reading' (duration: 563.357412ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:26.667336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.103403Z","time spent":"563.916606ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:02:26.66742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:26.103597Z","time spent":"563.812033ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:02:26.81105Z","caller":"traceutil/trace.go:171","msg":"trace[1057926969] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"138.375023ms","start":"2026-06-15T20:02:26.67265Z","end":"2026-06-15T20:02:26.811025Z","steps":["trace[1057926969] 'process raft request' (duration: 138.22466ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:26.811347Z","caller":"traceutil/trace.go:171","msg":"trace[2126571323] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"137.808792ms","start":"2026-06-15T20:02:26.673507Z","end":"2026-06-15T20:02:26.811315Z","steps":["trace[2126571323] 'process raft request' (duration: 137.481673ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:26.811421Z","caller":"traceutil/trace.go:171","msg":"trace[1655707452] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"137.74407ms","start":"2026-06-15T20:02:26.673632Z","end":"2026-06-15T20:02:26.811376Z","steps":["trace[1655707452] 'process raft request' (duration: 137.603117ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:49.203605Z","caller":"traceutil/trace.go:171","msg":"trace[1667920253] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"337.291951ms","start":"2026-06-15T20:02:48.866287Z","end":"2026-06-15T20:02:49.203579Z","steps":["trace[1667920253] 'read index received' (duration: 337.061145ms)","trace[1667920253] 'applied index is now lower than readState.Index' (duration: 229.875µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:49.203722Z","caller":"traceutil/trace.go:171","msg":"trace[144683060] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"411.39301ms","start":"2026-06-15T20:02:48.792317Z","end":"2026-06-15T20:02:49.20371Z","steps":["trace[144683060] 'process raft request' (duration: 411.100204ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:49.203843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:48.792294Z","time spent":"411.456252ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:49.204032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.7379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:02:49.204072Z","caller":"traceutil/trace.go:171","msg":"trace[1703167173] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1108; }","duration":"337.810962ms","start":"2026-06-15T20:02:48.866251Z","end":"2026-06-15T20:02:49.204062Z","steps":["trace[1703167173] 'agreement among raft nodes before linearized reading' (duration: 337.741811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:49.204104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:48.866235Z","time spent":"337.860293ms","remote":"127.0.0.1:45820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":29,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:02:49.204426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.485449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-15T20:02:49.204469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.727749ms","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-15T20:02:49.204575Z","caller":"traceutil/trace.go:171","msg":"trace[438048407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1108; }","duration":"150.892532ms","start":"2026-06-15T20:02:49.053659Z","end":"2026-06-15T20:02:49.204551Z","steps":["trace[438048407] 'agreement among raft nodes before linearized reading' (duration: 150.543045ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:49.204616Z","caller":"traceutil/trace.go:171","msg":"trace[321431551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"145.683163ms","start":"2026-06-15T20:02:49.058832Z","end":"2026-06-15T20:02:49.204515Z","steps":["trace[321431551] 'agreement among raft nodes before linearized reading' (duration: 145.364545ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:49.205337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.208996ms","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-15T20:02:49.205727Z","caller":"traceutil/trace.go:171","msg":"trace[2103503255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"238.683388ms","start":"2026-06-15T20:02:48.966968Z","end":"2026-06-15T20:02:49.205651Z","steps":["trace[2103503255] 'agreement among raft nodes before linearized reading' (duration: 237.01586ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:49.519235Z","caller":"traceutil/trace.go:171","msg":"trace[1979755265] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"308.814007ms","start":"2026-06-15T20:02:49.210367Z","end":"2026-06-15T20:02:49.519181Z","steps":["trace[1979755265] 'process raft request' (duration: 230.643403ms)","trace[1979755265] 'compare' (duration: 77.930667ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:49.519502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:49.210342Z","time spent":"309.001101ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:50.105355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.313053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:02:50.105769Z","caller":"traceutil/trace.go:171","msg":"trace[1048520811] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"599.321172ms","start":"2026-06-15T20:02:49.50643Z","end":"2026-06-15T20:02:50.105751Z","steps":["trace[1048520811] 'read index received' (duration: 209.451336ms)","trace[1048520811] 'applied index is now lower than readState.Index' (duration: 389.868796ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:50.105847Z","caller":"traceutil/trace.go:171","msg":"trace[30340675] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"895.358096ms","start":"2026-06-15T20:02:49.210436Z","end":"2026-06-15T20:02:50.105794Z","steps":["trace[30340675] 'process raft request' (duration: 505.525381ms)","trace[30340675] 'compare' (duration: 389.095538ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:50.105916Z","caller":"traceutil/trace.go:171","msg":"trace[353088058] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"892.259915ms","start":"2026-06-15T20:02:49.213625Z","end":"2026-06-15T20:02:50.105885Z","steps":["trace[353088058] 'process raft request' (duration: 891.876706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:50.106013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:49.210407Z","time spent":"895.525169ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:02:50.106038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.614529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:02:50.106113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:49.213614Z","time spent":"892.401478ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:50.106132Z","caller":"traceutil/trace.go:171","msg":"trace[1085910553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1111; }","duration":"599.712851ms","start":"2026-06-15T20:02:49.506344Z","end":"2026-06-15T20:02:50.106079Z","steps":["trace[1085910553] 'agreement among raft nodes before linearized reading' (duration: 599.448995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:50.106369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:49.506322Z","time spent":"599.930756ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:02:50.336139Z","caller":"traceutil/trace.go:171","msg":"trace[799249141] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"121.210971ms","start":"2026-06-15T20:02:50.2149Z","end":"2026-06-15T20:02:50.336111Z","steps":["trace[799249141] 'read index received' (duration: 77.516889ms)","trace[799249141] 'applied index is now lower than readState.Index' (duration: 43.692792ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:50.336218Z","caller":"traceutil/trace.go:171","msg":"trace[1367956116] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"166.51417ms","start":"2026-06-15T20:02:50.169671Z","end":"2026-06-15T20:02:50.336185Z","steps":["trace[1367956116] 'process raft request' (duration: 122.784378ms)","trace[1367956116] 'compare' (duration: 43.506797ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:50.336448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.478767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:02:50.336535Z","caller":"traceutil/trace.go:171","msg":"trace[787298528] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1113; }","duration":"121.712233ms","start":"2026-06-15T20:02:50.214803Z","end":"2026-06-15T20:02:50.336516Z","steps":["trace[787298528] 'agreement among raft nodes before linearized reading' (duration: 121.420106ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:51.98235Z","caller":"traceutil/trace.go:171","msg":"trace[471145125] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"453.521057ms","start":"2026-06-15T20:02:51.528804Z","end":"2026-06-15T20:02:51.982325Z","steps":["trace[471145125] 'process raft request' (duration: 453.268631ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:51.982497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:02:51.528782Z","time spent":"453.63872ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:02:52.215538Z","caller":"traceutil/trace.go:171","msg":"trace[942685024] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"103.781552ms","start":"2026-06-15T20:02:52.111737Z","end":"2026-06-15T20:02:52.215519Z","steps":["trace[942685024] 'read index received' (duration: 5.366963ms)","trace[942685024] 'applied index is now lower than readState.Index' (duration: 98.412979ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:52.215798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.053268ms","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-15T20:02:52.215841Z","caller":"traceutil/trace.go:171","msg":"trace[797836179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"104.15721ms","start":"2026-06-15T20:02:52.111672Z","end":"2026-06-15T20:02:52.21583Z","steps":["trace[797836179] 'agreement among raft nodes before linearized reading' (duration: 103.952285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:52.215994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.221401ms","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-15T20:02:52.216025Z","caller":"traceutil/trace.go:171","msg":"trace[250379761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1117; }","duration":"104.290833ms","start":"2026-06-15T20:02:52.111726Z","end":"2026-06-15T20:02:52.216017Z","steps":["trace[250379761] 'agreement among raft nodes before linearized reading' (duration: 104.209481ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:54.249767Z","caller":"traceutil/trace.go:171","msg":"trace[1571926285] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"258.210284ms","start":"2026-06-15T20:02:53.991534Z","end":"2026-06-15T20:02:54.249745Z","steps":["trace[1571926285] 'process raft request' (duration: 258.090792ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:02:54.377598Z","caller":"traceutil/trace.go:171","msg":"trace[1230113789] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1165; }","duration":"143.074603ms","start":"2026-06-15T20:02:54.234484Z","end":"2026-06-15T20:02:54.377558Z","steps":["trace[1230113789] 'read index received' (duration: 15.160697ms)","trace[1230113789] 'applied index is now lower than readState.Index' (duration: 127.911176ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:02:54.377778Z","caller":"traceutil/trace.go:171","msg":"trace[1012269871] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"154.339281ms","start":"2026-06-15T20:02:54.22342Z","end":"2026-06-15T20:02:54.377759Z","steps":["trace[1012269871] 'process raft request' (duration: 122.626724ms)","trace[1012269871] 'compare' (duration: 31.206006ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:02:54.37806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.109369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T20:02:54.378232Z","caller":"traceutil/trace.go:171","msg":"trace[252619100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1123; }","duration":"121.306593ms","start":"2026-06-15T20:02:54.256829Z","end":"2026-06-15T20:02:54.378136Z","steps":["trace[252619100] 'agreement among raft nodes before linearized reading' (duration: 121.016936ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:02:54.3783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.694847ms","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-15T20:02:54.378569Z","caller":"traceutil/trace.go:171","msg":"trace[1300816910] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1123; }","duration":"144.053545ms","start":"2026-06-15T20:02:54.234429Z","end":"2026-06-15T20:02:54.378482Z","steps":["trace[1300816910] 'agreement among raft nodes before linearized reading' (duration: 143.577354ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:07.939192Z","caller":"traceutil/trace.go:171","msg":"trace[244306731] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"110.69687ms","start":"2026-06-15T20:03:07.828475Z","end":"2026-06-15T20:03:07.939172Z","steps":["trace[244306731] 'process raft request' (duration: 110.571737ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:08.566088Z","caller":"traceutil/trace.go:171","msg":"trace[2042852185] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"142.377227ms","start":"2026-06-15T20:03:08.42369Z","end":"2026-06-15T20:03:08.566067Z","steps":["trace[2042852185] 'process raft request' (duration: 140.874012ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.005083Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995060351,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:03:11.148295Z","caller":"traceutil/trace.go:171","msg":"trace[427313468] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"754.78524ms","start":"2026-06-15T20:03:10.393446Z","end":"2026-06-15T20:03:11.148231Z","steps":["trace[427313468] 'process raft request' (duration: 754.626066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.148465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.39341Z","time spent":"754.961613ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:11.151285Z","caller":"traceutil/trace.go:171","msg":"trace[294970865] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"580.91135ms","start":"2026-06-15T20:03:10.570359Z","end":"2026-06-15T20:03:11.15127Z","steps":["trace[294970865] 'process raft request' (duration: 580.800747ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.151418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.570332Z","time spent":"580.997473ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:11.151593Z","caller":"traceutil/trace.go:171","msg":"trace[2065225457] linearizableReadLoop","detail":"{readStateIndex:1276; appliedIndex:1274; }","duration":"647.444788ms","start":"2026-06-15T20:03:10.504108Z","end":"2026-06-15T20:03:11.151553Z","steps":["trace[2065225457] 'read index received' (duration: 643.980938ms)","trace[2065225457] 'applied index is now lower than readState.Index' (duration: 3.46219ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:11.151808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.680743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:11.151856Z","caller":"traceutil/trace.go:171","msg":"trace[155541656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"647.792295ms","start":"2026-06-15T20:03:10.504049Z","end":"2026-06-15T20:03:11.151841Z","steps":["trace[155541656] 'agreement among raft nodes before linearized reading' (duration: 647.692703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.151874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.700957ms","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-15T20:03:11.152155Z","caller":"traceutil/trace.go:171","msg":"trace[202652388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"575.045525ms","start":"2026-06-15T20:03:10.577096Z","end":"2026-06-15T20:03:11.152142Z","steps":["trace[202652388] 'agreement among raft nodes before linearized reading' (duration: 574.679887ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.152215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.57706Z","time spent":"575.143718ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:11.151894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.504026Z","time spent":"647.859387ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:03:11.152181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.84374ms","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-15T20:03:11.152292Z","caller":"traceutil/trace.go:171","msg":"trace[873669655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1228; }","duration":"575.017064ms","start":"2026-06-15T20:03:10.577249Z","end":"2026-06-15T20:03:11.152266Z","steps":["trace[873669655] 'agreement among raft nodes before linearized reading' (duration: 574.752278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.152346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.577212Z","time spent":"575.119966ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:11.152621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.757919ms","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-15T20:03:11.152666Z","caller":"traceutil/trace.go:171","msg":"trace[41196419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1228; }","duration":"574.845701ms","start":"2026-06-15T20:03:10.577808Z","end":"2026-06-15T20:03:11.152654Z","steps":["trace[41196419] 'agreement among raft nodes before linearized reading' (duration: 574.136335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:11.152697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:10.57779Z","time spent":"574.900412ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:03:27.107163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:26.578384Z","time spent":"528.772433ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-15T20:03:27.788288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995060495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:28.083032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.917465ms","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-15T20:03:28.083148Z","caller":"traceutil/trace.go:171","msg":"trace[326835663] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1285; }","duration":"786.048808ms","start":"2026-06-15T20:03:27.297081Z","end":"2026-06-15T20:03:28.08313Z","steps":["trace[326835663] 'range keys from in-memory index tree' (duration: 785.897584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.083657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.78757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:03:28.083807Z","caller":"traceutil/trace.go:171","msg":"trace[1019359451] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"975.335041ms","start":"2026-06-15T20:03:27.108444Z","end":"2026-06-15T20:03:28.083779Z","steps":["trace[1019359451] 'process raft request' (duration: 831.300346ms)","trace[1019359451] 'compare' (duration: 143.429842ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:28.084095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.108423Z","time spent":"975.630657ms","remote":"127.0.0.1:45460","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:28.308746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.398543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:03:28.308872Z","caller":"traceutil/trace.go:171","msg":"trace[510747818] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.066765719s","start":"2026-06-15T20:03:27.242077Z","end":"2026-06-15T20:03:28.308842Z","steps":["trace[510747818] 'process raft request' (duration: 963.204013ms)","trace[510747818] 'compare' (duration: 103.2809ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:28.309019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.242053Z","time spent":"1.066882332s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:28.309095Z","caller":"traceutil/trace.go:171","msg":"trace[219421782] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"1.021612312s","start":"2026-06-15T20:03:27.28747Z","end":"2026-06-15T20:03:28.309082Z","steps":["trace[219421782] 'read index received' (duration: 652.281647ms)","trace[219421782] 'applied index is now lower than readState.Index' (duration: 369.329465ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:28.309224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021754245s","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-15T20:03:28.309252Z","caller":"traceutil/trace.go:171","msg":"trace[558478767] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1288; }","duration":"1.021810256s","start":"2026-06-15T20:03:27.287435Z","end":"2026-06-15T20:03:28.309245Z","steps":["trace[558478767] 'agreement among raft nodes before linearized reading' (duration: 1.021697954s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.199365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-15T20:03:28.309272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.28742Z","time spent":"1.021848197s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-15T20:03:28.309283Z","caller":"traceutil/trace.go:171","msg":"trace[1249422612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1288; }","duration":"998.286067ms","start":"2026-06-15T20:03:27.31099Z","end":"2026-06-15T20:03:28.309277Z","steps":["trace[1249422612] 'agreement among raft nodes before linearized reading' (duration: 998.169705ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.310935Z","time spent":"998.363749ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:03:28.30935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.002863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T20:03:28.309411Z","caller":"traceutil/trace.go:171","msg":"trace[1275797508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1288; }","duration":"768.080595ms","start":"2026-06-15T20:03:27.541317Z","end":"2026-06-15T20:03:28.309398Z","steps":["trace[1275797508] 'agreement among raft nodes before linearized reading' (duration: 767.888981ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.569879ms","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-15T20:03:28.309447Z","caller":"traceutil/trace.go:171","msg":"trace[1768968806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1288; }","duration":"976.63353ms","start":"2026-06-15T20:03:27.332808Z","end":"2026-06-15T20:03:28.309442Z","steps":["trace[1768968806] 'agreement among raft nodes before linearized reading' (duration: 976.585509ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.176379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:03:28.309456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.708395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-15T20:03:28.30947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.332775Z","time spent":"976.690351ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-15T20:03:28.309476Z","caller":"traceutil/trace.go:171","msg":"trace[1927010235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1288; }","duration":"994.748677ms","start":"2026-06-15T20:03:27.314722Z","end":"2026-06-15T20:03:28.30947Z","steps":["trace[1927010235] 'agreement among raft nodes before linearized reading' (duration: 994.690475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.314672Z","time spent":"994.819418ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:28.309447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.541213Z","time spent":"768.227608ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:28.309508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.228001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-06-15T20:03:28.309474Z","caller":"traceutil/trace.go:171","msg":"trace[1858038649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1288; }","duration":"803.305222ms","start":"2026-06-15T20:03:27.506162Z","end":"2026-06-15T20:03:28.309467Z","steps":["trace[1858038649] 'agreement among raft nodes before linearized reading' (duration: 803.20433ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:28.30953Z","caller":"traceutil/trace.go:171","msg":"trace[688787926] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1288; }","duration":"223.304794ms","start":"2026-06-15T20:03:28.086221Z","end":"2026-06-15T20:03:28.309526Z","steps":["trace[688787926] 'agreement among raft nodes before linearized reading' (duration: 223.263653ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:28.309555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:27.506139Z","time spent":"803.408824ms","remote":"127.0.0.1:45426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:03:28.582273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.511266ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-15T20:03:28.582707Z","caller":"traceutil/trace.go:171","msg":"trace[330814142] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"269.157646ms","start":"2026-06-15T20:03:28.313526Z","end":"2026-06-15T20:03:28.582683Z","steps":["trace[330814142] 'process raft request' (duration: 268.87113ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:28.582801Z","caller":"traceutil/trace.go:171","msg":"trace[1044414951] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"269.270819ms","start":"2026-06-15T20:03:28.313492Z","end":"2026-06-15T20:03:28.582763Z","steps":["trace[1044414951] 'process raft request' (duration: 97.075447ms)","trace[1044414951] 'compare' (duration: 171.275361ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:28.582899Z","caller":"traceutil/trace.go:171","msg":"trace[1417266153] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"269.159856ms","start":"2026-06-15T20:03:28.313708Z","end":"2026-06-15T20:03:28.582868Z","steps":["trace[1417266153] 'process raft request' (duration: 268.781878ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:28.582924Z","caller":"traceutil/trace.go:171","msg":"trace[647787795] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"269.240219ms","start":"2026-06-15T20:03:28.313677Z","end":"2026-06-15T20:03:28.582918Z","steps":["trace[647787795] 'process raft request' (duration: 268.777308ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:28.58298Z","caller":"traceutil/trace.go:171","msg":"trace[637350045] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"269.182597ms","start":"2026-06-15T20:03:28.313793Z","end":"2026-06-15T20:03:28.582975Z","steps":["trace[637350045] 'process raft request' (duration: 268.728257ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.667235Z","caller":"traceutil/trace.go:171","msg":"trace[554545296] linearizableReadLoop","detail":"{readStateIndex:1347; appliedIndex:1346; }","duration":"162.627371ms","start":"2026-06-15T20:03:30.50457Z","end":"2026-06-15T20:03:30.667198Z","steps":["trace[554545296] 'read index received' (duration: 162.509399ms)","trace[554545296] 'applied index is now lower than readState.Index' (duration: 116.512µs)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:30.667278Z","caller":"traceutil/trace.go:171","msg":"trace[967277652] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"353.260876ms","start":"2026-06-15T20:03:30.313975Z","end":"2026-06-15T20:03:30.667236Z","steps":["trace[967277652] 'process raft request' (duration: 353.050371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:30.667509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:30.313949Z","time spent":"353.431229ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:30.667741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.144494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:30.667862Z","caller":"traceutil/trace.go:171","msg":"trace[420183996] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"163.298346ms","start":"2026-06-15T20:03:30.504526Z","end":"2026-06-15T20:03:30.667825Z","steps":["trace[420183996] 'agreement among raft nodes before linearized reading' (duration: 163.109572ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.880082Z","caller":"traceutil/trace.go:171","msg":"trace[1468253815] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"207.178844ms","start":"2026-06-15T20:03:30.672869Z","end":"2026-06-15T20:03:30.880048Z","steps":["trace[1468253815] 'process raft request' (duration: 206.983599ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.908464Z","caller":"traceutil/trace.go:171","msg":"trace[1359363576] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"234.787788ms","start":"2026-06-15T20:03:30.673655Z","end":"2026-06-15T20:03:30.908443Z","steps":["trace[1359363576] 'process raft request' (duration: 234.738927ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.908484Z","caller":"traceutil/trace.go:171","msg":"trace[1854611694] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"235.082974ms","start":"2026-06-15T20:03:30.67338Z","end":"2026-06-15T20:03:30.908463Z","steps":["trace[1854611694] 'process raft request' (duration: 234.89719ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.909024Z","caller":"traceutil/trace.go:171","msg":"trace[1504784838] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"234.4829ms","start":"2026-06-15T20:03:30.674534Z","end":"2026-06-15T20:03:30.909017Z","steps":["trace[1504784838] 'process raft request' (duration: 233.883236ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:30.908999Z","caller":"traceutil/trace.go:171","msg":"trace[1821989734] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"234.019189ms","start":"2026-06-15T20:03:30.674915Z","end":"2026-06-15T20:03:30.908934Z","steps":["trace[1821989734] 'process raft request' (duration: 233.930797ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:31.684699Z","caller":"traceutil/trace.go:171","msg":"trace[1381541323] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"104.241512ms","start":"2026-06-15T20:03:31.580432Z","end":"2026-06-15T20:03:31.684673Z","steps":["trace[1381541323] 'process raft request' (duration: 104.070688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:32.290291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.541512ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-15T20:03:32.290906Z","caller":"traceutil/trace.go:171","msg":"trace[404058324] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"640.812955ms","start":"2026-06-15T20:03:31.650045Z","end":"2026-06-15T20:03:32.290858Z","steps":["trace[404058324] 'process raft request' (duration: 640.327703ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:32.291157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:31.650039Z","time spent":"640.99462ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:32.690007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.89286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-15T20:03:32.69009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.918918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:03:32.690137Z","caller":"traceutil/trace.go:171","msg":"trace[1500921391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"184.091025ms","start":"2026-06-15T20:03:32.506009Z","end":"2026-06-15T20:03:32.6901Z","steps":["trace[1500921391] 'range keys from in-memory index tree' (duration: 183.812398ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:32.69017Z","caller":"traceutil/trace.go:171","msg":"trace[1029877403] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1301; }","duration":"214.058081ms","start":"2026-06-15T20:03:32.476091Z","end":"2026-06-15T20:03:32.690149Z","steps":["trace[1029877403] 'count revisions from in-memory index tree' (duration: 213.836156ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:41.169386Z","caller":"traceutil/trace.go:171","msg":"trace[1095652126] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"590.95483ms","start":"2026-06-15T20:03:40.578396Z","end":"2026-06-15T20:03:41.169351Z","steps":["trace[1095652126] 'process raft request' (duration: 590.717505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.16966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:40.578377Z","time spent":"591.153244ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:41.553743Z","caller":"traceutil/trace.go:171","msg":"trace[925076823] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"599.532307ms","start":"2026-06-15T20:03:40.954189Z","end":"2026-06-15T20:03:41.553721Z","steps":["trace[925076823] 'read index received' (duration: 214.875401ms)","trace[925076823] 'applied index is now lower than readState.Index' (duration: 384.655746ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:41.553855Z","caller":"traceutil/trace.go:171","msg":"trace[762562583] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"726.731776ms","start":"2026-06-15T20:03:40.827111Z","end":"2026-06-15T20:03:41.553843Z","steps":["trace[762562583] 'process raft request' (duration: 688.573771ms)","trace[762562583] 'compare' (duration: 37.839648ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:41.553934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:40.827079Z","time spent":"726.795317ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:41.554117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.214963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-15T20:03:41.554198Z","caller":"traceutil/trace.go:171","msg":"trace[1020063089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"519.351036ms","start":"2026-06-15T20:03:41.034825Z","end":"2026-06-15T20:03:41.554176Z","steps":["trace[1020063089] 'agreement among raft nodes before linearized reading' (duration: 519.144562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.55425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.034803Z","time spent":"519.433589ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:41.55427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.309523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-15T20:03:41.554303Z","caller":"traceutil/trace.go:171","msg":"trace[1339047958] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1381; }","duration":"474.387555ms","start":"2026-06-15T20:03:41.079907Z","end":"2026-06-15T20:03:41.554294Z","steps":["trace[1339047958] 'agreement among raft nodes before linearized reading' (duration: 474.272723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.554329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.07988Z","time spent":"474.442907ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:41.554432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.261164ms","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-15T20:03:41.554452Z","caller":"traceutil/trace.go:171","msg":"trace[64507356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"600.325896ms","start":"2026-06-15T20:03:40.954121Z","end":"2026-06-15T20:03:41.554447Z","steps":["trace[64507356] 'agreement among raft nodes before linearized reading' (duration: 600.260684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.554469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:40.954089Z","time spent":"600.375856ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:03:41.554553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.803981ms","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:530"} {"level":"info","ts":"2026-06-15T20:03:41.554591Z","caller":"traceutil/trace.go:171","msg":"trace[1693049097] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"320.895613ms","start":"2026-06-15T20:03:41.233688Z","end":"2026-06-15T20:03:41.554584Z","steps":["trace[1693049097] 'agreement among raft nodes before linearized reading' (duration: 320.818101ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.554612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.233658Z","time spent":"320.948964ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-15T20:03:41.554658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.258547ms","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-15T20:03:41.554705Z","caller":"traceutil/trace.go:171","msg":"trace[851510219] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1381; }","duration":"482.358219ms","start":"2026-06-15T20:03:41.072333Z","end":"2026-06-15T20:03:41.554691Z","steps":["trace[851510219] 'agreement among raft nodes before linearized reading' (duration: 482.230035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.554742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.072301Z","time spent":"482.431641ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:03:41.554897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.517307ms","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-15T20:03:41.554925Z","caller":"traceutil/trace.go:171","msg":"trace[1099988227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"497.611049ms","start":"2026-06-15T20:03:41.057306Z","end":"2026-06-15T20:03:41.554917Z","steps":["trace[1099988227] 'agreement among raft nodes before linearized reading' (duration: 497.533517ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.55495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.057277Z","time spent":"497.66738ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-15T20:03:41.870022Z","caller":"traceutil/trace.go:171","msg":"trace[12750357] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"311.127699ms","start":"2026-06-15T20:03:41.558851Z","end":"2026-06-15T20:03:41.869978Z","steps":["trace[12750357] 'process raft request' (duration: 245.599166ms)","trace[12750357] 'compare' (duration: 65.158464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:41.870177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.558835Z","time spent":"311.259731ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:41.937192Z","caller":"traceutil/trace.go:171","msg":"trace[1096738074] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"370.457529ms","start":"2026-06-15T20:03:41.566709Z","end":"2026-06-15T20:03:41.937167Z","steps":["trace[1096738074] 'process raft request' (duration: 370.423919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.937373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.566695Z","time spent":"370.609293ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:41.937622Z","caller":"traceutil/trace.go:171","msg":"trace[41226986] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"376.093319ms","start":"2026-06-15T20:03:41.561488Z","end":"2026-06-15T20:03:41.937582Z","steps":["trace[41226986] 'process raft request' (duration: 375.485425ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:41.937672Z","caller":"traceutil/trace.go:171","msg":"trace[1091692330] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"376.102939ms","start":"2026-06-15T20:03:41.561556Z","end":"2026-06-15T20:03:41.937659Z","steps":["trace[1091692330] 'process raft request' (duration: 375.523086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.937718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.561547Z","time spent":"376.14773ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:41.937798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.561479Z","time spent":"376.223492ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:41.937958Z","caller":"traceutil/trace.go:171","msg":"trace[666388213] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"376.15899ms","start":"2026-06-15T20:03:41.561622Z","end":"2026-06-15T20:03:41.937781Z","steps":["trace[666388213] 'process raft request' (duration: 375.486635ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:41.938406Z","caller":"traceutil/trace.go:171","msg":"trace[1719516951] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"376.394506ms","start":"2026-06-15T20:03:41.56133Z","end":"2026-06-15T20:03:41.937725Z","steps":["trace[1719516951] 'process raft request' (duration: 375.475995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:41.938648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.561292Z","time spent":"377.228586ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:41.938628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:41.561611Z","time spent":"376.892959ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:42.850512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995060667,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-15T20:03:43.296452Z","caller":"traceutil/trace.go:171","msg":"trace[1073102088] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"669.417851ms","start":"2026-06-15T20:03:42.627013Z","end":"2026-06-15T20:03:43.296431Z","steps":["trace[1073102088] 'process raft request' (duration: 669.125304ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:43.296602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:42.626987Z","time spent":"669.535104ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-15T20:03:43.29664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.701093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-15T20:03:43.296446Z","caller":"traceutil/trace.go:171","msg":"trace[1990117197] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"946.414577ms","start":"2026-06-15T20:03:42.349928Z","end":"2026-06-15T20:03:43.296343Z","steps":["trace[1990117197] 'read index received' (duration: 943.419219ms)","trace[1990117197] 'applied index is now lower than readState.Index' (duration: 2.988948ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:43.296929Z","caller":"traceutil/trace.go:171","msg":"trace[1160942777] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1388; }","duration":"947.017701ms","start":"2026-06-15T20:03:42.349889Z","end":"2026-06-15T20:03:43.296907Z","steps":["trace[1160942777] 'agreement among raft nodes before linearized reading' (duration: 946.605751ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:43.297158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:42.34987Z","time spent":"947.213575ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:03:43.29717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.890461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:43.29733Z","caller":"traceutil/trace.go:171","msg":"trace[917817480] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1388; }","duration":"791.972713ms","start":"2026-06-15T20:03:42.505226Z","end":"2026-06-15T20:03:43.297198Z","steps":["trace[917817480] 'agreement among raft nodes before linearized reading' (duration: 791.913122ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:43.297372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:42.505199Z","time spent":"792.164868ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:03:43.297454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.763102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-06-15T20:03:43.297562Z","caller":"traceutil/trace.go:171","msg":"trace[846543117] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1388; }","duration":"171.944926ms","start":"2026-06-15T20:03:43.125595Z","end":"2026-06-15T20:03:43.29754Z","steps":["trace[846543117] 'agreement among raft nodes before linearized reading' (duration: 171.586238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:44.376199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995060678,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:44.876455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5605185813995060678,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-15T20:03:45.198255Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.866616208s","expected-duration":"1s"} {"level":"info","ts":"2026-06-15T20:03:45.198647Z","caller":"traceutil/trace.go:171","msg":"trace[1269962438] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"1.891718474s","start":"2026-06-15T20:03:43.306903Z","end":"2026-06-15T20:03:45.198622Z","steps":["trace[1269962438] 'process raft request' (duration: 1.89154626s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.198922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.306869Z","time spent":"1.89197163s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:45.373466Z","caller":"traceutil/trace.go:171","msg":"trace[80794167] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"2.064980319s","start":"2026-06-15T20:03:43.308461Z","end":"2026-06-15T20:03:45.373441Z","steps":["trace[80794167] 'process raft request' (duration: 2.059979765s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.373604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.308443Z","time spent":"2.065083282s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:45.373681Z","caller":"traceutil/trace.go:171","msg":"trace[1652749595] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1444; }","duration":"1.49844419s","start":"2026-06-15T20:03:43.87521Z","end":"2026-06-15T20:03:45.373654Z","steps":["trace[1652749595] 'read index received' (duration: 1.323273581s)","trace[1652749595] 'applied index is now lower than readState.Index' (duration: 175.169199ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:03:45.373984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.498784478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-15T20:03:45.37402Z","caller":"traceutil/trace.go:171","msg":"trace[2123465475] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1391; }","duration":"1.4988773s","start":"2026-06-15T20:03:43.875132Z","end":"2026-06-15T20:03:45.37401Z","steps":["trace[2123465475] 'agreement among raft nodes before linearized reading' (duration: 1.498739127s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.374048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.875102Z","time spent":"1.498939632s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-15T20:03:45.374217Z","caller":"traceutil/trace.go:171","msg":"trace[1164428350] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"1.814137733s","start":"2026-06-15T20:03:43.560067Z","end":"2026-06-15T20:03:45.374205Z","steps":["trace[1164428350] 'process raft request' (duration: 1.813302185s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.374241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430313627s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-15T20:03:45.37427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.560048Z","time spent":"1.814190675s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-15T20:03:45.374282Z","caller":"traceutil/trace.go:171","msg":"trace[36783002] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"1.430406468s","start":"2026-06-15T20:03:43.943864Z","end":"2026-06-15T20:03:45.374271Z","steps":["trace[36783002] 'agreement among raft nodes before linearized reading' (duration: 1.430083821s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.374317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.943843Z","time spent":"1.43046681s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-15T20:03:45.374471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.090357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-15T20:03:45.374543Z","caller":"traceutil/trace.go:171","msg":"trace[1101599934] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1391; }","duration":"456.215261ms","start":"2026-06-15T20:03:44.918307Z","end":"2026-06-15T20:03:45.374522Z","steps":["trace[1101599934] 'agreement among raft nodes before linearized reading' (duration: 456.121828ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.374601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.918284Z","time spent":"456.299092ms","remote":"127.0.0.1:45856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-15T20:03:45.374626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231312518s","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:524"} {"level":"info","ts":"2026-06-15T20:03:45.374668Z","caller":"traceutil/trace.go:171","msg":"trace[2127400643] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1391; }","duration":"1.231410471s","start":"2026-06-15T20:03:44.143245Z","end":"2026-06-15T20:03:45.374656Z","steps":["trace[2127400643] 'agreement among raft nodes before linearized reading' (duration: 1.231219986s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.374702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.143218Z","time spent":"1.231477761s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-15T20:03:45.374892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428712369s","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:530"} {"level":"warn","ts":"2026-06-15T20:03:45.374974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.403596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-06-15T20:03:45.374982Z","caller":"traceutil/trace.go:171","msg":"trace[1508171634] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1391; }","duration":"1.428826321s","start":"2026-06-15T20:03:43.946136Z","end":"2026-06-15T20:03:45.374962Z","steps":["trace[1508171634] 'agreement among raft nodes before linearized reading' (duration: 1.428364281s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:45.375004Z","caller":"traceutil/trace.go:171","msg":"trace[696595074] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1391; }","duration":"778.483857ms","start":"2026-06-15T20:03:44.596511Z","end":"2026-06-15T20:03:45.374995Z","steps":["trace[696595074] 'agreement among raft nodes before linearized reading' (duration: 778.433266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.375002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430789537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-15T20:03:45.375031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.596482Z","time spent":"778.543738ms","remote":"127.0.0.1:45500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-15T20:03:45.375039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.946128Z","time spent":"1.428901463s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-15T20:03:45.375048Z","caller":"traceutil/trace.go:171","msg":"trace[945394511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1391; }","duration":"1.430891349s","start":"2026-06-15T20:03:43.944144Z","end":"2026-06-15T20:03:45.375035Z","steps":["trace[945394511] 'agreement among raft nodes before linearized reading' (duration: 1.430766496s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.375084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.944119Z","time spent":"1.430956521s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:45.375211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431269948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-06-15T20:03:45.375204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426389146s","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-15T20:03:45.375259Z","caller":"traceutil/trace.go:171","msg":"trace[1142372752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1391; }","duration":"1.43138144s","start":"2026-06-15T20:03:43.943864Z","end":"2026-06-15T20:03:45.375245Z","steps":["trace[1142372752] 'agreement among raft nodes before linearized reading' (duration: 1.431234617s)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:45.375286Z","caller":"traceutil/trace.go:171","msg":"trace[1519371192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1391; }","duration":"1.426521288s","start":"2026-06-15T20:03:43.94875Z","end":"2026-06-15T20:03:45.375271Z","steps":["trace[1519371192] 'agreement among raft nodes before linearized reading' (duration: 1.425945505s)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.375295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.943824Z","time spent":"1.431460852s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-15T20:03:45.375316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:43.948736Z","time spent":"1.42657437s","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-15T20:03:45.375179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.763622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:03:45.375385Z","caller":"traceutil/trace.go:171","msg":"trace[1741081196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"832.018249ms","start":"2026-06-15T20:03:44.543345Z","end":"2026-06-15T20:03:45.375363Z","steps":["trace[1741081196] 'agreement among raft nodes before linearized reading' (duration: 831.731482ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.375435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.543326Z","time spent":"832.09486ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-15T20:03:45.374924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.328833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:76921"} {"level":"info","ts":"2026-06-15T20:03:45.37564Z","caller":"traceutil/trace.go:171","msg":"trace[1398861823] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1391; }","duration":"779.09059ms","start":"2026-06-15T20:03:44.596535Z","end":"2026-06-15T20:03:45.375626Z","steps":["trace[1398861823] 'agreement among raft nodes before linearized reading' (duration: 778.295693ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.375685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:03:44.596504Z","time spent":"779.165012ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":76943,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-15T20:03:45.614514Z","caller":"traceutil/trace.go:171","msg":"trace[1240498066] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"234.292653ms","start":"2026-06-15T20:03:45.380195Z","end":"2026-06-15T20:03:45.614487Z","steps":["trace[1240498066] 'process raft request' (duration: 209.992796ms)","trace[1240498066] 'compare' (duration: 24.096702ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:45.614883Z","caller":"traceutil/trace.go:171","msg":"trace[1916565995] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"232.511791ms","start":"2026-06-15T20:03:45.382346Z","end":"2026-06-15T20:03:45.614858Z","steps":["trace[1916565995] 'process raft request' (duration: 232.419889ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:45.614928Z","caller":"traceutil/trace.go:171","msg":"trace[1039576958] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"234.320493ms","start":"2026-06-15T20:03:45.380583Z","end":"2026-06-15T20:03:45.614904Z","steps":["trace[1039576958] 'process raft request' (duration: 234.087158ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:45.615009Z","caller":"traceutil/trace.go:171","msg":"trace[1325404935] linearizableReadLoop","detail":"{readStateIndex:1454; appliedIndex:1449; }","duration":"228.337836ms","start":"2026-06-15T20:03:45.386655Z","end":"2026-06-15T20:03:45.614993Z","steps":["trace[1325404935] 'read index received' (duration: 203.572339ms)","trace[1325404935] 'applied index is now lower than readState.Index' (duration: 24.764167ms)"],"step_count":2} {"level":"info","ts":"2026-06-15T20:03:45.615185Z","caller":"traceutil/trace.go:171","msg":"trace[1464870327] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"232.571262ms","start":"2026-06-15T20:03:45.3826Z","end":"2026-06-15T20:03:45.615171Z","steps":["trace[1464870327] 'process raft request' (duration: 232.213624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:45.61524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.498433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:76921"} {"level":"info","ts":"2026-06-15T20:03:45.615289Z","caller":"traceutil/trace.go:171","msg":"trace[684921189] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1398; }","duration":"233.576995ms","start":"2026-06-15T20:03:45.381696Z","end":"2026-06-15T20:03:45.615273Z","steps":["trace[684921189] 'agreement among raft nodes before linearized reading' (duration: 233.36574ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:45.614918Z","caller":"traceutil/trace.go:171","msg":"trace[77288029] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"234.428105ms","start":"2026-06-15T20:03:45.380466Z","end":"2026-06-15T20:03:45.614895Z","steps":["trace[77288029] 'process raft request' (duration: 233.955414ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:47.755912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.257099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-15T20:03:47.75593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.2784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-15T20:03:47.756001Z","caller":"traceutil/trace.go:171","msg":"trace[841148574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1403; }","duration":"126.409203ms","start":"2026-06-15T20:03:47.629571Z","end":"2026-06-15T20:03:47.75598Z","steps":["trace[841148574] 'range keys from in-memory index tree' (duration: 126.014074ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:03:47.756008Z","caller":"traceutil/trace.go:171","msg":"trace[852389384] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1403; }","duration":"126.416843ms","start":"2026-06-15T20:03:47.629573Z","end":"2026-06-15T20:03:47.75599Z","steps":["trace[852389384] 'range keys from in-memory index tree' (duration: 126.068996ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:47.756132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.438014ms","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-15T20:03:47.756249Z","caller":"traceutil/trace.go:171","msg":"trace[1598095487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"126.583367ms","start":"2026-06-15T20:03:47.62964Z","end":"2026-06-15T20:03:47.756224Z","steps":["trace[1598095487] 'range keys from in-memory index tree' (duration: 126.218679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:03:47.756064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.890051ms","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:531"} {"level":"info","ts":"2026-06-15T20:03:47.756466Z","caller":"traceutil/trace.go:171","msg":"trace[1516983263] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1403; }","duration":"126.313471ms","start":"2026-06-15T20:03:47.630115Z","end":"2026-06-15T20:03:47.756429Z","steps":["trace[1516983263] 'range keys from in-memory index tree' (duration: 125.678107ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:28.26328Z","caller":"traceutil/trace.go:171","msg":"trace[1862427511] transaction","detail":"{read_only:false; response_revision:1592; number_of_response:1; }","duration":"136.367312ms","start":"2026-06-15T20:04:28.12689Z","end":"2026-06-15T20:04:28.263258Z","steps":["trace[1862427511] 'process raft request' (duration: 136.323361ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:04:28.263746Z","caller":"traceutil/trace.go:171","msg":"trace[471534813] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"136.876483ms","start":"2026-06-15T20:04:28.126806Z","end":"2026-06-15T20:04:28.263683Z","steps":["trace[471534813] 'process raft request' (duration: 82.265134ms)","trace[471534813] 'compare' (duration: 54.038766ms)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:04:36.70265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.166474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-15T20:04:36.702783Z","caller":"traceutil/trace.go:171","msg":"trace[257066813] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:1630; }","duration":"121.386789ms","start":"2026-06-15T20:04:36.581368Z","end":"2026-06-15T20:04:36.702755Z","steps":["trace[257066813] 'range keys from in-memory index tree' (duration: 120.894336ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:07:39.596274Z","caller":"traceutil/trace.go:171","msg":"trace[368479636] transaction","detail":"{read_only:false; response_revision:2488; number_of_response:1; }","duration":"141.580485ms","start":"2026-06-15T20:07:39.454655Z","end":"2026-06-15T20:07:39.596236Z","steps":["trace[368479636] 'process raft request' (duration: 141.3219ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:07:46.838982Z","caller":"traceutil/trace.go:171","msg":"trace[1229011869] linearizableReadLoop","detail":"{readStateIndex:2629; appliedIndex:2628; }","duration":"332.825487ms","start":"2026-06-15T20:07:46.506132Z","end":"2026-06-15T20:07:46.838958Z","steps":["trace[1229011869] 'read index received' (duration: 332.586272ms)","trace[1229011869] 'applied index is now lower than readState.Index' (duration: 238.075µs)"],"step_count":2} {"level":"warn","ts":"2026-06-15T20:07:46.839218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.358756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.177\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-15T20:07:46.839206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.040093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-15T20:07:46.839326Z","caller":"traceutil/trace.go:171","msg":"trace[1245564707] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2524; }","duration":"333.223017ms","start":"2026-06-15T20:07:46.506074Z","end":"2026-06-15T20:07:46.839297Z","steps":["trace[1245564707] 'agreement among raft nodes before linearized reading' (duration: 332.993802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:07:46.839384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:07:46.50605Z","time spent":"333.324119ms","remote":"127.0.0.1:45432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-15T20:07:46.839255Z","caller":"traceutil/trace.go:171","msg":"trace[1850001239] range","detail":"{range_begin:/registry/masterleases/199.204.45.177; range_end:; response_count:1; response_revision:2524; }","duration":"247.466279ms","start":"2026-06-15T20:07:46.591777Z","end":"2026-06-15T20:07:46.839243Z","steps":["trace[1850001239] 'agreement among raft nodes before linearized reading' (duration: 247.325656ms)"],"step_count":1} {"level":"info","ts":"2026-06-15T20:07:46.840442Z","caller":"traceutil/trace.go:171","msg":"trace[17025901] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"397.780923ms","start":"2026-06-15T20:07:46.442446Z","end":"2026-06-15T20:07:46.840227Z","steps":["trace[17025901] 'process raft request' (duration: 396.35316ms)"],"step_count":1} {"level":"warn","ts":"2026-06-15T20:07:46.84079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-15T20:07:46.442412Z","time spent":"398.283753ms","remote":"127.0.0.1:45686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"}