{"level":"warn","ts":"2026-06-24T05:28:06.603874Z","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-24T05:28:06.603956Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227: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.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-24T05:28:06.604018Z","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-24T05:28:06.604027Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-24T05:28:06.604052Z","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-24T05:28:06.604684Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-06-24T05:28:06.604786Z","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.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-24T05:28:06.608214Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.193272ms"} {"level":"info","ts":"2026-06-24T05:28:06.613372Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-06-24T05:28:06.613429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-06-24T05:28:06.613445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-06-24T05:28:06.613453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-24T05:28:06.613459Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-06-24T05:28:06.613487Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-06-24T05:28:06.623378Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-24T05:28:06.624597Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-24T05:28:06.62555Z","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-24T05:28:06.628684Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-24T05:28:06.628931Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-24T05:28:06.630131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-06-24T05:28:06.630231Z","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-24T05:28:06.630462Z","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-24T05:28:06.630563Z","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-24T05:28:06.63058Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-06-24T05:28:06.633422Z","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-24T05:28:06.633529Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-24T05:28:06.633606Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-06-24T05:28:06.633689Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-24T05:28:06.633723Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-24T05:28:07.61418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-06-24T05:28:07.614227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-24T05:28:07.614263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-06-24T05:28:07.614287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-06-24T05:28:07.614301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-24T05:28:07.614319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-06-24T05:28:07.614335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-06-24T05:28:07.623304Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T05:28:07.624287Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-06-24T05:28:07.62446Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T05:28:07.624485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-24T05:28:07.624729Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T05:28:07.624805Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T05:28:07.624829Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-24T05:28:07.625226Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-24T05:28:07.62527Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-24T05:28:07.628688Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-06-24T05:28:07.629847Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-24T05:28:21.911818Z","caller":"traceutil/trace.go:171","msg":"trace[917930417] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"124.456419ms","start":"2026-06-24T05:28:21.787294Z","end":"2026-06-24T05:28:21.911751Z","steps":["trace[917930417] 'process raft request' (duration: 124.147099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:27.623221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.508267ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-24T05:28:27.623322Z","caller":"traceutil/trace.go:171","msg":"trace[672143110] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:419; }","duration":"352.703053ms","start":"2026-06-24T05:28:27.270597Z","end":"2026-06-24T05:28:27.6233Z","steps":["trace[672143110] 'range keys from in-memory index tree' (duration: 352.362942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:27.623367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:27.270567Z","time spent":"352.790736ms","remote":"127.0.0.1:58532","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-24T05:28:33.630574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.865368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:33.6307Z","caller":"traceutil/trace.go:171","msg":"trace[792824259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"577.031573ms","start":"2026-06-24T05:28:33.053627Z","end":"2026-06-24T05:28:33.630658Z","steps":["trace[792824259] 'range keys from in-memory index tree' (duration: 576.781395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:33.630746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:33.053613Z","time spent":"577.124346ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:28:33.630748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.813398ms","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-24T05:28:33.630901Z","caller":"traceutil/trace.go:171","msg":"trace[1272807584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"541.949482ms","start":"2026-06-24T05:28:33.088869Z","end":"2026-06-24T05:28:33.630819Z","steps":["trace[1272807584] 'range keys from in-memory index tree' (duration: 541.723375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:33.630946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:33.088852Z","time spent":"542.085607ms","remote":"127.0.0.1:58532","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-24T05:28:33.631052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012364391s","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-24T05:28:33.631193Z","caller":"traceutil/trace.go:171","msg":"trace[1248135912] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:463; }","duration":"1.012516586s","start":"2026-06-24T05:28:32.618659Z","end":"2026-06-24T05:28:33.631175Z","steps":["trace[1248135912] 'range keys from in-memory index tree' (duration: 1.0123475s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:34.179093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528257,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T05:28:34.330146Z","caller":"traceutil/trace.go:171","msg":"trace[1833537630] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"651.605333ms","start":"2026-06-24T05:28:33.678513Z","end":"2026-06-24T05:28:34.330118Z","steps":["trace[1833537630] 'read index received' (duration: 651.342714ms)","trace[1833537630] 'applied index is now lower than readState.Index' (duration: 261.649µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:28:34.330238Z","caller":"traceutil/trace.go:171","msg":"trace[636404283] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"693.974234ms","start":"2026-06-24T05:28:33.636225Z","end":"2026-06-24T05:28:34.3302Z","steps":["trace[636404283] 'process raft request' (duration: 693.706016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:34.330316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.779158ms","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-24T05:28:34.330353Z","caller":"traceutil/trace.go:171","msg":"trace[240608254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:464; }","duration":"651.883302ms","start":"2026-06-24T05:28:33.678459Z","end":"2026-06-24T05:28:34.330342Z","steps":["trace[240608254] 'agreement among raft nodes before linearized reading' (duration: 651.793879ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:34.33035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.765763ms","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-24T05:28:34.330389Z","caller":"traceutil/trace.go:171","msg":"trace[1266272438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"345.865657ms","start":"2026-06-24T05:28:33.984514Z","end":"2026-06-24T05:28:34.33038Z","steps":["trace[1266272438] 'agreement among raft nodes before linearized reading' (duration: 345.762754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:34.330389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:33.678438Z","time spent":"651.943504ms","remote":"127.0.0.1:58532","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-24T05:28:34.330412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:33.984482Z","time spent":"345.925759ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:28:34.3304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.0123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:34.33046Z","caller":"traceutil/trace.go:171","msg":"trace[1723428304] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"272.131383ms","start":"2026-06-24T05:28:34.05831Z","end":"2026-06-24T05:28:34.330441Z","steps":["trace[1723428304] 'agreement among raft nodes before linearized reading' (duration: 272.017579ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:34.330841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:33.636197Z","time spent":"694.110619ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:28:34.508636Z","caller":"traceutil/trace.go:171","msg":"trace[1442992434] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"172.572422ms","start":"2026-06-24T05:28:34.336031Z","end":"2026-06-24T05:28:34.508603Z","steps":["trace[1442992434] 'process raft request' (duration: 172.411927ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:34.558624Z","caller":"traceutil/trace.go:171","msg":"trace[1980230734] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"222.555024ms","start":"2026-06-24T05:28:34.33605Z","end":"2026-06-24T05:28:34.558605Z","steps":["trace[1980230734] 'process raft request' (duration: 222.385499ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:34.558796Z","caller":"traceutil/trace.go:171","msg":"trace[1529647595] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"222.716098ms","start":"2026-06-24T05:28:34.336062Z","end":"2026-06-24T05:28:34.558778Z","steps":["trace[1529647595] 'process raft request' (duration: 222.510262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:46.961197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:28:47.462337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:28:47.963382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:28:47.982201Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.59921299s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T05:28:47.98269Z","caller":"traceutil/trace.go:171","msg":"trace[609220231] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"1.522308026s","start":"2026-06-24T05:28:46.460339Z","end":"2026-06-24T05:28:47.982647Z","steps":["trace[609220231] 'read index received' (duration: 1.521979876s)","trace[609220231] 'applied index is now lower than readState.Index' (duration: 326.82µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:28:47.982774Z","caller":"traceutil/trace.go:171","msg":"trace[1803662306] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.599878082s","start":"2026-06-24T05:28:46.38285Z","end":"2026-06-24T05:28:47.982728Z","steps":["trace[1803662306] 'process raft request' (duration: 1.599578833s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:47.982931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:46.382822Z","time spent":"1.600017046s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:28:47.982938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522575285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:28:47.982974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.422294302s","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-24T05:28:47.983016Z","caller":"traceutil/trace.go:171","msg":"trace[866461937] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:525; }","duration":"1.522680858s","start":"2026-06-24T05:28:46.460298Z","end":"2026-06-24T05:28:47.982979Z","steps":["trace[866461937] 'agreement among raft nodes before linearized reading' (duration: 1.522508202s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:47.983031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338701835s","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-24T05:28:47.98306Z","caller":"traceutil/trace.go:171","msg":"trace[355692333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:525; }","duration":"1.422395695s","start":"2026-06-24T05:28:46.560645Z","end":"2026-06-24T05:28:47.983041Z","steps":["trace[355692333] 'agreement among raft nodes before linearized reading' (duration: 1.422218049s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:47.983087Z","caller":"traceutil/trace.go:171","msg":"trace[1093855907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:525; }","duration":"1.338786378s","start":"2026-06-24T05:28:46.644287Z","end":"2026-06-24T05:28:47.983074Z","steps":["trace[1093855907] 'agreement among raft nodes before linearized reading' (duration: 1.338706105s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:47.983076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:46.460281Z","time spent":"1.522775541s","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-24T05:28:47.983113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:46.644251Z","time spent":"1.33885462s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:28:47.983106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:46.56059Z","time spent":"1.422507378s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T05:28:47.983117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.926339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:47.983156Z","caller":"traceutil/trace.go:171","msg":"trace[108317067] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"922.995461ms","start":"2026-06-24T05:28:47.060148Z","end":"2026-06-24T05:28:47.983143Z","steps":["trace[108317067] 'agreement among raft nodes before linearized reading' (duration: 922.929859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:47.983176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:47.060129Z","time spent":"923.043132ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:28:47.983234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.349738465s","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-24T05:28:47.983355Z","caller":"traceutil/trace.go:171","msg":"trace[2011130271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:525; }","duration":"1.34990546s","start":"2026-06-24T05:28:46.633419Z","end":"2026-06-24T05:28:47.983325Z","steps":["trace[2011130271] 'agreement among raft nodes before linearized reading' (duration: 1.349655392s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:47.983411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:46.6334Z","time spent":"1.349999863s","remote":"127.0.0.1:58532","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-24T05:28:51.03722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:50.561691Z","time spent":"475.52468ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-24T05:28:54.985396Z","caller":"traceutil/trace.go:171","msg":"trace[1921892968] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"463.525881ms","start":"2026-06-24T05:28:54.521842Z","end":"2026-06-24T05:28:54.985367Z","steps":["trace[1921892968] 'process raft request' (duration: 463.001154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:54.98554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:54.521802Z","time spent":"463.665455ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:28:55.200366Z","caller":"traceutil/trace.go:171","msg":"trace[1809079724] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"145.04487ms","start":"2026-06-24T05:28:55.055292Z","end":"2026-06-24T05:28:55.200337Z","steps":["trace[1809079724] 'read index received' (duration: 144.793982ms)","trace[1809079724] 'applied index is now lower than readState.Index' (duration: 249.838µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:28:55.200415Z","caller":"traceutil/trace.go:171","msg":"trace[1244810027] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"161.990237ms","start":"2026-06-24T05:28:55.038392Z","end":"2026-06-24T05:28:55.200382Z","steps":["trace[1244810027] 'process raft request' (duration: 161.7901ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:55.200508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.200386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:55.200544Z","caller":"traceutil/trace.go:171","msg":"trace[631263422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"145.288659ms","start":"2026-06-24T05:28:55.055244Z","end":"2026-06-24T05:28:55.200533Z","steps":["trace[631263422] 'agreement among raft nodes before linearized reading' (duration: 145.195446ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:56.112988Z","caller":"traceutil/trace.go:171","msg":"trace[1878685482] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"513.503611ms","start":"2026-06-24T05:28:55.599461Z","end":"2026-06-24T05:28:56.112964Z","steps":["trace[1878685482] 'process raft request' (duration: 512.999645ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:56.113135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:55.599437Z","time spent":"513.619575ms","remote":"127.0.0.1:58362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:28:56.276868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.631073ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T05:28:56.276999Z","caller":"traceutil/trace.go:171","msg":"trace[1566441943] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:663; }","duration":"220.922502ms","start":"2026-06-24T05:28:56.056028Z","end":"2026-06-24T05:28:56.27695Z","steps":["trace[1566441943] 'read index received' (duration: 56.332273ms)","trace[1566441943] 'applied index is now lower than readState.Index' (duration: 164.588599ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:28:56.277206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.163129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:56.277266Z","caller":"traceutil/trace.go:171","msg":"trace[1475880286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"221.228481ms","start":"2026-06-24T05:28:56.056022Z","end":"2026-06-24T05:28:56.27725Z","steps":["trace[1475880286] 'agreement among raft nodes before linearized reading' (duration: 221.074937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:56.777094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:28:57.27773Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006528589,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:28:57.62792Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.44055589s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T05:28:57.628301Z","caller":"traceutil/trace.go:171","msg":"trace[440532490] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"1.351222823s","start":"2026-06-24T05:28:56.277053Z","end":"2026-06-24T05:28:57.628276Z","steps":["trace[440532490] 'read index received' (duration: 1.351004856s)","trace[440532490] 'applied index is now lower than readState.Index' (duration: 216.957µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:28:57.628455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.53028862s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T05:28:57.628487Z","caller":"traceutil/trace.go:171","msg":"trace[728955369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.530368922s","start":"2026-06-24T05:28:56.098108Z","end":"2026-06-24T05:28:57.628477Z","steps":["trace[728955369] 'agreement among raft nodes before linearized reading' (duration: 1.530254909s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:57.62855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51417261s","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-24T05:28:57.628611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530527907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-24T05:28:57.628633Z","caller":"traceutil/trace.go:171","msg":"trace[1380418355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.514311325s","start":"2026-06-24T05:28:56.114302Z","end":"2026-06-24T05:28:57.628613Z","steps":["trace[1380418355] 'agreement among raft nodes before linearized reading' (duration: 1.514019245s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:57.628612Z","caller":"traceutil/trace.go:171","msg":"trace[1072119236] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.441321574s","start":"2026-06-24T05:28:56.187243Z","end":"2026-06-24T05:28:57.628565Z","steps":["trace[1072119236] 'process raft request' (duration: 1.44087316s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:57.628606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35017507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:57.628666Z","caller":"traceutil/trace.go:171","msg":"trace[2110880194] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:640; }","duration":"1.53062027s","start":"2026-06-24T05:28:56.098018Z","end":"2026-06-24T05:28:57.628638Z","steps":["trace[2110880194] 'agreement among raft nodes before linearized reading' (duration: 1.530539958s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:57.628678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.114285Z","time spent":"1.514385026s","remote":"127.0.0.1:58532","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-24T05:28:57.628701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.098002Z","time spent":"1.530693523s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-24T05:28:57.628759Z","caller":"traceutil/trace.go:171","msg":"trace[935592713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.350324094s","start":"2026-06-24T05:28:56.278367Z","end":"2026-06-24T05:28:57.628692Z","steps":["trace[935592713] 'agreement among raft nodes before linearized reading' (duration: 1.350146248s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:57.628741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.187217Z","time spent":"1.441463309s","remote":"127.0.0.1:58512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T05:28:57.62883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.278346Z","time spent":"1.350473429s","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:28:57.628837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.098087Z","time spent":"1.530425454s","remote":"127.0.0.1:58532","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-24T05:28:57.629242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.544332294s","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-24T05:28:57.62932Z","caller":"traceutil/trace.go:171","msg":"trace[1182993895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.544454248s","start":"2026-06-24T05:28:56.084845Z","end":"2026-06-24T05:28:57.629299Z","steps":["trace[1182993895] 'agreement among raft nodes before linearized reading' (duration: 1.54355614s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:57.629368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:56.084822Z","time spent":"1.54453619s","remote":"127.0.0.1:58532","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-24T05:28:58.702723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.008853349s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:58.702817Z","caller":"traceutil/trace.go:171","msg":"trace[429280660] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:644; }","duration":"1.009008664s","start":"2026-06-24T05:28:57.693785Z","end":"2026-06-24T05:28:58.702794Z","steps":["trace[429280660] 'range keys from in-memory index tree' (duration: 1.008771246s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:58.702864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:57.693768Z","time spent":"1.009085466s","remote":"127.0.0.1:58406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-06-24T05:28:58.70286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016588943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:58.702923Z","caller":"traceutil/trace.go:171","msg":"trace[1948744522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"1.016692037s","start":"2026-06-24T05:28:57.686215Z","end":"2026-06-24T05:28:58.702907Z","steps":["trace[1948744522] 'range keys from in-memory index tree' (duration: 1.016536272s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:28:58.702952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:28:57.686203Z","time spent":"1.016742529s","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:28:58.860066Z","caller":"traceutil/trace.go:171","msg":"trace[1752402950] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"154.596042ms","start":"2026-06-24T05:28:58.705445Z","end":"2026-06-24T05:28:58.860041Z","steps":["trace[1752402950] 'process raft request' (duration: 154.455108ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:58.860308Z","caller":"traceutil/trace.go:171","msg":"trace[891073652] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:671; }","duration":"149.539343ms","start":"2026-06-24T05:28:58.710734Z","end":"2026-06-24T05:28:58.860273Z","steps":["trace[891073652] 'read index received' (duration: 149.527222ms)","trace[891073652] 'applied index is now lower than readState.Index' (duration: 9.751µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:28:58.860535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.78272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:28:58.860593Z","caller":"traceutil/trace.go:171","msg":"trace[1853678527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"149.905904ms","start":"2026-06-24T05:28:58.710672Z","end":"2026-06-24T05:28:58.860578Z","steps":["trace[1853678527] 'agreement among raft nodes before linearized reading' (duration: 149.742809ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:28:59.637063Z","caller":"traceutil/trace.go:171","msg":"trace[970881266] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"104.88943ms","start":"2026-06-24T05:28:59.532147Z","end":"2026-06-24T05:28:59.637037Z","steps":["trace[970881266] 'process raft request' (duration: 102.290858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:01.046838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.647649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T05:29:01.046888Z","caller":"traceutil/trace.go:171","msg":"trace[1271880945] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:681; }","duration":"485.738882ms","start":"2026-06-24T05:29:00.561139Z","end":"2026-06-24T05:29:01.046878Z","steps":["trace[1271880945] 'range keys from in-memory index tree' (duration: 485.35729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:01.04691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:00.561119Z","time spent":"485.786144ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-06-24T05:29:01.047288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.14507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:29:01.047325Z","caller":"traceutil/trace.go:171","msg":"trace[1824231156] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"281.290202ms","start":"2026-06-24T05:29:00.766027Z","end":"2026-06-24T05:29:01.047317Z","steps":["trace[1824231156] 'read index received' (duration: 131.976777ms)","trace[1824231156] 'applied index is now lower than readState.Index' (duration: 149.312675ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:29:01.047382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.356314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:29:01.047399Z","caller":"traceutil/trace.go:171","msg":"trace[1789922637] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:682; }","duration":"281.421627ms","start":"2026-06-24T05:29:00.765972Z","end":"2026-06-24T05:29:01.047394Z","steps":["trace[1789922637] 'agreement among raft nodes before linearized reading' (duration: 281.364675ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:01.047475Z","caller":"traceutil/trace.go:171","msg":"trace[1072717170] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"480.450795ms","start":"2026-06-24T05:29:00.567002Z","end":"2026-06-24T05:29:01.047453Z","steps":["trace[1072717170] 'process raft request' (duration: 331.114099ms)","trace[1072717170] 'compare' (duration: 149.086269ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:29:01.047602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:00.566986Z","time spent":"480.551408ms","remote":"127.0.0.1:58326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-24T05:29:03.756292Z","caller":"traceutil/trace.go:171","msg":"trace[1291700200] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"154.81752ms","start":"2026-06-24T05:29:03.601447Z","end":"2026-06-24T05:29:03.756265Z","steps":["trace[1291700200] 'process raft request' (duration: 154.662915ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:11.112161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.61516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:29:11.112283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.7481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T05:29:11.112325Z","caller":"traceutil/trace.go:171","msg":"trace[482588872] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:787; }","duration":"479.824517ms","start":"2026-06-24T05:29:10.632476Z","end":"2026-06-24T05:29:11.1123Z","steps":["trace[482588872] 'range keys from in-memory index tree' (duration: 479.527557ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:11.112386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:10.632455Z","time spent":"479.916369ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:29:11.112855Z","caller":"traceutil/trace.go:171","msg":"trace[737766605] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:787; }","duration":"550.851194ms","start":"2026-06-24T05:29:10.561483Z","end":"2026-06-24T05:29:11.112334Z","steps":["trace[737766605] 'range keys from in-memory index tree' (duration: 550.610987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:11.113058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:10.561468Z","time spent":"551.489674ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"info","ts":"2026-06-24T05:29:13.81814Z","caller":"traceutil/trace.go:171","msg":"trace[754421009] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"109.272393ms","start":"2026-06-24T05:29:13.708843Z","end":"2026-06-24T05:29:13.818115Z","steps":["trace[754421009] 'process raft request' (duration: 108.953133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.344922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.85551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T05:29:15.34502Z","caller":"traceutil/trace.go:171","msg":"trace[1938312501] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:804; }","duration":"999.991375ms","start":"2026-06-24T05:29:14.345006Z","end":"2026-06-24T05:29:15.344997Z","steps":["trace[1938312501] 'count revisions from in-memory index tree' (duration: 999.782218ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:14.344989Z","time spent":"1.000069637s","remote":"127.0.0.1:58742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:29:15.345163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.089894074s","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":"warn","ts":"2026-06-24T05:29:15.345408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464985903s","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-24T05:29:15.345504Z","caller":"traceutil/trace.go:171","msg":"trace[1252425784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.465097236s","start":"2026-06-24T05:29:13.880384Z","end":"2026-06-24T05:29:15.345481Z","steps":["trace[1252425784] 'range keys from in-memory index tree' (duration: 1.464849309s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:13.880364Z","time spent":"1.465181019s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:29:15.345614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.469191096s","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-24T05:29:15.345635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.466120159s","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-24T05:29:15.345676Z","caller":"traceutil/trace.go:171","msg":"trace[319270023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"1.46929626s","start":"2026-06-24T05:29:13.876365Z","end":"2026-06-24T05:29:15.345661Z","steps":["trace[319270023] 'range keys from in-memory index tree' (duration: 1.469045841s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45828978s","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-24T05:29:15.34569Z","caller":"traceutil/trace.go:171","msg":"trace[1290087859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:804; }","duration":"1.466215911s","start":"2026-06-24T05:29:13.879459Z","end":"2026-06-24T05:29:15.345675Z","steps":["trace[1290087859] 'range keys from in-memory index tree' (duration: 1.465975834s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:13.876348Z","time spent":"1.469350951s","remote":"127.0.0.1:58532","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-24T05:29:15.345731Z","caller":"traceutil/trace.go:171","msg":"trace[1095012869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.458365063s","start":"2026-06-24T05:29:13.887357Z","end":"2026-06-24T05:29:15.345722Z","steps":["trace[1095012869] 'range keys from in-memory index tree' (duration: 1.458171227s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:13.87944Z","time spent":"1.466279554s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:29:15.345748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:13.887342Z","time spent":"1.458401995s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T05:29:15.345394Z","caller":"traceutil/trace.go:171","msg":"trace[2048836548] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:804; }","duration":"1.090220984s","start":"2026-06-24T05:29:14.255123Z","end":"2026-06-24T05:29:15.345344Z","steps":["trace[2048836548] 'range keys from in-memory index tree' (duration: 1.089483181s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.345939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:14.255106Z","time spent":"1.090826133s","remote":"127.0.0.1:58532","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-24T05:29:15.394904Z","caller":"traceutil/trace.go:171","msg":"trace[194729936] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"338.52551ms","start":"2026-06-24T05:29:15.056357Z","end":"2026-06-24T05:29:15.394883Z","steps":["trace[194729936] 'read index received' (duration: 338.497939ms)","trace[194729936] 'applied index is now lower than readState.Index' (duration: 26.321µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:29:15.395138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.672664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:29:15.395221Z","caller":"traceutil/trace.go:171","msg":"trace[1950433538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"338.85801ms","start":"2026-06-24T05:29:15.056342Z","end":"2026-06-24T05:29:15.3952Z","steps":["trace[1950433538] 'agreement among raft nodes before linearized reading' (duration: 338.627553ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.395272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:15.056289Z","time spent":"338.970354ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:29:15.395744Z","caller":"traceutil/trace.go:171","msg":"trace[127492656] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"949.194845ms","start":"2026-06-24T05:29:14.446526Z","end":"2026-06-24T05:29:15.395721Z","steps":["trace[127492656] 'process raft request' (duration: 948.233364ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.395918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:14.446495Z","time spent":"949.339019ms","remote":"127.0.0.1:58512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:29:15.547623Z","caller":"traceutil/trace.go:171","msg":"trace[1001692831] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"198.817812ms","start":"2026-06-24T05:29:15.348784Z","end":"2026-06-24T05:29:15.547602Z","steps":["trace[1001692831] 'process raft request' (duration: 198.668797ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:15.548023Z","caller":"traceutil/trace.go:171","msg":"trace[783046706] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"198.280995ms","start":"2026-06-24T05:29:15.349699Z","end":"2026-06-24T05:29:15.54798Z","steps":["trace[783046706] 'process raft request' (duration: 197.869412ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:15.548391Z","caller":"traceutil/trace.go:171","msg":"trace[2091576182] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"198.162341ms","start":"2026-06-24T05:29:15.350216Z","end":"2026-06-24T05:29:15.548378Z","steps":["trace[2091576182] 'process raft request' (duration: 197.655675ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:15.548483Z","caller":"traceutil/trace.go:171","msg":"trace[1991785606] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"198.12701ms","start":"2026-06-24T05:29:15.350347Z","end":"2026-06-24T05:29:15.548474Z","steps":["trace[1991785606] 'process raft request' (duration: 197.964755ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:15.548473Z","caller":"traceutil/trace.go:171","msg":"trace[623546460] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:837; }","duration":"151.486262ms","start":"2026-06-24T05:29:15.396966Z","end":"2026-06-24T05:29:15.548453Z","steps":["trace[623546460] 'read index received' (duration: 150.409068ms)","trace[623546460] 'applied index is now lower than readState.Index' (duration: 1.076104ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:29:15.548509Z","caller":"traceutil/trace.go:171","msg":"trace[228073458] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"197.987896ms","start":"2026-06-24T05:29:15.3505Z","end":"2026-06-24T05:29:15.548488Z","steps":["trace[228073458] 'process raft request' (duration: 197.879672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:15.548594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.604645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:29:15.548653Z","caller":"traceutil/trace.go:171","msg":"trace[53229852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"151.706389ms","start":"2026-06-24T05:29:15.396932Z","end":"2026-06-24T05:29:15.548638Z","steps":["trace[53229852] 'agreement among raft nodes before linearized reading' (duration: 151.588695ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:43.984894Z","caller":"traceutil/trace.go:171","msg":"trace[1717514848] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"218.805275ms","start":"2026-06-24T05:29:43.766067Z","end":"2026-06-24T05:29:43.984873Z","steps":["trace[1717514848] 'read index received' (duration: 218.775074ms)","trace[1717514848] 'applied index is now lower than readState.Index' (duration: 29.341µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:29:43.985016Z","caller":"traceutil/trace.go:171","msg":"trace[487730673] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"282.37756ms","start":"2026-06-24T05:29:43.702586Z","end":"2026-06-24T05:29:43.984964Z","steps":["trace[487730673] 'process raft request' (duration: 282.142063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:43.985105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.021532ms","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-24T05:29:43.985151Z","caller":"traceutil/trace.go:171","msg":"trace[1136642005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:992; }","duration":"219.131936ms","start":"2026-06-24T05:29:43.766007Z","end":"2026-06-24T05:29:43.985139Z","steps":["trace[1136642005] 'agreement among raft nodes before linearized reading' (duration: 218.9511ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:44.018Z","caller":"traceutil/trace.go:171","msg":"trace[2110161815] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"213.860628ms","start":"2026-06-24T05:29:43.804081Z","end":"2026-06-24T05:29:44.017941Z","steps":["trace[2110161815] 'process raft request' (duration: 213.780896ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:44.018081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.953453ms","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-24T05:29:44.018129Z","caller":"traceutil/trace.go:171","msg":"trace[1096277179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:994; }","duration":"174.059897ms","start":"2026-06-24T05:29:43.844058Z","end":"2026-06-24T05:29:44.018118Z","steps":["trace[1096277179] 'agreement among raft nodes before linearized reading' (duration: 173.930413ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:44.018199Z","caller":"traceutil/trace.go:171","msg":"trace[928798654] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"222.634056ms","start":"2026-06-24T05:29:43.795551Z","end":"2026-06-24T05:29:44.018185Z","steps":["trace[928798654] 'process raft request' (duration: 222.219843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:44.018267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.465105ms","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-24T05:29:44.018347Z","caller":"traceutil/trace.go:171","msg":"trace[1477010521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"189.585009ms","start":"2026-06-24T05:29:43.828735Z","end":"2026-06-24T05:29:44.01832Z","steps":["trace[1477010521] 'agreement among raft nodes before linearized reading' (duration: 189.310911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:44.018491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.334261ms","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-24T05:29:44.01855Z","caller":"traceutil/trace.go:171","msg":"trace[1785700018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:994; }","duration":"224.583048ms","start":"2026-06-24T05:29:43.79393Z","end":"2026-06-24T05:29:44.018513Z","steps":["trace[1785700018] 'agreement among raft nodes before linearized reading' (duration: 223.964439ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:44.127902Z","caller":"traceutil/trace.go:171","msg":"trace[2133928798] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"105.037519ms","start":"2026-06-24T05:29:44.022828Z","end":"2026-06-24T05:29:44.127865Z","steps":["trace[2133928798] 'process raft request' (duration: 104.781031ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:44.128491Z","caller":"traceutil/trace.go:171","msg":"trace[243843767] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"105.462893ms","start":"2026-06-24T05:29:44.022977Z","end":"2026-06-24T05:29:44.12844Z","steps":["trace[243843767] 'process raft request' (duration: 104.844053ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:50.551661Z","caller":"traceutil/trace.go:171","msg":"trace[1625770904] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"540.76611ms","start":"2026-06-24T05:29:50.010875Z","end":"2026-06-24T05:29:50.551641Z","steps":["trace[1625770904] 'process raft request' (duration: 540.634556ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:50.551807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.010859Z","time spent":"540.877703ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:29:51.568863Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017369986s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-24T05:29:52.056443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000140524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-24T05:29:52.056528Z","caller":"traceutil/trace.go:171","msg":"trace[500863580] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000268239s","start":"2026-06-24T05:29:50.056239Z","end":"2026-06-24T05:29:52.056507Z","steps":["trace[500863580] 'agreement among raft nodes before linearized reading' (duration: 2.000164655s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:52.056578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.056225Z","time spent":"2.000342551s","remote":"127.0.0.1:58250","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-24T05:29:53.251811Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.682768047s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-24T05:29:53.310434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.775746ms","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-24T05:29:53.310731Z","caller":"traceutil/trace.go:171","msg":"trace[1378599664] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1016; }","duration":"692.083296ms","start":"2026-06-24T05:29:52.618628Z","end":"2026-06-24T05:29:53.310711Z","steps":["trace[1378599664] 'range keys from in-memory index tree' (duration: 691.759456ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.311161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742007234s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:29:53.311374Z","caller":"traceutil/trace.go:171","msg":"trace[1970852416] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"2.509200281s","start":"2026-06-24T05:29:50.802151Z","end":"2026-06-24T05:29:53.311352Z","steps":["trace[1970852416] 'process raft request' (duration: 2.509127359s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.311476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.802132Z","time spent":"2.509299854s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:29:53.311932Z","caller":"traceutil/trace.go:171","msg":"trace[1858610187] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"3.274172316s","start":"2026-06-24T05:29:50.037735Z","end":"2026-06-24T05:29:53.311907Z","steps":["trace[1858610187] 'process raft request' (duration: 1.531305935s)","trace[1858610187] 'compare' (duration: 1.74185641s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:29:53.312038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.037708Z","time spent":"3.27427844s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:29:53.31265Z","caller":"traceutil/trace.go:171","msg":"trace[59367545] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1056; }","duration":"3.256357241s","start":"2026-06-24T05:29:50.056273Z","end":"2026-06-24T05:29:53.31263Z","steps":["trace[59367545] 'read index received' (duration: 495.238007ms)","trace[59367545] 'applied index is now lower than readState.Index' (duration: 2.761117744s)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:29:53.312793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255587977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:29:53.31283Z","caller":"traceutil/trace.go:171","msg":"trace[1298675199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"1.255627948s","start":"2026-06-24T05:29:52.057191Z","end":"2026-06-24T05:29:53.312819Z","steps":["trace[1298675199] 'agreement among raft nodes before linearized reading' (duration: 1.255564616s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.312857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:52.057159Z","time spent":"1.25569384s","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:29:53.312867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.16290063s","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-24T05:29:53.312895Z","caller":"traceutil/trace.go:171","msg":"trace[1193486021] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"3.16295021s","start":"2026-06-24T05:29:50.149936Z","end":"2026-06-24T05:29:53.312886Z","steps":["trace[1193486021] 'agreement among raft nodes before linearized reading' (duration: 3.162835067s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.312922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.149927Z","time spent":"3.162989152s","remote":"127.0.0.1:58532","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-24T05:29:53.312928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.517579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-24T05:29:53.312997Z","caller":"traceutil/trace.go:171","msg":"trace[1064272025] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"757.622954ms","start":"2026-06-24T05:29:52.55536Z","end":"2026-06-24T05:29:53.312982Z","steps":["trace[1064272025] 'agreement among raft nodes before linearized reading' (duration: 757.442518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.313015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.410827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T05:29:53.313037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:52.555328Z","time spent":"757.701766ms","remote":"127.0.0.1:58532","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-24T05:29:53.313096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.749887859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-24T05:29:53.313125Z","caller":"traceutil/trace.go:171","msg":"trace[1180380910] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1018; }","duration":"2.74994106s","start":"2026-06-24T05:29:50.563177Z","end":"2026-06-24T05:29:53.313118Z","steps":["trace[1180380910] 'agreement among raft nodes before linearized reading' (duration: 2.749868218s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:53.313042Z","caller":"traceutil/trace.go:171","msg":"trace[213997003] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1018; }","duration":"858.466009ms","start":"2026-06-24T05:29:52.454567Z","end":"2026-06-24T05:29:53.313033Z","steps":["trace[213997003] 'agreement among raft nodes before linearized reading' (duration: 858.423358ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.31315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.563162Z","time spent":"2.749982822s","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-06-24T05:29:53.313174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:52.454551Z","time spent":"858.615334ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:29:53.31339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.177264975s","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-24T05:29:53.313387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.165549203s","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-24T05:29:53.313422Z","caller":"traceutil/trace.go:171","msg":"trace[2078985369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"3.177350707s","start":"2026-06-24T05:29:50.136064Z","end":"2026-06-24T05:29:53.313415Z","steps":["trace[2078985369] 'agreement among raft nodes before linearized reading' (duration: 3.177272345s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:29:53.313431Z","caller":"traceutil/trace.go:171","msg":"trace[1174064475] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"3.165613785s","start":"2026-06-24T05:29:50.147807Z","end":"2026-06-24T05:29:53.313421Z","steps":["trace[1174064475] 'agreement among raft nodes before linearized reading' (duration: 3.165509212s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.313443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.136048Z","time spent":"3.177389459s","remote":"127.0.0.1:58532","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-24T05:29:53.31347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.147773Z","time spent":"3.165690408s","remote":"127.0.0.1:58532","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-24T05:29:53.313715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.257338303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T05:29:53.313749Z","caller":"traceutil/trace.go:171","msg":"trace[1246983849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"3.257390294s","start":"2026-06-24T05:29:50.05635Z","end":"2026-06-24T05:29:53.313741Z","steps":["trace[1246983849] 'agreement among raft nodes before linearized reading' (duration: 3.256388482s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:53.313804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:29:50.056343Z","time spent":"3.257455516s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-24T05:29:55.440119Z","caller":"traceutil/trace.go:171","msg":"trace[1035046753] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"121.386157ms","start":"2026-06-24T05:29:55.318708Z","end":"2026-06-24T05:29:55.440094Z","steps":["trace[1035046753] 'process raft request' (duration: 121.173351ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:29:56.084308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.482232ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T05:30:01.73677Z","caller":"traceutil/trace.go:171","msg":"trace[615756335] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"105.930678ms","start":"2026-06-24T05:30:01.630808Z","end":"2026-06-24T05:30:01.736739Z","steps":["trace[615756335] 'process raft request' (duration: 40.832755ms)","trace[615756335] 'compare' (duration: 64.961709ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:01.812125Z","caller":"traceutil/trace.go:171","msg":"trace[286312982] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"180.266514ms","start":"2026-06-24T05:30:01.631818Z","end":"2026-06-24T05:30:01.812085Z","steps":["trace[286312982] 'process raft request' (duration: 180.098599ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:01.812329Z","caller":"traceutil/trace.go:171","msg":"trace[1862737134] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"180.192511ms","start":"2026-06-24T05:30:01.632106Z","end":"2026-06-24T05:30:01.812299Z","steps":["trace[1862737134] 'process raft request' (duration: 179.936223ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:01.812657Z","caller":"traceutil/trace.go:171","msg":"trace[1030038479] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"179.704856ms","start":"2026-06-24T05:30:01.632936Z","end":"2026-06-24T05:30:01.812641Z","steps":["trace[1030038479] 'process raft request' (duration: 179.332564ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:07.910519Z","caller":"traceutil/trace.go:171","msg":"trace[1245056712] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"121.012735ms","start":"2026-06-24T05:30:07.789471Z","end":"2026-06-24T05:30:07.910484Z","steps":["trace[1245056712] 'process raft request' (duration: 120.718136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:14.199833Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006529593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:30:14.70065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006529593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:30:15.152418Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.62450277s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T05:30:15.152679Z","caller":"traceutil/trace.go:171","msg":"trace[453333931] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"1.624836841s","start":"2026-06-24T05:30:13.527822Z","end":"2026-06-24T05:30:15.152659Z","steps":["trace[453333931] 'process raft request' (duration: 1.624732807s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.152794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.527778Z","time spent":"1.624955084s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:15.369968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.856054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:30:15.37019Z","caller":"traceutil/trace.go:171","msg":"trace[687038741] linearizableReadLoop","detail":"{readStateIndex:1132; appliedIndex:1130; }","duration":"1.671276672s","start":"2026-06-24T05:30:13.698897Z","end":"2026-06-24T05:30:15.370173Z","steps":["trace[687038741] 'read index received' (duration: 1.453711736s)","trace[687038741] 'applied index is now lower than readState.Index' (duration: 217.563456ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:15.3702Z","caller":"traceutil/trace.go:171","msg":"trace[1361850178] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"1.821403031s","start":"2026-06-24T05:30:13.548766Z","end":"2026-06-24T05:30:15.370169Z","steps":["trace[1361850178] 'process raft request' (duration: 1.645218046s)","trace[1361850178] 'compare' (duration: 175.593366ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:15.370325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.548736Z","time spent":"1.821516334s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:30:15.370438Z","caller":"traceutil/trace.go:171","msg":"trace[621218718] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"334.381289ms","start":"2026-06-24T05:30:15.036049Z","end":"2026-06-24T05:30:15.370431Z","steps":["trace[621218718] 'process raft request' (duration: 334.045478ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.37048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:15.036023Z","time spent":"334.43812ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:15.370466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671562161s","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-24T05:30:15.370516Z","caller":"traceutil/trace.go:171","msg":"trace[1175946303] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.671662404s","start":"2026-06-24T05:30:13.698842Z","end":"2026-06-24T05:30:15.370505Z","steps":["trace[1175946303] 'agreement among raft nodes before linearized reading' (duration: 1.671495989s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.370547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.698814Z","time spent":"1.671729246s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:15.370597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474513446s","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-24T05:30:15.370625Z","caller":"traceutil/trace.go:171","msg":"trace[830614752] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1087; }","duration":"1.474590428s","start":"2026-06-24T05:30:13.896028Z","end":"2026-06-24T05:30:15.370619Z","steps":["trace[830614752] 'agreement among raft nodes before linearized reading' (duration: 1.474529256s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.37065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.896008Z","time spent":"1.47463666s","remote":"127.0.0.1:58646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:30:15.370756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.404805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-24T05:30:15.370768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427801655s","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-24T05:30:15.370799Z","caller":"traceutil/trace.go:171","msg":"trace[412447139] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1087; }","duration":"650.496588ms","start":"2026-06-24T05:30:14.720293Z","end":"2026-06-24T05:30:15.370789Z","steps":["trace[412447139] 'agreement among raft nodes before linearized reading' (duration: 650.425056ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.370809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430559553s","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-24T05:30:15.370807Z","caller":"traceutil/trace.go:171","msg":"trace[1673208294] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1087; }","duration":"1.427854526s","start":"2026-06-24T05:30:13.942942Z","end":"2026-06-24T05:30:15.370797Z","steps":["trace[1673208294] 'agreement among raft nodes before linearized reading' (duration: 1.427733843s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:15.370831Z","caller":"traceutil/trace.go:171","msg":"trace[814215488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1087; }","duration":"1.430616884s","start":"2026-06-24T05:30:13.940209Z","end":"2026-06-24T05:30:15.370826Z","steps":["trace[814215488] 'agreement among raft nodes before linearized reading' (duration: 1.430576633s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.370834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:14.720273Z","time spent":"650.553139ms","remote":"127.0.0.1:58782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:30:15.370845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.94019Z","time spent":"1.430652425s","remote":"127.0.0.1:58532","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-24T05:30:15.370844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.942936Z","time spent":"1.427899668s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:30:15.370858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436673536s","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-24T05:30:15.37091Z","caller":"traceutil/trace.go:171","msg":"trace[1872005218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.436752188s","start":"2026-06-24T05:30:13.934135Z","end":"2026-06-24T05:30:15.370887Z","steps":["trace[1872005218] 'agreement among raft nodes before linearized reading' (duration: 1.436635845s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.370775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430660156s","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-24T05:30:15.370943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.934106Z","time spent":"1.436829381s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T05:30:15.370955Z","caller":"traceutil/trace.go:171","msg":"trace[2833113] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1087; }","duration":"1.430872182s","start":"2026-06-24T05:30:13.940078Z","end":"2026-06-24T05:30:15.37095Z","steps":["trace[2833113] 'agreement among raft nodes before linearized reading' (duration: 1.430663786s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.370972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:13.940064Z","time spent":"1.430902913s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:15.370929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314153883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:30:15.371091Z","caller":"traceutil/trace.go:171","msg":"trace[1447743776] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1087; }","duration":"1.314437222s","start":"2026-06-24T05:30:14.056635Z","end":"2026-06-24T05:30:15.371072Z","steps":["trace[1447743776] 'agreement among raft nodes before linearized reading' (duration: 1.314109362s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.372258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:14.056616Z","time spent":"1.315625979s","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:30:15.636053Z","caller":"traceutil/trace.go:171","msg":"trace[1819171492] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1134; }","duration":"245.173889ms","start":"2026-06-24T05:30:15.390856Z","end":"2026-06-24T05:30:15.63603Z","steps":["trace[1819171492] 'read index received' (duration: 30.92643ms)","trace[1819171492] 'applied index is now lower than readState.Index' (duration: 214.246499ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:15.636056Z","caller":"traceutil/trace.go:171","msg":"trace[304195446] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"257.754388ms","start":"2026-06-24T05:30:15.378276Z","end":"2026-06-24T05:30:15.63603Z","steps":["trace[304195446] 'process raft request' (duration: 228.830663ms)","trace[304195446] 'compare' (duration: 28.74866ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:15.636113Z","caller":"traceutil/trace.go:171","msg":"trace[1029220063] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"248.789115ms","start":"2026-06-24T05:30:15.387315Z","end":"2026-06-24T05:30:15.636104Z","steps":["trace[1029220063] 'process raft request' (duration: 248.689552ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:15.636122Z","caller":"traceutil/trace.go:171","msg":"trace[1604878606] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"253.958608ms","start":"2026-06-24T05:30:15.382132Z","end":"2026-06-24T05:30:15.636091Z","steps":["trace[1604878606] 'process raft request' (duration: 253.849785ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:15.636216Z","caller":"traceutil/trace.go:171","msg":"trace[858763178] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"254.718953ms","start":"2026-06-24T05:30:15.381487Z","end":"2026-06-24T05:30:15.636206Z","steps":["trace[858763178] 'process raft request' (duration: 254.462965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:15.636261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.393517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:30:15.63758Z","caller":"traceutil/trace.go:171","msg":"trace[1408565555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1092; }","duration":"246.74348ms","start":"2026-06-24T05:30:15.390817Z","end":"2026-06-24T05:30:15.63756Z","steps":["trace[1408565555] 'agreement among raft nodes before linearized reading' (duration: 245.390687ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:16.17475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.148369ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T05:30:17.522777Z","caller":"traceutil/trace.go:171","msg":"trace[88853533] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"365.529596ms","start":"2026-06-24T05:30:17.15722Z","end":"2026-06-24T05:30:17.52275Z","steps":["trace[88853533] 'process raft request' (duration: 365.327529ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:17.523018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.157196Z","time spent":"365.65165ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:18.248798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.519908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:30:18.248862Z","caller":"traceutil/trace.go:171","msg":"trace[1881379175] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"607.98754ms","start":"2026-06-24T05:30:17.640863Z","end":"2026-06-24T05:30:18.24885Z","steps":["trace[1881379175] 'read index received' (duration: 315.294346ms)","trace[1881379175] 'applied index is now lower than readState.Index' (duration: 292.692134ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:18.248921Z","caller":"traceutil/trace.go:171","msg":"trace[701861659] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"722.279922ms","start":"2026-06-24T05:30:17.526576Z","end":"2026-06-24T05:30:18.248856Z","steps":["trace[701861659] 'process raft request' (duration: 429.64634ms)","trace[701861659] 'compare' (duration: 292.441416ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:18.248977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.129484ms","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-24T05:30:18.24899Z","caller":"traceutil/trace.go:171","msg":"trace[931530927] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1094; }","duration":"608.186366ms","start":"2026-06-24T05:30:17.640799Z","end":"2026-06-24T05:30:18.248986Z","steps":["trace[931530927] 'agreement among raft nodes before linearized reading' (duration: 608.113214ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.249005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.640778Z","time spent":"608.223907ms","remote":"127.0.0.1:58532","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-24T05:30:18.249014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.526557Z","time spent":"722.413397ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:18.249194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.283399ms","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-24T05:30:18.249228Z","caller":"traceutil/trace.go:171","msg":"trace[601550679] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1094; }","duration":"608.386422ms","start":"2026-06-24T05:30:17.640832Z","end":"2026-06-24T05:30:18.249218Z","steps":["trace[601550679] 'agreement among raft nodes before linearized reading' (duration: 608.263789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.249257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.640809Z","time spent":"608.442053ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:18.249369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.364441ms","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-24T05:30:18.249398Z","caller":"traceutil/trace.go:171","msg":"trace[1469912788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1094; }","duration":"608.409823ms","start":"2026-06-24T05:30:17.640981Z","end":"2026-06-24T05:30:18.249391Z","steps":["trace[1469912788] 'agreement among raft nodes before linearized reading' (duration: 608.335911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.249422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.640975Z","time spent":"608.442414ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:18.249419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.516287ms","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-24T05:30:18.249453Z","caller":"traceutil/trace.go:171","msg":"trace[957211326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1094; }","duration":"608.590159ms","start":"2026-06-24T05:30:17.640858Z","end":"2026-06-24T05:30:18.249448Z","steps":["trace[957211326] 'agreement among raft nodes before linearized reading' (duration: 608.528767ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.249468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:17.640838Z","time spent":"608.62588ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:18.697976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.300847ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:30:18.698103Z","caller":"traceutil/trace.go:171","msg":"trace[152488878] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"444.785874ms","start":"2026-06-24T05:30:18.25329Z","end":"2026-06-24T05:30:18.698076Z","steps":["trace[152488878] 'process raft request' (duration: 105.294501ms)","trace[152488878] 'compare' (duration: 339.166932ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:18.69821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:18.253274Z","time spent":"444.879106ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:30:18.774506Z","caller":"traceutil/trace.go:171","msg":"trace[1960226963] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"520.502883ms","start":"2026-06-24T05:30:18.25398Z","end":"2026-06-24T05:30:18.774483Z","steps":["trace[1960226963] 'process raft request' (duration: 520.294356ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:18.774767Z","caller":"traceutil/trace.go:171","msg":"trace[43362908] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"520.295426ms","start":"2026-06-24T05:30:18.254261Z","end":"2026-06-24T05:30:18.774556Z","steps":["trace[43362908] 'process raft request' (duration: 520.137171ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.774834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:18.253956Z","time spent":"520.798603ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:18.774892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:18.25425Z","time spent":"520.584475ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:30:18.774496Z","caller":"traceutil/trace.go:171","msg":"trace[2118907769] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"520.154372ms","start":"2026-06-24T05:30:18.254318Z","end":"2026-06-24T05:30:18.774472Z","steps":["trace[2118907769] 'process raft request' (duration: 520.11457ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:18.775087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:18.254308Z","time spent":"520.71777ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:20.852345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.018244ms","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-24T05:30:20.852401Z","caller":"traceutil/trace.go:171","msg":"trace[1985062045] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"150.120657ms","start":"2026-06-24T05:30:20.702268Z","end":"2026-06-24T05:30:20.852388Z","steps":["trace[1985062045] 'range keys from in-memory index tree' (duration: 149.89363ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:21.562723Z","caller":"traceutil/trace.go:171","msg":"trace[271344756] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"707.131762ms","start":"2026-06-24T05:30:20.855559Z","end":"2026-06-24T05:30:21.56269Z","steps":["trace[271344756] 'process raft request' (duration: 706.937056ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:21.562652Z","caller":"traceutil/trace.go:171","msg":"trace[110333606] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"706.468461ms","start":"2026-06-24T05:30:20.856167Z","end":"2026-06-24T05:30:21.562635Z","steps":["trace[110333606] 'process raft request' (duration: 706.44554ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:21.562743Z","caller":"traceutil/trace.go:171","msg":"trace[455882170] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"706.811222ms","start":"2026-06-24T05:30:20.855912Z","end":"2026-06-24T05:30:21.562723Z","steps":["trace[455882170] 'process raft request' (duration: 706.679318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:21.562897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:20.855904Z","time spent":"706.963596ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:21.56297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:20.855539Z","time spent":"707.266457ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:21.563131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:20.856159Z","time spent":"706.668537ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:22.096648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.681991ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:30:22.096852Z","caller":"traceutil/trace.go:171","msg":"trace[1324062342] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"504.313618ms","start":"2026-06-24T05:30:21.592517Z","end":"2026-06-24T05:30:22.09683Z","steps":["trace[1324062342] 'process raft request' (duration: 311.370808ms)","trace[1324062342] 'compare' (duration: 192.543817ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:22.096941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:21.592487Z","time spent":"504.413571ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:22.465609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.465146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-24T05:30:22.46568Z","caller":"traceutil/trace.go:171","msg":"trace[101163450] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1106; }","duration":"138.573ms","start":"2026-06-24T05:30:22.327092Z","end":"2026-06-24T05:30:22.465665Z","steps":["trace[101163450] 'range keys from in-memory index tree' (duration: 138.311381ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:36.816012Z","caller":"traceutil/trace.go:171","msg":"trace[1611581351] linearizableReadLoop","detail":"{readStateIndex:1257; appliedIndex:1256; }","duration":"183.988275ms","start":"2026-06-24T05:30:36.632003Z","end":"2026-06-24T05:30:36.815991Z","steps":["trace[1611581351] 'read index received' (duration: 183.85337ms)","trace[1611581351] 'applied index is now lower than readState.Index' (duration: 133.565µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:36.816166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.143079ms","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-24T05:30:36.816194Z","caller":"traceutil/trace.go:171","msg":"trace[45397194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1204; }","duration":"184.222152ms","start":"2026-06-24T05:30:36.631964Z","end":"2026-06-24T05:30:36.816186Z","steps":["trace[45397194] 'agreement among raft nodes before linearized reading' (duration: 184.102218ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:36.816418Z","caller":"traceutil/trace.go:171","msg":"trace[1985126516] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"673.740319ms","start":"2026-06-24T05:30:36.142672Z","end":"2026-06-24T05:30:36.816412Z","steps":["trace[1985126516] 'process raft request' (duration: 673.164151ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:36.816485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:36.142652Z","time spent":"673.786751ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:30:37.043718Z","caller":"traceutil/trace.go:171","msg":"trace[1556116878] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"222.578693ms","start":"2026-06-24T05:30:36.821105Z","end":"2026-06-24T05:30:37.043684Z","steps":["trace[1556116878] 'process raft request' (duration: 198.976252ms)","trace[1556116878] 'compare' (duration: 23.342903ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:30:39.547762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006529869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:30:40.048466Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006529869,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:30:40.055294Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.234882476s","expected-duration":"1s"} {"level":"info","ts":"2026-06-24T05:30:40.055837Z","caller":"traceutil/trace.go:171","msg":"trace[2022167059] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"1.009051959s","start":"2026-06-24T05:30:39.046727Z","end":"2026-06-24T05:30:40.055779Z","steps":["trace[2022167059] 'read index received' (duration: 1.008814812s)","trace[2022167059] 'applied index is now lower than readState.Index' (duration: 235.427µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:30:40.055972Z","caller":"traceutil/trace.go:171","msg":"trace[138430223] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.235625228s","start":"2026-06-24T05:30:38.820313Z","end":"2026-06-24T05:30:40.055938Z","steps":["trace[138430223] 'process raft request' (duration: 1.235292028s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:40.056169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:38.820295Z","time spent":"1.235787374s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:30:40.056243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.036554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-24T05:30:40.056275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.093382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-24T05:30:40.056339Z","caller":"traceutil/trace.go:171","msg":"trace[1429931711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1210; }","duration":"999.179926ms","start":"2026-06-24T05:30:39.057144Z","end":"2026-06-24T05:30:40.056323Z","steps":["trace[1429931711] 'agreement among raft nodes before linearized reading' (duration: 999.020571ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:40.056306Z","caller":"traceutil/trace.go:171","msg":"trace[1405392418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1210; }","duration":"348.150079ms","start":"2026-06-24T05:30:39.708141Z","end":"2026-06-24T05:30:40.056291Z","steps":["trace[1405392418] 'agreement among raft nodes before linearized reading' (duration: 347.917991ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:40.056027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000720364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:30:40.05644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.425454ms","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-24T05:30:40.056429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.404392ms","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-24T05:30:40.056459Z","caller":"traceutil/trace.go:171","msg":"trace[400427936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1210; }","duration":"1.001175999s","start":"2026-06-24T05:30:39.055255Z","end":"2026-06-24T05:30:40.056431Z","steps":["trace[400427936] 'agreement among raft nodes before linearized reading' (duration: 1.000716574s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:40.056471Z","caller":"traceutil/trace.go:171","msg":"trace[1028238758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"352.495077ms","start":"2026-06-24T05:30:39.70397Z","end":"2026-06-24T05:30:40.056465Z","steps":["trace[1028238758] 'agreement among raft nodes before linearized reading' (duration: 352.429445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:40.056498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.703941Z","time spent":"352.552199ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:40.056508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.055243Z","time spent":"1.001252961s","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:30:40.056374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.057136Z","time spent":"999.232257ms","remote":"127.0.0.1:58532","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-24T05:30:40.05654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.708133Z","time spent":"348.283073ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T05:30:40.056735Z","caller":"traceutil/trace.go:171","msg":"trace[705325731] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"384.490524ms","start":"2026-06-24T05:30:39.671982Z","end":"2026-06-24T05:30:40.056473Z","steps":["trace[705325731] 'agreement among raft nodes before linearized reading' (duration: 384.210165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:40.056795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.671963Z","time spent":"384.822505ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T05:30:40.057651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.010915298s","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-24T05:30:40.057734Z","caller":"traceutil/trace.go:171","msg":"trace[962420547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1210; }","duration":"1.011026072s","start":"2026-06-24T05:30:39.046695Z","end":"2026-06-24T05:30:40.057721Z","steps":["trace[962420547] 'agreement among raft nodes before linearized reading' (duration: 1.010823236s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:30:40.057768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:30:39.046676Z","time spent":"1.011084074s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-24T05:30:42.266483Z","caller":"traceutil/trace.go:171","msg":"trace[880414094] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"148.904027ms","start":"2026-06-24T05:30:42.117559Z","end":"2026-06-24T05:30:42.266463Z","steps":["trace[880414094] 'process raft request' (duration: 148.853356ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:42.266509Z","caller":"traceutil/trace.go:171","msg":"trace[704713335] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"149.167476ms","start":"2026-06-24T05:30:42.117315Z","end":"2026-06-24T05:30:42.266482Z","steps":["trace[704713335] 'process raft request' (duration: 148.99426ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:42.266744Z","caller":"traceutil/trace.go:171","msg":"trace[1327272168] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"149.235969ms","start":"2026-06-24T05:30:42.117486Z","end":"2026-06-24T05:30:42.266722Z","steps":["trace[1327272168] 'process raft request' (duration: 149.139126ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:42.266798Z","caller":"traceutil/trace.go:171","msg":"trace[1402709806] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"149.172386ms","start":"2026-06-24T05:30:42.117591Z","end":"2026-06-24T05:30:42.266763Z","steps":["trace[1402709806] 'process raft request' (duration: 148.843795ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:30:52.263608Z","caller":"traceutil/trace.go:171","msg":"trace[908560053] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"124.546393ms","start":"2026-06-24T05:30:52.139041Z","end":"2026-06-24T05:30:52.263588Z","steps":["trace[908560053] 'process raft request' (duration: 124.119099ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:00.946645Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006530069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-24T05:31:00.981257Z","caller":"traceutil/trace.go:171","msg":"trace[1814169078] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"535.328824ms","start":"2026-06-24T05:31:00.445905Z","end":"2026-06-24T05:31:00.981234Z","steps":["trace[1814169078] 'read index received' (duration: 535.138618ms)","trace[1814169078] 'applied index is now lower than readState.Index' (duration: 189.226µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:00.981522Z","caller":"traceutil/trace.go:171","msg":"trace[1729871678] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"537.658208ms","start":"2026-06-24T05:31:00.443669Z","end":"2026-06-24T05:31:00.981327Z","steps":["trace[1729871678] 'process raft request' (duration: 537.448772ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:00.981601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.443405Z","time spent":"538.142474ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:01.357812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.507638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:01.357885Z","caller":"traceutil/trace.go:171","msg":"trace[704105751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"303.617302ms","start":"2026-06-24T05:31:01.054253Z","end":"2026-06-24T05:31:01.35787Z","steps":["trace[704105751] 'range keys from in-memory index tree' (duration: 303.463026ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:01.357917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:01.054237Z","time spent":"303.673724ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:31:01.358106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.617082ms","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-24T05:31:01.358109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.077629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T05:31:01.358133Z","caller":"traceutil/trace.go:171","msg":"trace[251936486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"907.658033ms","start":"2026-06-24T05:31:00.450466Z","end":"2026-06-24T05:31:01.358124Z","steps":["trace[251936486] 'agreement among raft nodes before linearized reading' (duration: 531.063099ms)","trace[251936486] 'range keys from in-memory index tree' (duration: 376.525772ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:01.358158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.45046Z","time spent":"907.693454ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:31:01.358175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.432223ms","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-24T05:31:01.358172Z","caller":"traceutil/trace.go:171","msg":"trace[2019588429] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1304; }","duration":"553.167212ms","start":"2026-06-24T05:31:00.804983Z","end":"2026-06-24T05:31:01.35815Z","steps":["trace[2019588429] 'agreement among raft nodes before linearized reading' (duration: 176.568928ms)","trace[2019588429] 'count revisions from in-memory index tree' (duration: 376.516721ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:01.358201Z","caller":"traceutil/trace.go:171","msg":"trace[154072052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"900.499635ms","start":"2026-06-24T05:31:00.457691Z","end":"2026-06-24T05:31:01.35819Z","steps":["trace[154072052] 'agreement among raft nodes before linearized reading' (duration: 523.87951ms)","trace[154072052] 'range keys from in-memory index tree' (duration: 376.562493ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:01.358205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.804969Z","time spent":"553.228894ms","remote":"127.0.0.1:58800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:31:01.358224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.457682Z","time spent":"900.538016ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:31:01.358195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.272239ms","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-24T05:31:01.35822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.059695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:01.358273Z","caller":"traceutil/trace.go:171","msg":"trace[445380690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"912.397234ms","start":"2026-06-24T05:31:00.445854Z","end":"2026-06-24T05:31:01.358251Z","steps":["trace[445380690] 'agreement among raft nodes before linearized reading' (duration: 535.688096ms)","trace[445380690] 'range keys from in-memory index tree' (duration: 376.504891ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:01.358292Z","caller":"traceutil/trace.go:171","msg":"trace[237477317] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1304; }","duration":"397.178128ms","start":"2026-06-24T05:31:00.961094Z","end":"2026-06-24T05:31:01.358272Z","steps":["trace[237477317] 'agreement among raft nodes before linearized reading' (duration: 20.451ms)","trace[237477317] 'count revisions from in-memory index tree' (duration: 376.632365ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:01.358315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.445829Z","time spent":"912.476826ms","remote":"127.0.0.1:58532","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-24T05:31:01.358325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.261345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.227\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-24T05:31:01.358333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.961075Z","time spent":"397.247571ms","remote":"127.0.0.1:48414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-06-24T05:31:01.358349Z","caller":"traceutil/trace.go:171","msg":"trace[685303727] range","detail":"{range_begin:/registry/masterleases/199.204.45.227; range_end:; response_count:1; response_revision:1304; }","duration":"789.329208ms","start":"2026-06-24T05:31:00.569012Z","end":"2026-06-24T05:31:01.358342Z","steps":["trace[685303727] 'agreement among raft nodes before linearized reading' (duration: 412.546238ms)","trace[685303727] 'range keys from in-memory index tree' (duration: 376.726068ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:01.358403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.568985Z","time spent":"789.41129ms","remote":"127.0.0.1:58306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.227\" "} {"level":"warn","ts":"2026-06-24T05:31:01.357835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.070961ms","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-24T05:31:01.35846Z","caller":"traceutil/trace.go:171","msg":"trace[642990877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"900.832185ms","start":"2026-06-24T05:31:00.45762Z","end":"2026-06-24T05:31:01.358452Z","steps":["trace[642990877] 'agreement among raft nodes before linearized reading' (duration: 523.959932ms)","trace[642990877] 'range keys from in-memory index tree' (duration: 376.14262ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:01.35848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:00.457608Z","time spent":"900.865457ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T05:31:01.961781Z","caller":"traceutil/trace.go:171","msg":"trace[2058237013] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"210.605222ms","start":"2026-06-24T05:31:01.751157Z","end":"2026-06-24T05:31:01.961763Z","steps":["trace[2058237013] 'process raft request' (duration: 210.506449ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:02.891003Z","caller":"traceutil/trace.go:171","msg":"trace[231151572] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"581.835054ms","start":"2026-06-24T05:31:02.309144Z","end":"2026-06-24T05:31:02.890979Z","steps":["trace[231151572] 'process raft request' (duration: 581.68737ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:02.89122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:02.309119Z","time spent":"582.032081ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:04.316035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.281963ms","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-24T05:31:04.316069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"919.444838ms","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-24T05:31:04.316125Z","caller":"traceutil/trace.go:171","msg":"trace[674534129] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"919.421698ms","start":"2026-06-24T05:31:03.396683Z","end":"2026-06-24T05:31:04.316105Z","steps":["trace[674534129] 'range keys from in-memory index tree' (duration: 919.15897ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:04.316147Z","caller":"traceutil/trace.go:171","msg":"trace[1582000619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"919.559703ms","start":"2026-06-24T05:31:03.396568Z","end":"2026-06-24T05:31:04.316128Z","steps":["trace[1582000619] 'range keys from in-memory index tree' (duration: 919.282644ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.396672Z","time spent":"919.48929ms","remote":"127.0.0.1:58532","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-24T05:31:04.316195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.396553Z","time spent":"919.631504ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-24T05:31:04.316262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.082154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T05:31:04.316363Z","caller":"traceutil/trace.go:171","msg":"trace[167108331] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1311; }","duration":"220.220388ms","start":"2026-06-24T05:31:04.096109Z","end":"2026-06-24T05:31:04.31633Z","steps":["trace[167108331] 'count revisions from in-memory index tree' (duration: 219.97963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.440709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-24T05:31:04.316535Z","caller":"traceutil/trace.go:171","msg":"trace[1894104939] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1311; }","duration":"442.574944ms","start":"2026-06-24T05:31:03.873937Z","end":"2026-06-24T05:31:04.316512Z","steps":["trace[1894104939] 'count revisions from in-memory index tree' (duration: 442.321506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.44218ms","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-24T05:31:04.316578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.87391Z","time spent":"442.657297ms","remote":"127.0.0.1:36730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:31:04.31629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330313252s","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-24T05:31:04.316598Z","caller":"traceutil/trace.go:171","msg":"trace[1010968650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"920.519303ms","start":"2026-06-24T05:31:03.396066Z","end":"2026-06-24T05:31:04.316585Z","steps":["trace[1010968650] 'range keys from in-memory index tree' (duration: 920.275505ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259920872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:31:04.316633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.396031Z","time spent":"920.594155ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-24T05:31:04.316633Z","caller":"traceutil/trace.go:171","msg":"trace[1870992619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1311; }","duration":"1.330699985s","start":"2026-06-24T05:31:02.985916Z","end":"2026-06-24T05:31:04.316616Z","steps":["trace[1870992619] 'range keys from in-memory index tree' (duration: 1.330084065s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:04.316662Z","caller":"traceutil/trace.go:171","msg":"trace[2061684321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"1.260038666s","start":"2026-06-24T05:31:03.056602Z","end":"2026-06-24T05:31:04.316641Z","steps":["trace[2061684321] 'range keys from in-memory index tree' (duration: 1.259803459s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:02.985895Z","time spent":"1.330774877s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-24T05:31:04.316702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.05657Z","time spent":"1.260122269s","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:31:04.316482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.462331ms","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-24T05:31:04.316751Z","caller":"traceutil/trace.go:171","msg":"trace[1988132559] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1311; }","duration":"920.767191ms","start":"2026-06-24T05:31:03.395975Z","end":"2026-06-24T05:31:04.316742Z","steps":["trace[1988132559] 'range keys from in-memory index tree' (duration: 920.346647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.395955Z","time spent":"920.815872ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:31:04.316844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.580571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:04.316879Z","caller":"traceutil/trace.go:171","msg":"trace[1248534829] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1311; }","duration":"616.667124ms","start":"2026-06-24T05:31:03.700202Z","end":"2026-06-24T05:31:04.316869Z","steps":["trace[1248534829] 'count revisions from in-memory index tree' (duration: 616.484098ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.316908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.700173Z","time spent":"616.727946ms","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:31:04.317089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.251635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-24T05:31:04.317129Z","caller":"traceutil/trace.go:171","msg":"trace[1324304111] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1311; }","duration":"602.336808ms","start":"2026-06-24T05:31:03.714782Z","end":"2026-06-24T05:31:04.317119Z","steps":["trace[1324304111] 'range keys from in-memory index tree' (duration: 600.84008ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.317173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:03.714757Z","time spent":"602.40869ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-24T05:31:04.792831Z","caller":"traceutil/trace.go:171","msg":"trace[1453191221] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"469.82645ms","start":"2026-06-24T05:31:04.322987Z","end":"2026-06-24T05:31:04.792814Z","steps":["trace[1453191221] 'process raft request' (duration: 469.711506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:04.792949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.322968Z","time spent":"469.919903ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:31:05.169462Z","caller":"traceutil/trace.go:171","msg":"trace[1375599078] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"846.231927ms","start":"2026-06-24T05:31:04.323204Z","end":"2026-06-24T05:31:05.169436Z","steps":["trace[1375599078] 'process raft request' (duration: 845.891346ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:05.169548Z","caller":"traceutil/trace.go:171","msg":"trace[1212680631] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"846.287119ms","start":"2026-06-24T05:31:04.323237Z","end":"2026-06-24T05:31:05.169524Z","steps":["trace[1212680631] 'process raft request' (duration: 846.171475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.169594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.323183Z","time spent":"846.344061ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:05.169693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.32321Z","time spent":"846.393662ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:31:05.170006Z","caller":"traceutil/trace.go:171","msg":"trace[576951737] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"846.737894ms","start":"2026-06-24T05:31:04.323255Z","end":"2026-06-24T05:31:05.169993Z","steps":["trace[576951737] 'process raft request' (duration: 846.236378ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.17007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.323246Z","time spent":"846.792615ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:31:05.170153Z","caller":"traceutil/trace.go:171","msg":"trace[2070544061] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"846.869958ms","start":"2026-06-24T05:31:04.323274Z","end":"2026-06-24T05:31:05.170144Z","steps":["trace[2070544061] 'process raft request' (duration: 846.529987ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.1702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.32321Z","time spent":"846.966971ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:31:05.170025Z","caller":"traceutil/trace.go:171","msg":"trace[1279689992] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1369; }","duration":"844.843113ms","start":"2026-06-24T05:31:04.325158Z","end":"2026-06-24T05:31:05.170001Z","steps":["trace[1279689992] 'read index received' (duration: 467.570838ms)","trace[1279689992] 'applied index is now lower than readState.Index' (duration: 377.271015ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:05.170237Z","caller":"traceutil/trace.go:171","msg":"trace[1944262149] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"846.742273ms","start":"2026-06-24T05:31:04.32348Z","end":"2026-06-24T05:31:05.170222Z","steps":["trace[1944262149] 'process raft request' (duration: 846.478965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.170567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.323469Z","time spent":"846.92835ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:05.170572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.39014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:05.170734Z","caller":"traceutil/trace.go:171","msg":"trace[838560833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1317; }","duration":"845.581467ms","start":"2026-06-24T05:31:04.325136Z","end":"2026-06-24T05:31:05.170718Z","steps":["trace[838560833] 'agreement among raft nodes before linearized reading' (duration: 845.37622ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.17082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.32513Z","time spent":"845.670679ms","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-24T05:31:05.202404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.202769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:31:05.202473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.290575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:05.202546Z","caller":"traceutil/trace.go:171","msg":"trace[1681254069] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1318; }","duration":"688.359184ms","start":"2026-06-24T05:31:04.514151Z","end":"2026-06-24T05:31:05.20251Z","steps":["trace[1681254069] 'agreement among raft nodes before linearized reading' (duration: 688.194298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.202597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.514134Z","time spent":"688.452867ms","remote":"127.0.0.1:48630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-06-24T05:31:05.202414Z","caller":"traceutil/trace.go:171","msg":"trace[1789662704] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"305.547873ms","start":"2026-06-24T05:31:04.896842Z","end":"2026-06-24T05:31:05.20239Z","steps":["trace[1789662704] 'process raft request' (duration: 305.408528ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.20276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:04.896812Z","time spent":"305.865133ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-24T05:31:05.203117Z","caller":"traceutil/trace.go:171","msg":"trace[1806790203] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1318; }","duration":"146.966166ms","start":"2026-06-24T05:31:05.056116Z","end":"2026-06-24T05:31:05.203082Z","steps":["trace[1806790203] 'agreement among raft nodes before linearized reading' (duration: 146.284154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.725048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.317153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:05.725152Z","caller":"traceutil/trace.go:171","msg":"trace[1616421633] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1318; }","duration":"430.485168ms","start":"2026-06-24T05:31:05.294645Z","end":"2026-06-24T05:31:05.72513Z","steps":["trace[1616421633] 'count revisions from in-memory index tree' (duration: 430.22806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:05.725203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:05.294622Z","time spent":"430.563881ms","remote":"127.0.0.1:48556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:31:06.556553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656053574006530114,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-24T05:31:06.639499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.932279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:31:06.639605Z","caller":"traceutil/trace.go:171","msg":"trace[145543886] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"857.275469ms","start":"2026-06-24T05:31:05.782309Z","end":"2026-06-24T05:31:06.639585Z","steps":["trace[145543886] 'process raft request' (duration: 115.190395ms)","trace[145543886] 'compare' (duration: 741.818575ms)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:06.63966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:05.782298Z","time spent":"857.338881ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T05:31:07.234225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.773953ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-24T05:31:07.328996Z","caller":"traceutil/trace.go:171","msg":"trace[1864011941] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1378; }","duration":"1.272871484s","start":"2026-06-24T05:31:06.0561Z","end":"2026-06-24T05:31:07.328972Z","steps":["trace[1864011941] 'read index received' (duration: 585.966856ms)","trace[1864011941] 'applied index is now lower than readState.Index' (duration: 686.903148ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:07.329058Z","caller":"traceutil/trace.go:171","msg":"trace[125398631] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.072268571s","start":"2026-06-24T05:31:06.256756Z","end":"2026-06-24T05:31:07.329025Z","steps":["trace[125398631] 'process raft request' (duration: 1.071997212s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.329115Z","caller":"traceutil/trace.go:171","msg":"trace[673715365] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"807.721942ms","start":"2026-06-24T05:31:06.521383Z","end":"2026-06-24T05:31:07.329105Z","steps":["trace[673715365] 'process raft request' (duration: 807.536136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.329213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.256731Z","time spent":"1.072396945s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:07.329232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.521359Z","time spent":"807.785745ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:07.329264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273159533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:07.329318Z","caller":"traceutil/trace.go:171","msg":"trace[1055581306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"1.273255737s","start":"2026-06-24T05:31:06.056048Z","end":"2026-06-24T05:31:07.329304Z","steps":["trace[1055581306] 'agreement among raft nodes before linearized reading' (duration: 1.273145733s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.329353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.056026Z","time spent":"1.273320148s","remote":"127.0.0.1:58250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:31:07.724849Z","caller":"traceutil/trace.go:171","msg":"trace[1912764104] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1381; }","duration":"395.746942ms","start":"2026-06-24T05:31:07.329077Z","end":"2026-06-24T05:31:07.724824Z","steps":["trace[1912764104] 'read index received' (duration: 395.492064ms)","trace[1912764104] 'applied index is now lower than readState.Index' (duration: 253.388µs)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:07.724901Z","caller":"traceutil/trace.go:171","msg":"trace[1568500601] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.077167197s","start":"2026-06-24T05:31:06.647702Z","end":"2026-06-24T05:31:07.724869Z","steps":["trace[1568500601] 'process raft request' (duration: 1.076881968s)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.724999Z","caller":"traceutil/trace.go:171","msg":"trace[6728980] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"517.320492ms","start":"2026-06-24T05:31:07.207632Z","end":"2026-06-24T05:31:07.724953Z","steps":["trace[6728980] 'process raft request' (duration: 517.125445ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.647688Z","time spent":"1.07728431s","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T05:31:07.725108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.432046ms","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-24T05:31:07.725109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.013811ms","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-24T05:31:07.725152Z","caller":"traceutil/trace.go:171","msg":"trace[718481281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"550.508317ms","start":"2026-06-24T05:31:07.174627Z","end":"2026-06-24T05:31:07.725136Z","steps":["trace[718481281] 'agreement among raft nodes before linearized reading' (duration: 550.353062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.207615Z","time spent":"517.461606ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-24T05:31:07.725188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.174612Z","time spent":"550.56756ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-24T05:31:07.725262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.048353ms","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-24T05:31:07.725298Z","caller":"traceutil/trace.go:171","msg":"trace[1107752464] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"550.086264ms","start":"2026-06-24T05:31:07.175204Z","end":"2026-06-24T05:31:07.72529Z","steps":["trace[1107752464] 'agreement among raft nodes before linearized reading' (duration: 549.999732ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.175195Z","time spent":"550.133816ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-24T05:31:07.725178Z","caller":"traceutil/trace.go:171","msg":"trace[692925] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1325; }","duration":"550.135886ms","start":"2026-06-24T05:31:07.175028Z","end":"2026-06-24T05:31:07.725164Z","steps":["trace[692925] 'agreement among raft nodes before linearized reading' (duration: 549.851197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.174979Z","time spent":"550.411344ms","remote":"127.0.0.1:58532","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-24T05:31:07.725533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.344876ms","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-24T05:31:07.725531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077830528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-06-24T05:31:07.725566Z","caller":"traceutil/trace.go:171","msg":"trace[156075837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1325; }","duration":"552.406968ms","start":"2026-06-24T05:31:07.17315Z","end":"2026-06-24T05:31:07.725557Z","steps":["trace[156075837] 'agreement among raft nodes before linearized reading' (duration: 552.317825ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.725574Z","caller":"traceutil/trace.go:171","msg":"trace[1192455959] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1325; }","duration":"1.07789455s","start":"2026-06-24T05:31:06.647668Z","end":"2026-06-24T05:31:07.725562Z","steps":["trace[1192455959] 'agreement among raft nodes before linearized reading' (duration: 1.077763216s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.536205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:31:07.725605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.647649Z","time spent":"1.077948622s","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-24T05:31:07.72563Z","caller":"traceutil/trace.go:171","msg":"trace[631400034] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1325; }","duration":"828.625798ms","start":"2026-06-24T05:31:06.896993Z","end":"2026-06-24T05:31:07.725618Z","steps":["trace[631400034] 'agreement among raft nodes before linearized reading' (duration: 828.555305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.281469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:07.725718Z","caller":"traceutil/trace.go:171","msg":"trace[57114758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1325; }","duration":"395.412622ms","start":"2026-06-24T05:31:07.330292Z","end":"2026-06-24T05:31:07.725705Z","steps":["trace[57114758] 'agreement among raft nodes before linearized reading' (duration: 395.280118ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.573369ms","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":"warn","ts":"2026-06-24T05:31:07.725754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.330274Z","time spent":"395.474084ms","remote":"127.0.0.1:58266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-24T05:31:07.725766Z","caller":"traceutil/trace.go:171","msg":"trace[894309006] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1325; }","duration":"193.637061ms","start":"2026-06-24T05:31:07.532121Z","end":"2026-06-24T05:31:07.725758Z","steps":["trace[894309006] 'agreement among raft nodes before linearized reading' (duration: 193.583289ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.725669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.896971Z","time spent":"828.68898ms","remote":"127.0.0.1:48380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-06-24T05:31:07.725597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.173129Z","time spent":"552.46201ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-24T05:31:07.725669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.057235ms","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-24T05:31:07.726091Z","caller":"traceutil/trace.go:171","msg":"trace[1228409086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1325; }","duration":"551.491109ms","start":"2026-06-24T05:31:07.174586Z","end":"2026-06-24T05:31:07.726077Z","steps":["trace[1228409086] 'agreement among raft nodes before linearized reading' (duration: 551.028165ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.726142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:07.174576Z","time spent":"551.553251ms","remote":"127.0.0.1:58532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-24T05:31:07.725748Z","caller":"traceutil/trace.go:171","msg":"trace[718619703] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.076696512s","start":"2026-06-24T05:31:06.648141Z","end":"2026-06-24T05:31:07.724837Z","steps":["trace[718619703] 'process raft request' (duration: 1.076579358s)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.72648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-24T05:31:06.648051Z","time spent":"1.078372215s","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-24T05:31:07.909078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.574482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-24T05:31:07.909152Z","caller":"traceutil/trace.go:171","msg":"trace[418083849] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"179.390188ms","start":"2026-06-24T05:31:07.729748Z","end":"2026-06-24T05:31:07.909138Z","steps":["trace[418083849] 'process raft request' (duration: 77.649331ms)","trace[418083849] 'compare' (duration: 101.435028ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:07.94372Z","caller":"traceutil/trace.go:171","msg":"trace[1897657937] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1384; }","duration":"211.49315ms","start":"2026-06-24T05:31:07.732215Z","end":"2026-06-24T05:31:07.943708Z","steps":["trace[1897657937] 'read index received' (duration: 75.180152ms)","trace[1897657937] 'applied index is now lower than readState.Index' (duration: 136.312288ms)"],"step_count":2} {"level":"info","ts":"2026-06-24T05:31:07.943728Z","caller":"traceutil/trace.go:171","msg":"trace[2072643257] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"213.921328ms","start":"2026-06-24T05:31:07.729788Z","end":"2026-06-24T05:31:07.943709Z","steps":["trace[2072643257] 'process raft request' (duration: 213.66688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:07.943817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.572312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:07.943834Z","caller":"traceutil/trace.go:171","msg":"trace[1891208858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"211.639004ms","start":"2026-06-24T05:31:07.732189Z","end":"2026-06-24T05:31:07.943828Z","steps":["trace[1891208858] 'agreement among raft nodes before linearized reading' (duration: 211.568432ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.943923Z","caller":"traceutil/trace.go:171","msg":"trace[879653007] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"213.908307ms","start":"2026-06-24T05:31:07.729991Z","end":"2026-06-24T05:31:07.9439Z","steps":["trace[879653007] 'process raft request' (duration: 213.620168ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.943957Z","caller":"traceutil/trace.go:171","msg":"trace[649316104] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"212.079548ms","start":"2026-06-24T05:31:07.73187Z","end":"2026-06-24T05:31:07.94395Z","steps":["trace[649316104] 'process raft request' (duration: 211.81719ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.944035Z","caller":"traceutil/trace.go:171","msg":"trace[1646605413] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"213.098411ms","start":"2026-06-24T05:31:07.730873Z","end":"2026-06-24T05:31:07.943972Z","steps":["trace[1646605413] 'process raft request' (duration: 212.795691ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.943723Z","caller":"traceutil/trace.go:171","msg":"trace[332154793] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"213.840374ms","start":"2026-06-24T05:31:07.729859Z","end":"2026-06-24T05:31:07.943699Z","steps":["trace[332154793] 'process raft request' (duration: 213.68925ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:07.944375Z","caller":"traceutil/trace.go:171","msg":"trace[539248942] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"213.724551ms","start":"2026-06-24T05:31:07.730605Z","end":"2026-06-24T05:31:07.94433Z","steps":["trace[539248942] 'process raft request' (duration: 213.03801ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:09.906984Z","caller":"traceutil/trace.go:171","msg":"trace[1366865319] linearizableReadLoop","detail":"{readStateIndex:1392; appliedIndex:1391; }","duration":"110.209167ms","start":"2026-06-24T05:31:09.796749Z","end":"2026-06-24T05:31:09.906959Z","steps":["trace[1366865319] 'read index received' (duration: 109.920078ms)","trace[1366865319] 'applied index is now lower than readState.Index' (duration: 288.339µs)"],"step_count":2} {"level":"warn","ts":"2026-06-24T05:31:09.907164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.400274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-06-24T05:31:09.9072Z","caller":"traceutil/trace.go:171","msg":"trace[2130389607] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1333; }","duration":"110.486486ms","start":"2026-06-24T05:31:09.796703Z","end":"2026-06-24T05:31:09.90719Z","steps":["trace[2130389607] 'agreement among raft nodes before linearized reading' (duration: 110.352182ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:09.907477Z","caller":"traceutil/trace.go:171","msg":"trace[947601976] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"174.982238ms","start":"2026-06-24T05:31:09.73248Z","end":"2026-06-24T05:31:09.907463Z","steps":["trace[947601976] 'process raft request' (duration: 174.293796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-24T05:31:11.302459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.773449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-24T05:31:11.302548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.822916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-24T05:31:11.302575Z","caller":"traceutil/trace.go:171","msg":"trace[1854240093] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1342; }","duration":"211.950994ms","start":"2026-06-24T05:31:11.090595Z","end":"2026-06-24T05:31:11.302546Z","steps":["trace[1854240093] 'count revisions from in-memory index tree' (duration: 211.657275ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:11.302631Z","caller":"traceutil/trace.go:171","msg":"trace[1908507864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"247.95578ms","start":"2026-06-24T05:31:11.054652Z","end":"2026-06-24T05:31:11.302608Z","steps":["trace[1908507864] 'range keys from in-memory index tree' (duration: 247.725923ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:31:17.248075Z","caller":"traceutil/trace.go:171","msg":"trace[1909914028] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"205.478139ms","start":"2026-06-24T05:31:17.042568Z","end":"2026-06-24T05:31:17.248046Z","steps":["trace[1909914028] 'process raft request' (duration: 205.281603ms)"],"step_count":1} {"level":"info","ts":"2026-06-24T05:34:58.573417Z","caller":"traceutil/trace.go:171","msg":"trace[649986447] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"155.134435ms","start":"2026-06-24T05:34:58.418255Z","end":"2026-06-24T05:34:58.57339Z","steps":["trace[649986447] 'process raft request' (duration: 70.308188ms)","trace[649986447] 'compare' (duration: 84.730094ms)"],"step_count":2}