{"level":"warn","ts":"2026-06-26T03:38:35.163595Z","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-26T03:38:35.163749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.24: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.19.213.24:2380","--initial-cluster=instance=https://199.19.213.24:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.24:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.24: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-26T03:38:35.163865Z","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-26T03:38:35.163887Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.24:2380"]} {"level":"info","ts":"2026-06-26T03:38:35.163918Z","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-26T03:38:35.165136Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"]} {"level":"info","ts":"2026-06-26T03:38:35.16528Z","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.19.213.24:2380"],"listen-peer-urls":["https://199.19.213.24:2380"],"advertise-client-urls":["https://199.19.213.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.24: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-26T03:38:35.169637Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.02262ms"} {"level":"info","ts":"2026-06-26T03:38:35.175737Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bd149264c66f18d5","cluster-id":"781da5450c4333a5"} {"level":"info","ts":"2026-06-26T03:38:35.175848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=()"} {"level":"info","ts":"2026-06-26T03:38:35.175891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became follower at term 0"} {"level":"info","ts":"2026-06-26T03:38:35.175926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bd149264c66f18d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-26T03:38:35.175946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became follower at term 1"} {"level":"info","ts":"2026-06-26T03:38:35.176034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=(13624675734226147541)"} {"level":"warn","ts":"2026-06-26T03:38:35.181083Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-26T03:38:35.182431Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-26T03:38:35.185325Z","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-26T03:38:35.190373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bd149264c66f18d5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-26T03:38:35.190975Z","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-26T03:38:35.191027Z","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-26T03:38:35.191052Z","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-26T03:38:35.191666Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bd149264c66f18d5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-26T03:38:35.191913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 switched to configuration voters=(13624675734226147541)"} {"level":"info","ts":"2026-06-26T03:38:35.192028Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"781da5450c4333a5","local-member-id":"bd149264c66f18d5","added-peer-id":"bd149264c66f18d5","added-peer-peer-urls":["https://199.19.213.24:2380"]} {"level":"info","ts":"2026-06-26T03:38:35.19563Z","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-26T03:38:35.195799Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.24:2380"} {"level":"info","ts":"2026-06-26T03:38:35.195842Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.24:2380"} {"level":"info","ts":"2026-06-26T03:38:35.195956Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bd149264c66f18d5","initial-advertise-peer-urls":["https://199.19.213.24:2380"],"listen-peer-urls":["https://199.19.213.24:2380"],"advertise-client-urls":["https://199.19.213.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-26T03:38:35.196002Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-26T03:38:35.476984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 is starting a new election at term 1"} {"level":"info","ts":"2026-06-26T03:38:35.477127Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-26T03:38:35.477241Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 received MsgPreVoteResp from bd149264c66f18d5 at term 1"} {"level":"info","ts":"2026-06-26T03:38:35.477313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became candidate at term 2"} {"level":"info","ts":"2026-06-26T03:38:35.477404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 received MsgVoteResp from bd149264c66f18d5 at term 2"} {"level":"info","ts":"2026-06-26T03:38:35.477431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bd149264c66f18d5 became leader at term 2"} {"level":"info","ts":"2026-06-26T03:38:35.477477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bd149264c66f18d5 elected leader bd149264c66f18d5 at term 2"} {"level":"info","ts":"2026-06-26T03:38:35.4785Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:35.479855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:38:35.479857Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bd149264c66f18d5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.24:2379]}","request-path":"/0/members/bd149264c66f18d5/attributes","cluster-id":"781da5450c4333a5","publish-timeout":"7s"} {"level":"info","ts":"2026-06-26T03:38:35.479954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-26T03:38:35.480344Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-26T03:38:35.480465Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-26T03:38:35.480666Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"781da5450c4333a5","local-member-id":"bd149264c66f18d5","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:35.480807Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:35.480871Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-26T03:38:35.483404Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-26T03:38:35.516207Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.24:2379"} {"level":"warn","ts":"2026-06-26T03:39:00.042788Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922415575,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:39:00.789957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.129693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:39:00.790282Z","caller":"traceutil/trace.go:171","msg":"trace[342161422] linearizableReadLoop","detail":"{readStateIndex:459; appliedIndex:455; }","duration":"1.24849053s","start":"2026-06-26T03:38:59.541772Z","end":"2026-06-26T03:39:00.790262Z","steps":["trace[342161422] 'read index received' (duration: 811.509092ms)","trace[342161422] 'applied index is now lower than readState.Index' (duration: 436.980518ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:00.790411Z","caller":"traceutil/trace.go:171","msg":"trace[1066259161] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"1.276563777s","start":"2026-06-26T03:38:59.513836Z","end":"2026-06-26T03:39:00.790399Z","steps":["trace[1066259161] 'process raft request' (duration: 1.276389143s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:00.790453Z","caller":"traceutil/trace.go:171","msg":"trace[1962737111] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"1.287174484s","start":"2026-06-26T03:38:59.503153Z","end":"2026-06-26T03:39:00.790327Z","steps":["trace[1962737111] 'process raft request' (duration: 850.109604ms)","trace[1962737111] 'compare' (duration: 435.947918ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:39:00.790654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:59.513809Z","time spent":"1.276640699s","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:39:00.790745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.70985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:00.790847Z","caller":"traceutil/trace.go:171","msg":"trace[964542059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"652.863714ms","start":"2026-06-26T03:39:00.137957Z","end":"2026-06-26T03:39:00.790821Z","steps":["trace[964542059] 'agreement among raft nodes before linearized reading' (duration: 652.659909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:00.790894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:00.137929Z","time spent":"652.957255ms","remote":"127.0.0.1:39600","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-26T03:39:00.790977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249195114s","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-26T03:39:00.791075Z","caller":"traceutil/trace.go:171","msg":"trace[841741953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:444; }","duration":"1.249341018s","start":"2026-06-26T03:38:59.541713Z","end":"2026-06-26T03:39:00.791054Z","steps":["trace[841741953] 'agreement among raft nodes before linearized reading' (duration: 1.249215556s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:00.791119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:59.541687Z","time spent":"1.24942316s","remote":"127.0.0.1:39858","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-26T03:39:00.791123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.175685ms","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-26T03:39:00.791168Z","caller":"traceutil/trace.go:171","msg":"trace[1791689558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:444; }","duration":"414.253096ms","start":"2026-06-26T03:39:00.376902Z","end":"2026-06-26T03:39:00.791155Z","steps":["trace[1791689558] 'agreement among raft nodes before linearized reading' (duration: 414.168054ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:00.79121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:00.376873Z","time spent":"414.330268ms","remote":"127.0.0.1:39858","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-26T03:39:00.791398Z","caller":"traceutil/trace.go:171","msg":"trace[1586222599] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"1.287392018s","start":"2026-06-26T03:38:59.503988Z","end":"2026-06-26T03:39:00.79138Z","steps":["trace[1586222599] 'process raft request' (duration: 1.286121763s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:00.79151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:59.503964Z","time spent":"1.287484281s","remote":"127.0.0.1:40086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:39:00.790693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:59.503142Z","time spent":"1.2874606s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:00.792053Z","caller":"traceutil/trace.go:171","msg":"trace[983339747] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"1.287020472s","start":"2026-06-26T03:38:59.505017Z","end":"2026-06-26T03:39:00.792038Z","steps":["trace[983339747] 'process raft request' (duration: 1.285172666s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:00.79211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:38:59.505011Z","time spent":"1.287071823s","remote":"127.0.0.1:40086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:07.983674Z","caller":"traceutil/trace.go:171","msg":"trace[2021372512] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"159.840882ms","start":"2026-06-26T03:39:07.823793Z","end":"2026-06-26T03:39:07.983634Z","steps":["trace[2021372512] 'process raft request' (duration: 159.598768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.355006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922415753,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T03:39:13.434049Z","caller":"traceutil/trace.go:171","msg":"trace[1535330930] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"651.361117ms","start":"2026-06-26T03:39:12.782659Z","end":"2026-06-26T03:39:13.43402Z","steps":["trace[1535330930] 'process raft request' (duration: 651.202243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.434208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.78263Z","time spent":"651.502189ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.456595Z","caller":"traceutil/trace.go:171","msg":"trace[2067374126] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"663.589544ms","start":"2026-06-26T03:39:12.792947Z","end":"2026-06-26T03:39:13.456536Z","steps":["trace[2067374126] 'process raft request' (duration: 663.3729ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:13.456666Z","caller":"traceutil/trace.go:171","msg":"trace[1904069052] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"663.007763ms","start":"2026-06-26T03:39:12.793636Z","end":"2026-06-26T03:39:13.456644Z","steps":["trace[1904069052] 'process raft request' (duration: 662.82152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.456829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.792924Z","time spent":"663.793387ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.456867Z","caller":"traceutil/trace.go:171","msg":"trace[1097850108] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"650.629172ms","start":"2026-06-26T03:39:12.806118Z","end":"2026-06-26T03:39:13.456747Z","steps":["trace[1097850108] 'process raft request' (duration: 650.446959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.456761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.793626Z","time spent":"663.094254ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.457063Z","caller":"traceutil/trace.go:171","msg":"trace[655820715] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"648.619252ms","start":"2026-06-26T03:39:12.808424Z","end":"2026-06-26T03:39:13.457043Z","steps":["trace[655820715] 'process raft request' (duration: 648.289105ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:13.45701Z","caller":"traceutil/trace.go:171","msg":"trace[1343049303] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"654.648111ms","start":"2026-06-26T03:39:12.802131Z","end":"2026-06-26T03:39:13.456779Z","steps":["trace[1343049303] 'process raft request' (duration: 654.382116ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.457156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.806096Z","time spent":"650.925497ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.45718Z","caller":"traceutil/trace.go:171","msg":"trace[2106286353] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"640.729238ms","start":"2026-06-26T03:39:12.816434Z","end":"2026-06-26T03:39:13.457163Z","steps":["trace[2106286353] 'process raft request' (duration: 640.33376ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:13.457226Z","caller":"traceutil/trace.go:171","msg":"trace[951976990] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"648.919158ms","start":"2026-06-26T03:39:12.808292Z","end":"2026-06-26T03:39:13.457211Z","steps":["trace[951976990] 'process raft request' (duration: 648.364847ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.457257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.816416Z","time spent":"640.79875ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:13.457171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.807956Z","time spent":"649.168893ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:13.457314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.808275Z","time spent":"649.004009ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.457426Z","caller":"traceutil/trace.go:171","msg":"trace[1051855914] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:545; }","duration":"603.156076ms","start":"2026-06-26T03:39:12.854248Z","end":"2026-06-26T03:39:13.457404Z","steps":["trace[1051855914] 'read index received' (duration: 579.614558ms)","trace[1051855914] 'applied index is now lower than readState.Index' (duration: 23.540308ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:13.457478Z","caller":"traceutil/trace.go:171","msg":"trace[1794900676] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"476.461069ms","start":"2026-06-26T03:39:12.981Z","end":"2026-06-26T03:39:13.457461Z","steps":["trace[1794900676] 'process raft request' (duration: 476.144543ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:13.45748Z","caller":"traceutil/trace.go:171","msg":"trace[492801651] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"623.067045ms","start":"2026-06-26T03:39:12.834369Z","end":"2026-06-26T03:39:13.457436Z","steps":["trace[492801651] 'process raft request' (duration: 622.617076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.45745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.802107Z","time spent":"655.15047ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.457496Z","caller":"traceutil/trace.go:171","msg":"trace[1529446859] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"638.399703ms","start":"2026-06-26T03:39:12.81905Z","end":"2026-06-26T03:39:13.45745Z","steps":["trace[1529446859] 'process raft request' (duration: 637.851993ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.457594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.980965Z","time spent":"476.591592ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:13.457628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.37344ms","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-26T03:39:13.457636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.819034Z","time spent":"638.552667ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-26T03:39:13.457671Z","caller":"traceutil/trace.go:171","msg":"trace[888198313] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"603.474663ms","start":"2026-06-26T03:39:12.854183Z","end":"2026-06-26T03:39:13.457658Z","steps":["trace[888198313] 'agreement among raft nodes before linearized reading' (duration: 603.31779ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.457628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.834333Z","time spent":"623.259828ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:13.457729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.854158Z","time spent":"603.555665ms","remote":"127.0.0.1:39858","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-26T03:39:13.457613Z","caller":"traceutil/trace.go:171","msg":"trace[352778449] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"650.942007ms","start":"2026-06-26T03:39:12.806242Z","end":"2026-06-26T03:39:13.457184Z","steps":["trace[352778449] 'process raft request' (duration: 650.372016ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.458251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.806223Z","time spent":"651.872666ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:13.473872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.051156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:39:13.473949Z","caller":"traceutil/trace.go:171","msg":"trace[162591474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"619.202339ms","start":"2026-06-26T03:39:12.854734Z","end":"2026-06-26T03:39:13.473936Z","steps":["trace[162591474] 'agreement among raft nodes before linearized reading' (duration: 619.042666ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.473991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.854702Z","time spent":"619.28337ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:39:13.474108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.82123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-26T03:39:13.474149Z","caller":"traceutil/trace.go:171","msg":"trace[1424484459] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"278.910191ms","start":"2026-06-26T03:39:13.195233Z","end":"2026-06-26T03:39:13.474143Z","steps":["trace[1424484459] 'agreement among raft nodes before linearized reading' (duration: 278.85164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.474248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.731778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:13.474289Z","caller":"traceutil/trace.go:171","msg":"trace[1422505682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"335.81183ms","start":"2026-06-26T03:39:13.138471Z","end":"2026-06-26T03:39:13.474283Z","steps":["trace[1422505682] 'agreement among raft nodes before linearized reading' (duration: 335.758789ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.474323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:13.138443Z","time spent":"335.87322ms","remote":"127.0.0.1:39600","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-26T03:39:13.474431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.222669ms","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-26T03:39:13.474471Z","caller":"traceutil/trace.go:171","msg":"trace[947699092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"619.307511ms","start":"2026-06-26T03:39:12.855158Z","end":"2026-06-26T03:39:13.474466Z","steps":["trace[947699092] 'agreement among raft nodes before linearized reading' (duration: 619.25233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:13.474499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:12.855144Z","time spent":"619.350353ms","remote":"127.0.0.1:39858","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-26T03:39:19.647096Z","caller":"traceutil/trace.go:171","msg":"trace[1758615123] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"128.86983ms","start":"2026-06-26T03:39:19.518196Z","end":"2026-06-26T03:39:19.647066Z","steps":["trace[1758615123] 'read index received' (duration: 128.679546ms)","trace[1758615123] 'applied index is now lower than readState.Index' (duration: 189.174µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:19.647198Z","caller":"traceutil/trace.go:171","msg":"trace[392255897] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"132.201824ms","start":"2026-06-26T03:39:19.51493Z","end":"2026-06-26T03:39:19.647131Z","steps":["trace[392255897] 'process raft request' (duration: 131.97805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:19.64746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.235786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:39:19.647549Z","caller":"traceutil/trace.go:171","msg":"trace[736432917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"129.349658ms","start":"2026-06-26T03:39:19.51818Z","end":"2026-06-26T03:39:19.647529Z","steps":["trace[736432917] 'agreement among raft nodes before linearized reading' (duration: 129.092983ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:20.148745Z","caller":"traceutil/trace.go:171","msg":"trace[1010993923] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"501.464104ms","start":"2026-06-26T03:39:19.647253Z","end":"2026-06-26T03:39:20.148718Z","steps":["trace[1010993923] 'read index received' (duration: 499.209141ms)","trace[1010993923] 'applied index is now lower than readState.Index' (duration: 2.253843ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:39:20.148925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.672865ms","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-26T03:39:20.148969Z","caller":"traceutil/trace.go:171","msg":"trace[2009735833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"621.764117ms","start":"2026-06-26T03:39:19.52719Z","end":"2026-06-26T03:39:20.148954Z","steps":["trace[2009735833] 'agreement among raft nodes before linearized reading' (duration: 621.664044ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:20.148927Z","caller":"traceutil/trace.go:171","msg":"trace[1707944268] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"587.851476ms","start":"2026-06-26T03:39:19.561018Z","end":"2026-06-26T03:39:20.148869Z","steps":["trace[1707944268] 'process raft request' (duration: 585.98654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:20.149004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:19.527175Z","time spent":"621.820339ms","remote":"127.0.0.1:39858","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-26T03:39:20.14911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.618695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vl48k\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-26T03:39:20.149173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:19.560968Z","time spent":"588.07431ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:20.149217Z","caller":"traceutil/trace.go:171","msg":"trace[527861085] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vl48k; range_end:; response_count:1; response_revision:624; }","duration":"524.797399ms","start":"2026-06-26T03:39:19.624392Z","end":"2026-06-26T03:39:20.149189Z","steps":["trace[527861085] 'agreement among raft nodes before linearized reading' (duration: 524.41142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:20.149279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:19.624378Z","time spent":"524.89069ms","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-vl48k\" "} {"level":"warn","ts":"2026-06-26T03:39:20.149343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.9933ms","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-26T03:39:20.149504Z","caller":"traceutil/trace.go:171","msg":"trace[405023263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"624.231255ms","start":"2026-06-26T03:39:19.525238Z","end":"2026-06-26T03:39:20.149469Z","steps":["trace[405023263] 'agreement among raft nodes before linearized reading' (duration: 623.814217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:20.149584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:19.525218Z","time spent":"624.353717ms","remote":"127.0.0.1:39858","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-26T03:39:20.649015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922415982,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T03:39:20.725624Z","caller":"traceutil/trace.go:171","msg":"trace[258723546] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.073097476s","start":"2026-06-26T03:39:19.652482Z","end":"2026-06-26T03:39:20.72558Z","steps":["trace[258723546] 'process raft request' (duration: 1.072837581s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:20.725841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:19.65245Z","time spent":"1.073272549s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:21.319363Z","caller":"traceutil/trace.go:171","msg":"trace[147919904] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.160909057s","start":"2026-06-26T03:39:20.158418Z","end":"2026-06-26T03:39:21.319327Z","steps":["trace[147919904] 'process raft request' (duration: 1.119817587s)","trace[147919904] 'compare' (duration: 40.823475ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:39:21.31957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:20.158397Z","time spent":"1.161064441s","remote":"127.0.0.1:39858","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-26T03:39:21.319663Z","caller":"traceutil/trace.go:171","msg":"trace[105468337] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.158263956s","start":"2026-06-26T03:39:20.161356Z","end":"2026-06-26T03:39:21.31962Z","steps":["trace[105468337] 'process raft request' (duration: 1.157915469s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:21.319727Z","caller":"traceutil/trace.go:171","msg":"trace[1417961744] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"1.138576962s","start":"2026-06-26T03:39:20.181137Z","end":"2026-06-26T03:39:21.319714Z","steps":["trace[1417961744] 'process raft request' (duration: 1.138388009s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:21.319787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:20.181116Z","time spent":"1.138641243s","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:39:21.319873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:20.16135Z","time spent":"1.158418009s","remote":"127.0.0.1:39858","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-26T03:39:21.319866Z","caller":"traceutil/trace.go:171","msg":"trace[824535484] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:651; }","duration":"1.171020784s","start":"2026-06-26T03:39:20.148811Z","end":"2026-06-26T03:39:21.319832Z","steps":["trace[824535484] 'read index received' (duration: 576.525778ms)","trace[824535484] 'applied index is now lower than readState.Index' (duration: 594.492956ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:21.319913Z","caller":"traceutil/trace.go:171","msg":"trace[1380595839] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"1.096929892s","start":"2026-06-26T03:39:20.222948Z","end":"2026-06-26T03:39:21.319878Z","steps":["trace[1380595839] 'process raft request' (duration: 1.096710468s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:21.320008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181176252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:21.320034Z","caller":"traceutil/trace.go:171","msg":"trace[467616908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:629; }","duration":"1.181263374s","start":"2026-06-26T03:39:20.138763Z","end":"2026-06-26T03:39:21.320026Z","steps":["trace[467616908] 'agreement among raft nodes before linearized reading' (duration: 1.181169292s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:21.320057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:20.138736Z","time spent":"1.181316284s","remote":"127.0.0.1:39614","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-26T03:39:21.32008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:20.222927Z","time spent":"1.097062104s","remote":"127.0.0.1:39806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:39:22.153702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922415991,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T03:39:22.341286Z","caller":"traceutil/trace.go:171","msg":"trace[1915092565] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"939.440044ms","start":"2026-06-26T03:39:21.401784Z","end":"2026-06-26T03:39:22.341224Z","steps":["trace[1915092565] 'process raft request' (duration: 938.591958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:22.34172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:21.401741Z","time spent":"939.634718ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:39:23.247157Z","caller":"traceutil/trace.go:171","msg":"trace[2047693600] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"1.593915601s","start":"2026-06-26T03:39:21.653209Z","end":"2026-06-26T03:39:23.247125Z","steps":["trace[2047693600] 'read index received' (duration: 687.488278ms)","trace[2047693600] 'applied index is now lower than readState.Index' (duration: 906.426033ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:23.247309Z","caller":"traceutil/trace.go:171","msg":"trace[303690262] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.507827545s","start":"2026-06-26T03:39:21.739429Z","end":"2026-06-26T03:39:23.247257Z","steps":["trace[303690262] 'process raft request' (duration: 1.506917808s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:23.247473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.95729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:39:23.247533Z","caller":"traceutil/trace.go:171","msg":"trace[1461353577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"517.084062ms","start":"2026-06-26T03:39:22.730431Z","end":"2026-06-26T03:39:23.247515Z","steps":["trace[1461353577] 'agreement among raft nodes before linearized reading' (duration: 516.883797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:23.24757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:21.739391Z","time spent":"1.508022519s","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:39:23.247593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594382041s","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-26T03:39:23.247709Z","caller":"traceutil/trace.go:171","msg":"trace[1945634434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"1.594572624s","start":"2026-06-26T03:39:21.653108Z","end":"2026-06-26T03:39:23.24768Z","steps":["trace[1945634434] 'agreement among raft nodes before linearized reading' (duration: 1.594204466s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:23.247766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:21.653074Z","time spent":"1.594681057s","remote":"127.0.0.1:39858","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-26T03:39:23.24776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.907252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:23.247863Z","caller":"traceutil/trace.go:171","msg":"trace[1832207095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"109.087475ms","start":"2026-06-26T03:39:23.138745Z","end":"2026-06-26T03:39:23.247832Z","steps":["trace[1832207095] 'agreement among raft nodes before linearized reading' (duration: 108.639356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:23.247586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:22.730405Z","time spent":"517.170453ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-26T03:39:23.856606Z","caller":"traceutil/trace.go:171","msg":"trace[1997954547] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"100.64135ms","start":"2026-06-26T03:39:23.755932Z","end":"2026-06-26T03:39:23.856573Z","steps":["trace[1997954547] 'process raft request' (duration: 99.899445ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:25.09038Z","caller":"traceutil/trace.go:171","msg":"trace[1669726089] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"294.698939ms","start":"2026-06-26T03:39:24.795643Z","end":"2026-06-26T03:39:25.090342Z","steps":["trace[1669726089] 'process raft request' (duration: 294.484205ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:25.100512Z","caller":"traceutil/trace.go:171","msg":"trace[610886703] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"223.323369ms","start":"2026-06-26T03:39:24.877148Z","end":"2026-06-26T03:39:25.100471Z","steps":["trace[610886703] 'process raft request' (duration: 222.677167ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:25.100734Z","caller":"traceutil/trace.go:171","msg":"trace[807002055] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"104.899573ms","start":"2026-06-26T03:39:24.995814Z","end":"2026-06-26T03:39:25.100713Z","steps":["trace[807002055] 'process raft request' (duration: 104.598407ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:28.054439Z","caller":"traceutil/trace.go:171","msg":"trace[580203851] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"241.025164ms","start":"2026-06-26T03:39:27.813377Z","end":"2026-06-26T03:39:28.054402Z","steps":["trace[580203851] 'process raft request' (duration: 240.430832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:37.919957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.482809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:37.920094Z","caller":"traceutil/trace.go:171","msg":"trace[2113644866] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:795; }","duration":"152.680073ms","start":"2026-06-26T03:39:37.767368Z","end":"2026-06-26T03:39:37.920048Z","steps":["trace[2113644866] 'count revisions from in-memory index tree' (duration: 152.382777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:37.920506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.914655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2sxcz\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-26T03:39:37.920615Z","caller":"traceutil/trace.go:171","msg":"trace[2081545355] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2sxcz; range_end:; response_count:1; response_revision:795; }","duration":"178.077297ms","start":"2026-06-26T03:39:37.742512Z","end":"2026-06-26T03:39:37.92059Z","steps":["trace[2081545355] 'range keys from in-memory index tree' (duration: 177.69515ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:39:38.496207Z","caller":"traceutil/trace.go:171","msg":"trace[610899891] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"359.663153ms","start":"2026-06-26T03:39:38.136516Z","end":"2026-06-26T03:39:38.49618Z","steps":["trace[610899891] 'read index received' (duration: 359.362847ms)","trace[610899891] 'applied index is now lower than readState.Index' (duration: 299.116µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:39:38.496288Z","caller":"traceutil/trace.go:171","msg":"trace[69728949] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"567.276167ms","start":"2026-06-26T03:39:37.928964Z","end":"2026-06-26T03:39:38.49624Z","steps":["trace[69728949] 'process raft request' (duration: 567.025292ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:38.496411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.883069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:39:38.496474Z","caller":"traceutil/trace.go:171","msg":"trace[604727659] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:796; }","duration":"360.031842ms","start":"2026-06-26T03:39:38.136425Z","end":"2026-06-26T03:39:38.496457Z","steps":["trace[604727659] 'agreement among raft nodes before linearized reading' (duration: 359.91256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:38.49651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:37.928933Z","time spent":"567.456562ms","remote":"127.0.0.1:39784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:39:38.496529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:38.136396Z","time spent":"360.120304ms","remote":"127.0.0.1:39830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":1,"response size":30,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:39:38.496741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.547742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:39:38.496806Z","caller":"traceutil/trace.go:171","msg":"trace[1320366061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"358.631565ms","start":"2026-06-26T03:39:38.138158Z","end":"2026-06-26T03:39:38.496789Z","steps":["trace[1320366061] 'agreement among raft nodes before linearized reading' (duration: 358.534073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:38.49699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.4308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:39:38.497034Z","caller":"traceutil/trace.go:171","msg":"trace[885498253] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:796; }","duration":"245.476781ms","start":"2026-06-26T03:39:38.251547Z","end":"2026-06-26T03:39:38.497024Z","steps":["trace[885498253] 'agreement among raft nodes before linearized reading' (duration: 245.40629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:39:38.497462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:39:38.138148Z","time spent":"358.693006ms","remote":"127.0.0.1:39600","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-26T03:40:13.158949Z","caller":"traceutil/trace.go:171","msg":"trace[1728612153] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"310.941817ms","start":"2026-06-26T03:40:12.847974Z","end":"2026-06-26T03:40:13.158916Z","steps":["trace[1728612153] 'process raft request' (duration: 310.732083ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:13.159139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:12.847947Z","time spent":"311.08685ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:13.660859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922416794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:40:14.214886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.997187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:40:14.215073Z","caller":"traceutil/trace.go:171","msg":"trace[100160200] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"410.640857ms","start":"2026-06-26T03:40:13.804411Z","end":"2026-06-26T03:40:14.215052Z","steps":["trace[100160200] 'process raft request' (duration: 410.582905ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.215137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.804386Z","time spent":"410.720449ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:40:14.215461Z","caller":"traceutil/trace.go:171","msg":"trace[1648669185] linearizableReadLoop","detail":"{readStateIndex:1054; appliedIndex:1053; }","duration":"1.05555036s","start":"2026-06-26T03:40:13.159856Z","end":"2026-06-26T03:40:14.215406Z","steps":["trace[1648669185] 'read index received' (duration: 597.881519ms)","trace[1648669185] 'applied index is now lower than readState.Index' (duration: 457.666141ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:14.215487Z","caller":"traceutil/trace.go:171","msg":"trace[919170748] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"1.069643043s","start":"2026-06-26T03:40:13.145806Z","end":"2026-06-26T03:40:14.215449Z","steps":["trace[919170748] 'process raft request' (duration: 611.969692ms)","trace[919170748] 'compare' (duration: 456.59699ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:40:14.215577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055713283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:14.215618Z","caller":"traceutil/trace.go:171","msg":"trace[661206277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"1.055770534s","start":"2026-06-26T03:40:13.159836Z","end":"2026-06-26T03:40:14.215606Z","steps":["trace[661206277] 'agreement among raft nodes before linearized reading' (duration: 1.055703563s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.215654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.159828Z","time spent":"1.055819865s","remote":"127.0.0.1:39614","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-26T03:40:14.215666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.145787Z","time spent":"1.069790036s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:14.581923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.962344ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-26T03:40:14.582102Z","caller":"traceutil/trace.go:171","msg":"trace[609751967] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"366.56637ms","start":"2026-06-26T03:40:14.21551Z","end":"2026-06-26T03:40:14.582077Z","steps":["trace[609751967] 'read index received' (duration: 31.333829ms)","trace[609751967] 'applied index is now lower than readState.Index' (duration: 335.230351ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:40:14.58219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.263147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:14.582292Z","caller":"traceutil/trace.go:171","msg":"trace[595378102] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1015; }","duration":"741.451401ms","start":"2026-06-26T03:40:13.840813Z","end":"2026-06-26T03:40:14.582265Z","steps":["trace[595378102] 'agreement among raft nodes before linearized reading' (duration: 741.267438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.582295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.91627ms","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-26T03:40:14.582338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.840785Z","time spent":"741.543512ms","remote":"127.0.0.1:39762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:40:14.582378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057311034s","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-26T03:40:14.582385Z","caller":"traceutil/trace.go:171","msg":"trace[1630064254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"995.035763ms","start":"2026-06-26T03:40:13.587325Z","end":"2026-06-26T03:40:14.582361Z","steps":["trace[1630064254] 'agreement among raft nodes before linearized reading' (duration: 994.782068ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:14.582413Z","caller":"traceutil/trace.go:171","msg":"trace[1694997850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"1.057395945s","start":"2026-06-26T03:40:13.525006Z","end":"2026-06-26T03:40:14.582402Z","steps":["trace[1694997850] 'agreement among raft nodes before linearized reading' (duration: 1.057187501s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.582434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.587314Z","time spent":"995.110914ms","remote":"127.0.0.1:39858","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-26T03:40:14.582455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.52498Z","time spent":"1.057465467s","remote":"127.0.0.1:39858","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-26T03:40:14.582631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058361924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:40:14.582664Z","caller":"traceutil/trace.go:171","msg":"trace[569006612] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"1.058438606s","start":"2026-06-26T03:40:13.524218Z","end":"2026-06-26T03:40:14.582656Z","steps":["trace[569006612] 'agreement among raft nodes before linearized reading' (duration: 1.058357544s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.58269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.52418Z","time spent":"1.058503807s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:40:14.582702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"989.051775ms","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-26T03:40:14.582738Z","caller":"traceutil/trace.go:171","msg":"trace[1558169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"989.131637ms","start":"2026-06-26T03:40:13.593594Z","end":"2026-06-26T03:40:14.582726Z","steps":["trace[1558169] 'agreement among raft nodes before linearized reading' (duration: 989.029285ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.58278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:13.593574Z","time spent":"989.199179ms","remote":"127.0.0.1:39858","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-26T03:40:14.58293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.704847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:14.582969Z","caller":"traceutil/trace.go:171","msg":"trace[1329996808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1015; }","duration":"357.778997ms","start":"2026-06-26T03:40:14.225181Z","end":"2026-06-26T03:40:14.58296Z","steps":["trace[1329996808] 'agreement among raft nodes before linearized reading' (duration: 357.721086ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:14.583297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:14.225166Z","time spent":"358.120794ms","remote":"127.0.0.1:39600","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-26T03:40:39.075818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922416994,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-26T03:40:39.276158Z","caller":"traceutil/trace.go:171","msg":"trace[1721801041] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1137; }","duration":"701.33997ms","start":"2026-06-26T03:40:38.574776Z","end":"2026-06-26T03:40:39.276116Z","steps":["trace[1721801041] 'read index received' (duration: 700.885811ms)","trace[1721801041] 'applied index is now lower than readState.Index' (duration: 452.469µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:39.276207Z","caller":"traceutil/trace.go:171","msg":"trace[833346529] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"973.75188ms","start":"2026-06-26T03:40:38.302426Z","end":"2026-06-26T03:40:39.276178Z","steps":["trace[833346529] 'process raft request' (duration: 973.363831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.276383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"701.571545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:40:39.276436Z","caller":"traceutil/trace.go:171","msg":"trace[709632068] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1093; }","duration":"701.719748ms","start":"2026-06-26T03:40:38.574702Z","end":"2026-06-26T03:40:39.276421Z","steps":["trace[709632068] 'agreement among raft nodes before linearized reading' (duration: 701.565795ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.276488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.574669Z","time spent":"701.805669ms","remote":"127.0.0.1:39660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:40:39.276527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.194429ms","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-26T03:40:39.276512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.302405Z","time spent":"973.980813ms","remote":"127.0.0.1:39858","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-26T03:40:39.276557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.569499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.24\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-26T03:40:39.276579Z","caller":"traceutil/trace.go:171","msg":"trace[1749855827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1093; }","duration":"555.25483ms","start":"2026-06-26T03:40:38.721307Z","end":"2026-06-26T03:40:39.276562Z","steps":["trace[1749855827] 'agreement among raft nodes before linearized reading' (duration: 555.066406ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.276613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.721296Z","time spent":"555.312331ms","remote":"127.0.0.1:39858","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-26T03:40:39.276661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.095565ms","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-26T03:40:39.276744Z","caller":"traceutil/trace.go:171","msg":"trace[1903249063] range","detail":"{range_begin:/registry/masterleases/199.19.213.24; range_end:; response_count:1; response_revision:1093; }","duration":"638.801344ms","start":"2026-06-26T03:40:38.637923Z","end":"2026-06-26T03:40:39.276725Z","steps":["trace[1903249063] 'agreement among raft nodes before linearized reading' (duration: 638.443377ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:39.276776Z","caller":"traceutil/trace.go:171","msg":"trace[2010414538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1093; }","duration":"558.291948ms","start":"2026-06-26T03:40:38.718461Z","end":"2026-06-26T03:40:39.276753Z","steps":["trace[2010414538] 'agreement among raft nodes before linearized reading' (duration: 557.979672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.276823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.637897Z","time spent":"638.907376ms","remote":"127.0.0.1:39652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.24\" "} {"level":"warn","ts":"2026-06-26T03:40:39.276839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.718419Z","time spent":"558.410481ms","remote":"127.0.0.1:39858","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-26T03:40:39.276809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.706067ms","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-26T03:40:39.276729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.96035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:40:39.276934Z","caller":"traceutil/trace.go:171","msg":"trace[782714462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1093; }","duration":"557.85448ms","start":"2026-06-26T03:40:38.719037Z","end":"2026-06-26T03:40:39.276891Z","steps":["trace[782714462] 'agreement among raft nodes before linearized reading' (duration: 557.535314ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:39.276969Z","caller":"traceutil/trace.go:171","msg":"trace[1144269640] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"562.229024ms","start":"2026-06-26T03:40:38.714729Z","end":"2026-06-26T03:40:39.276958Z","steps":["trace[1144269640] 'agreement among raft nodes before linearized reading' (duration: 561.942109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.276985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.719023Z","time spent":"557.952081ms","remote":"127.0.0.1:39858","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-26T03:40:39.276999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:38.714711Z","time spent":"562.280126ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:40:39.608096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.277746Z","time spent":"330.342375ms","remote":"127.0.0.1:39652","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-26T03:40:39.627913Z","caller":"traceutil/trace.go:171","msg":"trace[1306656389] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"345.594512ms","start":"2026-06-26T03:40:39.282298Z","end":"2026-06-26T03:40:39.627893Z","steps":["trace[1306656389] 'process raft request' (duration: 345.537581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.62806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.282291Z","time spent":"345.690533ms","remote":"127.0.0.1:39858","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-26T03:40:39.628035Z","caller":"traceutil/trace.go:171","msg":"trace[2076386730] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"345.708274ms","start":"2026-06-26T03:40:39.282195Z","end":"2026-06-26T03:40:39.627903Z","steps":["trace[2076386730] 'process raft request' (duration: 345.479ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:39.628136Z","caller":"traceutil/trace.go:171","msg":"trace[932063363] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"345.643713ms","start":"2026-06-26T03:40:39.282458Z","end":"2026-06-26T03:40:39.628102Z","steps":["trace[932063363] 'process raft request' (duration: 345.405598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.628202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.282448Z","time spent":"345.719574ms","remote":"127.0.0.1:39858","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-26T03:40:39.628232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.282167Z","time spent":"345.954988ms","remote":"127.0.0.1:39858","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-26T03:40:39.628287Z","caller":"traceutil/trace.go:171","msg":"trace[1653556973] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1138; }","duration":"335.651868ms","start":"2026-06-26T03:40:39.292624Z","end":"2026-06-26T03:40:39.628275Z","steps":["trace[1653556973] 'read index received' (duration: 284.950212ms)","trace[1653556973] 'applied index is now lower than readState.Index' (duration: 50.700366ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:39.628366Z","caller":"traceutil/trace.go:171","msg":"trace[431199952] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"345.763224ms","start":"2026-06-26T03:40:39.282594Z","end":"2026-06-26T03:40:39.628358Z","steps":["trace[431199952] 'process raft request' (duration: 345.428258ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.628469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.282587Z","time spent":"345.859536ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:39.628404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.77488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-26T03:40:39.628539Z","caller":"traceutil/trace.go:171","msg":"trace[658350784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"335.937604ms","start":"2026-06-26T03:40:39.292594Z","end":"2026-06-26T03:40:39.628532Z","steps":["trace[658350784] 'agreement among raft nodes before linearized reading' (duration: 335.713459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:39.628571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:39.292584Z","time spent":"335.971964ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-06-26T03:40:41.062816Z","caller":"traceutil/trace.go:171","msg":"trace[132399989] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"343.499961ms","start":"2026-06-26T03:40:40.719283Z","end":"2026-06-26T03:40:41.062783Z","steps":["trace[132399989] 'read index received' (duration: 343.163964ms)","trace[132399989] 'applied index is now lower than readState.Index' (duration: 334.777µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:41.062938Z","caller":"traceutil/trace.go:171","msg":"trace[1396790708] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"432.967532ms","start":"2026-06-26T03:40:40.629948Z","end":"2026-06-26T03:40:41.062915Z","steps":["trace[1396790708] 'process raft request' (duration: 432.632124ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:41.062995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.675205ms","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":"info","ts":"2026-06-26T03:40:41.063042Z","caller":"traceutil/trace.go:171","msg":"trace[796115191] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1100; }","duration":"343.801078ms","start":"2026-06-26T03:40:40.719226Z","end":"2026-06-26T03:40:41.063027Z","steps":["trace[796115191] 'agreement among raft nodes before linearized reading' (duration: 343.685886ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:41.063089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:40.719201Z","time spent":"343.876899ms","remote":"127.0.0.1:58962","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-26T03:40:41.063101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:40.629909Z","time spent":"433.086704ms","remote":"127.0.0.1:39858","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-26T03:40:42.071301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.835719ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:40:42.071414Z","caller":"traceutil/trace.go:171","msg":"trace[2043328337] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1146; }","duration":"439.862265ms","start":"2026-06-26T03:40:41.631537Z","end":"2026-06-26T03:40:42.071399Z","steps":["trace[2043328337] 'read index received' (duration: 280.821082ms)","trace[2043328337] 'applied index is now lower than readState.Index' (duration: 159.040193ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:40:42.071656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.896436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:40:42.071682Z","caller":"traceutil/trace.go:171","msg":"trace[1564966753] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"438.938246ms","start":"2026-06-26T03:40:41.632737Z","end":"2026-06-26T03:40:42.071675Z","steps":["trace[1564966753] 'agreement among raft nodes before linearized reading' (duration: 438.827134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.071703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.632731Z","time spent":"438.967267ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:40:42.071856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.725452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-26T03:40:42.071877Z","caller":"traceutil/trace.go:171","msg":"trace[2020420135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1101; }","duration":"366.769143ms","start":"2026-06-26T03:40:41.705103Z","end":"2026-06-26T03:40:42.071872Z","steps":["trace[2020420135] 'agreement among raft nodes before linearized reading' (duration: 366.714252ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.071894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.705092Z","time spent":"366.797474ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T03:40:42.071985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.085261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:40:42.072009Z","caller":"traceutil/trace.go:171","msg":"trace[1827872983] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1101; }","duration":"435.111691ms","start":"2026-06-26T03:40:41.63689Z","end":"2026-06-26T03:40:42.072002Z","steps":["trace[1827872983] 'agreement among raft nodes before linearized reading' (duration: 435.07411ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.072038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.636866Z","time spent":"435.166923ms","remote":"127.0.0.1:59012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:40:42.072323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.256564ms","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-26T03:40:42.072387Z","caller":"traceutil/trace.go:171","msg":"trace[315982617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1101; }","duration":"439.390786ms","start":"2026-06-26T03:40:41.632982Z","end":"2026-06-26T03:40:42.072373Z","steps":["trace[315982617] 'agreement among raft nodes before linearized reading' (duration: 439.197393ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.072419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.632951Z","time spent":"439.461117ms","remote":"127.0.0.1:39858","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-26T03:40:42.072571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.570969ms","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-26T03:40:42.072594Z","caller":"traceutil/trace.go:171","msg":"trace[197384637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1101; }","duration":"439.6326ms","start":"2026-06-26T03:40:41.632954Z","end":"2026-06-26T03:40:42.072587Z","steps":["trace[197384637] 'agreement among raft nodes before linearized reading' (duration: 439.568609ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.072611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.632924Z","time spent":"439.683082ms","remote":"127.0.0.1:39858","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-26T03:40:42.07259Z","caller":"traceutil/trace.go:171","msg":"trace[80082259] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"789.326151ms","start":"2026-06-26T03:40:41.283217Z","end":"2026-06-26T03:40:42.072544Z","steps":["trace[80082259] 'process raft request' (duration: 629.169056ms)","trace[80082259] 'compare' (duration: 158.412692ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:40:42.072797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.28319Z","time spent":"789.493825ms","remote":"127.0.0.1:39858","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-26T03:40:42.0727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.828684ms","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-26T03:40:42.072964Z","caller":"traceutil/trace.go:171","msg":"trace[1767900913] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1101; }","duration":"441.432975ms","start":"2026-06-26T03:40:41.631504Z","end":"2026-06-26T03:40:42.072937Z","steps":["trace[1767900913] 'agreement among raft nodes before linearized reading' (duration: 440.003197ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.073193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:41.631489Z","time spent":"441.68396ms","remote":"127.0.0.1:39858","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-26T03:40:42.292792Z","caller":"traceutil/trace.go:171","msg":"trace[313803873] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"214.835058ms","start":"2026-06-26T03:40:42.077921Z","end":"2026-06-26T03:40:42.292756Z","steps":["trace[313803873] 'process raft request' (duration: 159.641305ms)","trace[313803873] 'compare' (duration: 55.02938ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:42.692789Z","caller":"traceutil/trace.go:171","msg":"trace[66711930] linearizableReadLoop","detail":"{readStateIndex:1152; appliedIndex:1147; }","duration":"554.113677ms","start":"2026-06-26T03:40:42.13865Z","end":"2026-06-26T03:40:42.692763Z","steps":["trace[66711930] 'read index received' (duration: 98.912874ms)","trace[66711930] 'applied index is now lower than readState.Index' (duration: 455.199743ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:40:42.692923Z","caller":"traceutil/trace.go:171","msg":"trace[647199969] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"614.741436ms","start":"2026-06-26T03:40:42.07817Z","end":"2026-06-26T03:40:42.692911Z","steps":["trace[647199969] 'process raft request' (duration: 614.518032ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:42.692925Z","caller":"traceutil/trace.go:171","msg":"trace[582545264] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"614.731686ms","start":"2026-06-26T03:40:42.078144Z","end":"2026-06-26T03:40:42.692876Z","steps":["trace[582545264] 'process raft request' (duration: 614.443311ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.693045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.078137Z","time spent":"614.808087ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:42.693094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.450903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:42.693126Z","caller":"traceutil/trace.go:171","msg":"trace[1790941604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"554.536645ms","start":"2026-06-26T03:40:42.13858Z","end":"2026-06-26T03:40:42.693117Z","steps":["trace[1790941604] 'agreement among raft nodes before linearized reading' (duration: 554.457533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.693113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.078119Z","time spent":"614.880968ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:42.693145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.138555Z","time spent":"554.585986ms","remote":"127.0.0.1:39600","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-26T03:40:42.693161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.443297ms","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-26T03:40:42.693223Z","caller":"traceutil/trace.go:171","msg":"trace[1934432851] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1106; }","duration":"547.556339ms","start":"2026-06-26T03:40:42.145649Z","end":"2026-06-26T03:40:42.693205Z","steps":["trace[1934432851] 'agreement among raft nodes before linearized reading' (duration: 547.442357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.69327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.145628Z","time spent":"547.63162ms","remote":"127.0.0.1:59034","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-26T03:40:42.693288Z","caller":"traceutil/trace.go:171","msg":"trace[404632102] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"614.984291ms","start":"2026-06-26T03:40:42.078297Z","end":"2026-06-26T03:40:42.693282Z","steps":["trace[404632102] 'process raft request' (duration: 614.4293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.693324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.078279Z","time spent":"615.024481ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:40:42.693372Z","caller":"traceutil/trace.go:171","msg":"trace[583179096] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"615.272606ms","start":"2026-06-26T03:40:42.078094Z","end":"2026-06-26T03:40:42.693367Z","steps":["trace[583179096] 'process raft request' (duration: 606.104209ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.693395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.078057Z","time spent":"615.323727ms","remote":"127.0.0.1:39858","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-26T03:40:42.69344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.680378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:42.693453Z","caller":"traceutil/trace.go:171","msg":"trace[1359389959] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1106; }","duration":"372.735959ms","start":"2026-06-26T03:40:42.320712Z","end":"2026-06-26T03:40:42.693448Z","steps":["trace[1359389959] 'agreement among raft nodes before linearized reading' (duration: 372.713858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:42.693465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:42.320687Z","time spent":"372.7746ms","remote":"127.0.0.1:58978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:40:43.309838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.008426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:43.309928Z","caller":"traceutil/trace.go:171","msg":"trace[935211835] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1106; }","duration":"171.16397ms","start":"2026-06-26T03:40:43.138745Z","end":"2026-06-26T03:40:43.309909Z","steps":["trace[935211835] 'range keys from in-memory index tree' (duration: 170.922415ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:44.545624Z","caller":"traceutil/trace.go:171","msg":"trace[1620249032] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"466.435461ms","start":"2026-06-26T03:40:44.079162Z","end":"2026-06-26T03:40:44.545597Z","steps":["trace[1620249032] 'process raft request' (duration: 466.258068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:44.545945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.079135Z","time spent":"466.651476ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:40:45.045904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.391886ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-26T03:40:45.046447Z","caller":"traceutil/trace.go:171","msg":"trace[2003056865] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"908.439478ms","start":"2026-06-26T03:40:44.137925Z","end":"2026-06-26T03:40:45.046364Z","steps":["trace[2003056865] 'read index received' (duration: 407.492335ms)","trace[2003056865] 'applied index is now lower than readState.Index' (duration: 500.943323ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:40:45.04676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.820206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:40:45.046815Z","caller":"traceutil/trace.go:171","msg":"trace[663416895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"908.929238ms","start":"2026-06-26T03:40:44.13787Z","end":"2026-06-26T03:40:45.046799Z","steps":["trace[663416895] 'agreement among raft nodes before linearized reading' (duration: 908.781455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.046849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.137846Z","time spent":"908.995429ms","remote":"127.0.0.1:39614","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-26T03:40:45.047084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.110536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-26T03:40:45.047125Z","caller":"traceutil/trace.go:171","msg":"trace[1221668570] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1108; }","duration":"902.179536ms","start":"2026-06-26T03:40:44.144935Z","end":"2026-06-26T03:40:45.047114Z","steps":["trace[1221668570] 'agreement among raft nodes before linearized reading' (duration: 902.106695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.047722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.144924Z","time spent":"902.782318ms","remote":"127.0.0.1:39768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:40:45.047834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.546567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-26T03:40:45.047809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.295044ms","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-26T03:40:45.047907Z","caller":"traceutil/trace.go:171","msg":"trace[1151264930] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1108; }","duration":"659.66873ms","start":"2026-06-26T03:40:44.388219Z","end":"2026-06-26T03:40:45.047888Z","steps":["trace[1151264930] 'agreement among raft nodes before linearized reading' (duration: 659.536427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.04795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.388198Z","time spent":"659.744021ms","remote":"127.0.0.1:39704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-06-26T03:40:45.047943Z","caller":"traceutil/trace.go:171","msg":"trace[83928704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"350.196151ms","start":"2026-06-26T03:40:44.697715Z","end":"2026-06-26T03:40:45.047911Z","steps":["trace[83928704] 'agreement among raft nodes before linearized reading' (duration: 349.732423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.047443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.144231ms","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-26T03:40:45.048005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.697693Z","time spent":"350.301653ms","remote":"127.0.0.1:39858","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-26T03:40:45.047912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.543419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-26T03:40:45.048075Z","caller":"traceutil/trace.go:171","msg":"trace[405178688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"749.802844ms","start":"2026-06-26T03:40:44.298248Z","end":"2026-06-26T03:40:45.048051Z","steps":["trace[405178688] 'agreement among raft nodes before linearized reading' (duration: 749.078929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.047509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.811405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-26T03:40:45.048148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.298228Z","time spent":"749.906746ms","remote":"127.0.0.1:39858","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-26T03:40:45.048172Z","caller":"traceutil/trace.go:171","msg":"trace[624300957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1108; }","duration":"349.562099ms","start":"2026-06-26T03:40:44.698601Z","end":"2026-06-26T03:40:45.048164Z","steps":["trace[624300957] 'agreement among raft nodes before linearized reading' (duration: 348.731162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.048197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.69857Z","time spent":"349.618581ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:40:45.047595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.176772ms","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-26T03:40:45.048091Z","caller":"traceutil/trace.go:171","msg":"trace[1721678631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1108; }","duration":"349.772553ms","start":"2026-06-26T03:40:44.698285Z","end":"2026-06-26T03:40:45.048057Z","steps":["trace[1721678631] 'agreement among raft nodes before linearized reading' (duration: 349.356365ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:40:45.048277Z","caller":"traceutil/trace.go:171","msg":"trace[548456714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"349.922936ms","start":"2026-06-26T03:40:44.69833Z","end":"2026-06-26T03:40:45.048253Z","steps":["trace[548456714] 'agreement among raft nodes before linearized reading' (duration: 348.984957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:40:45.048327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.698324Z","time spent":"349.987377ms","remote":"127.0.0.1:39858","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-26T03:40:45.04861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:40:44.698249Z","time spent":"350.342884ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T03:41:03.230571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.998027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:03.230661Z","caller":"traceutil/trace.go:171","msg":"trace[1062716798] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1227; }","duration":"161.166751ms","start":"2026-06-26T03:41:03.069475Z","end":"2026-06-26T03:41:03.230641Z","steps":["trace[1062716798] 'count revisions from in-memory index tree' (duration: 160.874865ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:05.638507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922417298,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:41:05.92013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.304065057s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T03:41:05.920503Z","caller":"traceutil/trace.go:171","msg":"trace[1229858427] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"1.304525626s","start":"2026-06-26T03:41:04.615955Z","end":"2026-06-26T03:41:05.92048Z","steps":["trace[1229858427] 'process raft request' (duration: 1.304386083s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:05.920615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:04.615931Z","time spent":"1.304609407s","remote":"127.0.0.1:39858","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-26T03:41:06.527416Z","caller":"traceutil/trace.go:171","msg":"trace[282832390] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1285; }","duration":"1.389589018s","start":"2026-06-26T03:41:05.137798Z","end":"2026-06-26T03:41:06.527387Z","steps":["trace[282832390] 'read index received' (duration: 782.629428ms)","trace[282832390] 'applied index is now lower than readState.Index' (duration: 606.95825ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:06.527547Z","caller":"traceutil/trace.go:171","msg":"trace[125708920] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"1.12844071s","start":"2026-06-26T03:41:05.399091Z","end":"2026-06-26T03:41:06.527531Z","steps":["trace[125708920] 'process raft request' (duration: 1.109230841s)","trace[125708920] 'compare' (duration: 18.852652ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:06.527652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.399068Z","time spent":"1.128505302s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:41:06.527879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284821188s","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-26T03:41:06.528279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283215977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-26T03:41:06.52883Z","caller":"traceutil/trace.go:171","msg":"trace[1290115171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.285856449s","start":"2026-06-26T03:41:05.242943Z","end":"2026-06-26T03:41:06.5288Z","steps":["trace[1290115171] 'agreement among raft nodes before linearized reading' (duration: 1.284713927s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:06.528893Z","caller":"traceutil/trace.go:171","msg":"trace[476646281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.283858941s","start":"2026-06-26T03:41:05.245011Z","end":"2026-06-26T03:41:06.52887Z","steps":["trace[476646281] 'agreement among raft nodes before linearized reading' (duration: 1.283131736s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:06.528923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.244992Z","time spent":"1.283923641s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-26T03:41:06.528315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2833217s","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-26T03:41:06.528913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.24292Z","time spent":"1.285971691s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-26T03:41:06.528992Z","caller":"traceutil/trace.go:171","msg":"trace[1805437895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"1.284006204s","start":"2026-06-26T03:41:05.244966Z","end":"2026-06-26T03:41:06.528972Z","steps":["trace[1805437895] 'agreement among raft nodes before linearized reading' (duration: 1.283295999s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:06.52836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.28120982s","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-26T03:41:06.529065Z","caller":"traceutil/trace.go:171","msg":"trace[1082880392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"1.281934854s","start":"2026-06-26T03:41:05.247124Z","end":"2026-06-26T03:41:06.529058Z","steps":["trace[1082880392] 'agreement among raft nodes before linearized reading' (duration: 1.28118392s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:06.529059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.244952Z","time spent":"1.284088515s","remote":"127.0.0.1:39858","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-26T03:41:06.529088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.247114Z","time spent":"1.281968875s","remote":"127.0.0.1:39858","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-26T03:41:06.528353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390532907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:06.529164Z","caller":"traceutil/trace.go:171","msg":"trace[1311420206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"1.391397593s","start":"2026-06-26T03:41:05.137749Z","end":"2026-06-26T03:41:06.529147Z","steps":["trace[1311420206] 'agreement among raft nodes before linearized reading' (duration: 1.390507645s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:06.528458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283521404s","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-26T03:41:06.529269Z","caller":"traceutil/trace.go:171","msg":"trace[2061230015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"1.28435456s","start":"2026-06-26T03:41:05.244906Z","end":"2026-06-26T03:41:06.529261Z","steps":["trace[2061230015] 'agreement among raft nodes before linearized reading' (duration: 1.283520434s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:06.529307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.244879Z","time spent":"1.284420151s","remote":"127.0.0.1:39858","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-26T03:41:06.529217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:05.137729Z","time spent":"1.391473835s","remote":"127.0.0.1:39600","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-26T03:41:16.691881Z","caller":"traceutil/trace.go:171","msg":"trace[1301896720] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1322; }","duration":"112.013528ms","start":"2026-06-26T03:41:16.579847Z","end":"2026-06-26T03:41:16.69186Z","steps":["trace[1301896720] 'read index received' (duration: 111.937135ms)","trace[1301896720] 'applied index is now lower than readState.Index' (duration: 74.853µs)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:16.691976Z","caller":"traceutil/trace.go:171","msg":"trace[1266512170] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"113.408944ms","start":"2026-06-26T03:41:16.578522Z","end":"2026-06-26T03:41:16.691931Z","steps":["trace[1266512170] 'process raft request' (duration: 113.200469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:16.692328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.473057ms","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-26T03:41:16.692454Z","caller":"traceutil/trace.go:171","msg":"trace[2087591319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1268; }","duration":"112.635721ms","start":"2026-06-26T03:41:16.579803Z","end":"2026-06-26T03:41:16.692439Z","steps":["trace[2087591319] 'agreement among raft nodes before linearized reading' (duration: 112.187142ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:24.430748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.58905ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-26T03:41:24.437694Z","caller":"traceutil/trace.go:171","msg":"trace[1092062863] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"462.082811ms","start":"2026-06-26T03:41:23.975588Z","end":"2026-06-26T03:41:24.437671Z","steps":["trace[1092062863] 'process raft request' (duration: 461.920708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:24.437833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:23.97556Z","time spent":"462.198304ms","remote":"127.0.0.1:39858","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-26T03:41:26.638686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922417490,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:41:26.680625Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.05802702s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T03:41:26.681183Z","caller":"traceutil/trace.go:171","msg":"trace[2110532919] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.058670011s","start":"2026-06-26T03:41:25.622475Z","end":"2026-06-26T03:41:26.681145Z","steps":["trace[2110532919] 'process raft request' (duration: 1.058454437s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:26.681384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:25.622449Z","time spent":"1.058838565s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:41:27.249592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.409879ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:41:27.2498Z","caller":"traceutil/trace.go:171","msg":"trace[1522752337] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"176.925033ms","start":"2026-06-26T03:41:27.072859Z","end":"2026-06-26T03:41:27.249784Z","steps":["trace[1522752337] 'process raft request' (duration: 176.884502ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:27.249902Z","caller":"traceutil/trace.go:171","msg":"trace[575314737] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"806.779192ms","start":"2026-06-26T03:41:26.443086Z","end":"2026-06-26T03:41:27.249866Z","steps":["trace[575314737] 'process raft request' (duration: 321.004696ms)","trace[575314737] 'compare' (duration: 485.201255ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:27.250001Z","caller":"traceutil/trace.go:171","msg":"trace[918505830] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"479.186239ms","start":"2026-06-26T03:41:26.770783Z","end":"2026-06-26T03:41:27.249969Z","steps":["trace[918505830] 'process raft request' (duration: 478.889034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.443049Z","time spent":"806.940785ms","remote":"127.0.0.1:39858","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-26T03:41:27.250055Z","caller":"traceutil/trace.go:171","msg":"trace[1453553098] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1377; }","duration":"1.111383552s","start":"2026-06-26T03:41:26.138606Z","end":"2026-06-26T03:41:27.24999Z","steps":["trace[1453553098] 'read index received' (duration: 542.294469ms)","trace[1453553098] 'applied index is now lower than readState.Index' (duration: 569.083583ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:27.250113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.770759Z","time spent":"479.300471ms","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":785,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:41:27.250336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.099736ms","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-26T03:41:27.250382Z","caller":"traceutil/trace.go:171","msg":"trace[1464821308] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1324; }","duration":"969.198177ms","start":"2026-06-26T03:41:26.281166Z","end":"2026-06-26T03:41:27.250364Z","steps":["trace[1464821308] 'agreement among raft nodes before linearized reading' (duration: 969.088094ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.321165ms","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-26T03:41:27.250425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.281147Z","time spent":"969.265209ms","remote":"127.0.0.1:34894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-06-26T03:41:27.250445Z","caller":"traceutil/trace.go:171","msg":"trace[304783498] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1324; }","duration":"365.372377ms","start":"2026-06-26T03:41:26.885062Z","end":"2026-06-26T03:41:27.250434Z","steps":["trace[304783498] 'agreement among raft nodes before linearized reading' (duration: 365.243195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.798234ms","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-26T03:41:27.250477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.885055Z","time spent":"365.415458ms","remote":"127.0.0.1:39858","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-26T03:41:27.250501Z","caller":"traceutil/trace.go:171","msg":"trace[250724037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"390.846455ms","start":"2026-06-26T03:41:26.859641Z","end":"2026-06-26T03:41:27.250487Z","steps":["trace[250724037] 'agreement among raft nodes before linearized reading' (duration: 390.700452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.859584Z","time spent":"390.943738ms","remote":"127.0.0.1:39858","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-26T03:41:27.250554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.478436ms","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-26T03:41:27.250602Z","caller":"traceutil/trace.go:171","msg":"trace[123419614] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1324; }","duration":"101.566568ms","start":"2026-06-26T03:41:27.149027Z","end":"2026-06-26T03:41:27.250594Z","steps":["trace[123419614] 'agreement among raft nodes before linearized reading' (duration: 101.496957ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.465238ms","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-26T03:41:27.250665Z","caller":"traceutil/trace.go:171","msg":"trace[1740875158] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"365.55267ms","start":"2026-06-26T03:41:26.885098Z","end":"2026-06-26T03:41:27.250651Z","steps":["trace[1740875158] 'agreement among raft nodes before linearized reading' (duration: 365.441708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.092881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-26T03:41:27.250715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.885071Z","time spent":"365.636571ms","remote":"127.0.0.1:39858","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-26T03:41:27.250736Z","caller":"traceutil/trace.go:171","msg":"trace[123787001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1324; }","duration":"366.245124ms","start":"2026-06-26T03:41:26.884469Z","end":"2026-06-26T03:41:27.250714Z","steps":["trace[123787001] 'agreement among raft nodes before linearized reading' (duration: 365.949469ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.884447Z","time spent":"366.324595ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T03:41:27.250751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.054463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:41:27.25083Z","caller":"traceutil/trace.go:171","msg":"trace[1617620900] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"447.194016ms","start":"2026-06-26T03:41:26.803616Z","end":"2026-06-26T03:41:27.25081Z","steps":["trace[1617620900] 'agreement among raft nodes before linearized reading' (duration: 446.88586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.250884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.803595Z","time spent":"447.279648ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:41:27.250904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11229422s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:27.252632Z","caller":"traceutil/trace.go:171","msg":"trace[1757448645] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1324; }","duration":"1.114051623s","start":"2026-06-26T03:41:26.138558Z","end":"2026-06-26T03:41:27.25261Z","steps":["trace[1757448645] 'agreement among raft nodes before linearized reading' (duration: 1.111615356s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.252717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:26.138537Z","time spent":"1.114163746s","remote":"127.0.0.1:39600","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-26T03:41:27.41362Z","caller":"traceutil/trace.go:171","msg":"trace[1312566673] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"159.315795ms","start":"2026-06-26T03:41:27.254262Z","end":"2026-06-26T03:41:27.413578Z","steps":["trace[1312566673] 'process raft request' (duration: 109.825766ms)","trace[1312566673] 'compare' (duration: 49.276895ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:27.413712Z","caller":"traceutil/trace.go:171","msg":"trace[2016295591] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"159.383597ms","start":"2026-06-26T03:41:27.254295Z","end":"2026-06-26T03:41:27.413679Z","steps":["trace[2016295591] 'read index received' (duration: 109.695045ms)","trace[2016295591] 'applied index is now lower than readState.Index' (duration: 49.685912ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:27.413766Z","caller":"traceutil/trace.go:171","msg":"trace[1051668585] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"157.843946ms","start":"2026-06-26T03:41:27.255891Z","end":"2026-06-26T03:41:27.413735Z","steps":["trace[1051668585] 'process raft request' (duration: 157.719744ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:27.414039Z","caller":"traceutil/trace.go:171","msg":"trace[1077724477] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"158.099831ms","start":"2026-06-26T03:41:27.255913Z","end":"2026-06-26T03:41:27.414012Z","steps":["trace[1077724477] 'process raft request' (duration: 157.774305ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:27.414436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.112161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:27.414619Z","caller":"traceutil/trace.go:171","msg":"trace[1528320621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"160.309114ms","start":"2026-06-26T03:41:27.254259Z","end":"2026-06-26T03:41:27.414569Z","steps":["trace[1528320621] 'agreement among raft nodes before linearized reading' (duration: 159.657481ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:27.414772Z","caller":"traceutil/trace.go:171","msg":"trace[222594392] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"158.824745ms","start":"2026-06-26T03:41:27.255933Z","end":"2026-06-26T03:41:27.414758Z","steps":["trace[222594392] 'process raft request' (duration: 157.990469ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:27.414981Z","caller":"traceutil/trace.go:171","msg":"trace[1791781564] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"159.008419ms","start":"2026-06-26T03:41:27.255962Z","end":"2026-06-26T03:41:27.414971Z","steps":["trace[1791781564] 'process raft request' (duration: 158.717683ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:27.41513Z","caller":"traceutil/trace.go:171","msg":"trace[1159656851] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"159.05068ms","start":"2026-06-26T03:41:27.256068Z","end":"2026-06-26T03:41:27.415118Z","steps":["trace[1159656851] 'process raft request' (duration: 158.845346ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:28.576029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.151755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-26T03:41:28.576509Z","caller":"traceutil/trace.go:171","msg":"trace[1471244093] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1390; }","duration":"101.490656ms","start":"2026-06-26T03:41:28.474618Z","end":"2026-06-26T03:41:28.576109Z","steps":["trace[1471244093] 'read index received' (duration: 40.39µs)","trace[1471244093] 'applied index is now lower than readState.Index' (duration: 101.448436ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:28.576644Z","caller":"traceutil/trace.go:171","msg":"trace[800677632] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"262.690488ms","start":"2026-06-26T03:41:28.313905Z","end":"2026-06-26T03:41:28.576596Z","steps":["trace[800677632] 'process raft request' (duration: 57.38426ms)","trace[800677632] 'compare' (duration: 203.988382ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:28.577223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.596788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:28.57774Z","caller":"traceutil/trace.go:171","msg":"trace[742067770] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1334; }","duration":"103.140108ms","start":"2026-06-26T03:41:28.474572Z","end":"2026-06-26T03:41:28.577712Z","steps":["trace[742067770] 'agreement among raft nodes before linearized reading' (duration: 101.986466ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:28.900694Z","caller":"traceutil/trace.go:171","msg":"trace[515601512] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1392; }","duration":"312.723927ms","start":"2026-06-26T03:41:28.587936Z","end":"2026-06-26T03:41:28.90066Z","steps":["trace[515601512] 'read index received' (duration: 65.969645ms)","trace[515601512] 'applied index is now lower than readState.Index' (duration: 246.753172ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:28.900817Z","caller":"traceutil/trace.go:171","msg":"trace[1168147002] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"312.91135ms","start":"2026-06-26T03:41:28.587888Z","end":"2026-06-26T03:41:28.900799Z","steps":["trace[1168147002] 'process raft request' (duration: 312.715827ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:28.900801Z","caller":"traceutil/trace.go:171","msg":"trace[1072042876] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"312.92516ms","start":"2026-06-26T03:41:28.58783Z","end":"2026-06-26T03:41:28.900755Z","steps":["trace[1072042876] 'process raft request' (duration: 246.504537ms)","trace[1072042876] 'compare' (duration: 65.669139ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:28.900926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.587883Z","time spent":"312.965371ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:41:28.9011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.58782Z","time spent":"313.120705ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:41:28.901465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.505462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:28.901571Z","caller":"traceutil/trace.go:171","msg":"trace[1238884831] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1337; }","duration":"313.636425ms","start":"2026-06-26T03:41:28.587914Z","end":"2026-06-26T03:41:28.901551Z","steps":["trace[1238884831] 'agreement among raft nodes before linearized reading' (duration: 313.484562ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:28.901704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.587909Z","time spent":"313.780188ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-26T03:41:28.90172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.604147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.24\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-06-26T03:41:28.901762Z","caller":"traceutil/trace.go:171","msg":"trace[2045581263] range","detail":"{range_begin:/registry/masterleases/199.19.213.24; range_end:; response_count:1; response_revision:1337; }","duration":"261.685739ms","start":"2026-06-26T03:41:28.640064Z","end":"2026-06-26T03:41:28.90175Z","steps":["trace[2045581263] 'agreement among raft nodes before linearized reading' (duration: 261.395073ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.919467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.783591ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-06-26T03:41:29.919763Z","caller":"traceutil/trace.go:171","msg":"trace[2119218959] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"663.122997ms","start":"2026-06-26T03:41:29.256621Z","end":"2026-06-26T03:41:29.919744Z","steps":["trace[2119218959] 'process raft request' (duration: 663.078477ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.919863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.256589Z","time spent":"663.210288ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:41:29.920031Z","caller":"traceutil/trace.go:171","msg":"trace[1514427429] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"1.012778481s","start":"2026-06-26T03:41:28.907211Z","end":"2026-06-26T03:41:29.91999Z","steps":["trace[1514427429] 'process raft request' (duration: 1.012352853s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.920112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.907199Z","time spent":"1.012875464s","remote":"127.0.0.1:39684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":771,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:41:29.920501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.90257Z","time spent":"1.01792899s","remote":"127.0.0.1:39652","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-26T03:41:29.920492Z","caller":"traceutil/trace.go:171","msg":"trace[972016703] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.011973236s","start":"2026-06-26T03:41:28.908118Z","end":"2026-06-26T03:41:29.920091Z","steps":["trace[972016703] 'process raft request' (duration: 1.011540518s)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:29.920736Z","caller":"traceutil/trace.go:171","msg":"trace[171176309] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"1.013662708s","start":"2026-06-26T03:41:28.907042Z","end":"2026-06-26T03:41:29.920705Z","steps":["trace[171176309] 'read index received' (duration: 496.626293ms)","trace[171176309] 'applied index is now lower than readState.Index' (duration: 517.034405ms)"],"step_count":2} {"level":"warn","ts":"2026-06-26T03:41:29.920867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.908103Z","time spent":"1.012513937s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-26T03:41:29.921133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014077857s","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:510"} {"level":"info","ts":"2026-06-26T03:41:29.921196Z","caller":"traceutil/trace.go:171","msg":"trace[171755780] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1340; }","duration":"1.014173228s","start":"2026-06-26T03:41:28.907008Z","end":"2026-06-26T03:41:29.921182Z","steps":["trace[171755780] 'agreement among raft nodes before linearized reading' (duration: 1.013978184s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.921251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:28.906995Z","time spent":"1.014244609s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-26T03:41:29.92119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.961096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-26T03:41:29.921312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.970146ms","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-26T03:41:29.922155Z","caller":"traceutil/trace.go:171","msg":"trace[1653582245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1340; }","duration":"502.846962ms","start":"2026-06-26T03:41:29.419284Z","end":"2026-06-26T03:41:29.922131Z","steps":["trace[1653582245] 'agreement among raft nodes before linearized reading' (duration: 501.883924ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:41:29.92138Z","caller":"traceutil/trace.go:171","msg":"trace[637009771] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1340; }","duration":"903.19115ms","start":"2026-06-26T03:41:29.018159Z","end":"2026-06-26T03:41:29.92135Z","steps":["trace[637009771] 'agreement among raft nodes before linearized reading' (duration: 902.922675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.922313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.419264Z","time spent":"502.965055ms","remote":"127.0.0.1:39858","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-26T03:41:29.921621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.715151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-26T03:41:29.922654Z","caller":"traceutil/trace.go:171","msg":"trace[1542164594] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1340; }","duration":"503.775561ms","start":"2026-06-26T03:41:29.418857Z","end":"2026-06-26T03:41:29.922632Z","steps":["trace[1542164594] 'agreement among raft nodes before linearized reading' (duration: 502.580468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.922724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.418836Z","time spent":"503.869383ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-26T03:41:29.921688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.974556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-26T03:41:29.922888Z","caller":"traceutil/trace.go:171","msg":"trace[1605555280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1340; }","duration":"503.188549ms","start":"2026-06-26T03:41:29.419683Z","end":"2026-06-26T03:41:29.922872Z","steps":["trace[1605555280] 'agreement among raft nodes before linearized reading' (duration: 501.917375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.922887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.018143Z","time spent":"904.145529ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-26T03:41:29.922975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.419673Z","time spent":"503.26096ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-26T03:41:29.921711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.879874ms","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-26T03:41:29.92307Z","caller":"traceutil/trace.go:171","msg":"trace[1399285115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1340; }","duration":"504.267641ms","start":"2026-06-26T03:41:29.418783Z","end":"2026-06-26T03:41:29.923051Z","steps":["trace[1399285115] 'agreement among raft nodes before linearized reading' (duration: 502.863634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.923121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.418751Z","time spent":"504.356942ms","remote":"127.0.0.1:39858","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-26T03:41:29.921733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.285478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:29.923261Z","caller":"traceutil/trace.go:171","msg":"trace[218524682] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1340; }","duration":"142.90356ms","start":"2026-06-26T03:41:29.780344Z","end":"2026-06-26T03:41:29.923247Z","steps":["trace[218524682] 'agreement among raft nodes before linearized reading' (duration: 141.285808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.921811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.326352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:29.923519Z","caller":"traceutil/trace.go:171","msg":"trace[1449533883] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"784.997034ms","start":"2026-06-26T03:41:29.138418Z","end":"2026-06-26T03:41:29.923415Z","steps":["trace[1449533883] 'agreement among raft nodes before linearized reading' (duration: 783.24057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.923649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.138395Z","time spent":"785.189237ms","remote":"127.0.0.1:39600","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-26T03:41:29.921976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.598089ms","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-26T03:41:29.923752Z","caller":"traceutil/trace.go:171","msg":"trace[403133029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1340; }","duration":"504.449923ms","start":"2026-06-26T03:41:29.41929Z","end":"2026-06-26T03:41:29.92374Z","steps":["trace[403133029] 'agreement among raft nodes before linearized reading' (duration: 502.379463ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:29.924345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:29.41927Z","time spent":"505.029855ms","remote":"127.0.0.1:39858","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-26T03:41:32.638741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1789511257922417570,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-26T03:41:32.980293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.002537696s","expected-duration":"1s"} {"level":"info","ts":"2026-06-26T03:41:32.98137Z","caller":"traceutil/trace.go:171","msg":"trace[1388720590] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.024522467s","start":"2026-06-26T03:41:31.956803Z","end":"2026-06-26T03:41:32.981325Z","steps":["trace[1388720590] 'process raft request' (duration: 1.023748181s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:32.98159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:31.956792Z","time spent":"1.02465818s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:41:32.981622Z","caller":"traceutil/trace.go:171","msg":"trace[816436683] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.024618699s","start":"2026-06-26T03:41:31.956979Z","end":"2026-06-26T03:41:32.981598Z","steps":["trace[816436683] 'process raft request' (duration: 1.024297153s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:32.981753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:31.956962Z","time spent":"1.024720641s","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-26T03:41:32.981981Z","caller":"traceutil/trace.go:171","msg":"trace[1115810876] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.024137409s","start":"2026-06-26T03:41:31.957831Z","end":"2026-06-26T03:41:32.981968Z","steps":["trace[1115810876] 'process raft request' (duration: 1.02366896s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:32.982044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:31.957781Z","time spent":"1.0242195s","remote":"127.0.0.1:39858","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-26T03:41:32.982172Z","caller":"traceutil/trace.go:171","msg":"trace[2022325153] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.024043137s","start":"2026-06-26T03:41:31.95812Z","end":"2026-06-26T03:41:32.982163Z","steps":["trace[2022325153] 'process raft request' (duration: 1.023808913s)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:32.982226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:31.958109Z","time spent":"1.024090728s","remote":"127.0.0.1:39858","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-26T03:41:33.008629Z","caller":"traceutil/trace.go:171","msg":"trace[896972234] linearizableReadLoop","detail":"{readStateIndex:1432; appliedIndex:1431; }","duration":"870.262607ms","start":"2026-06-26T03:41:32.138349Z","end":"2026-06-26T03:41:33.008612Z","steps":["trace[896972234] 'read index received' (duration: 843.885322ms)","trace[896972234] 'applied index is now lower than readState.Index' (duration: 26.376465ms)"],"step_count":2} {"level":"info","ts":"2026-06-26T03:41:33.008718Z","caller":"traceutil/trace.go:171","msg":"trace[1493748108] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"955.690366ms","start":"2026-06-26T03:41:32.053016Z","end":"2026-06-26T03:41:33.008707Z","steps":["trace[1493748108] 'process raft request' (duration: 955.509983ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:33.008792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:32.052992Z","time spent":"955.749968ms","remote":"127.0.0.1:39858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-26T03:41:33.009187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.074101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:33.009261Z","caller":"traceutil/trace.go:171","msg":"trace[1231509884] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1374; }","duration":"645.233253ms","start":"2026-06-26T03:41:32.364013Z","end":"2026-06-26T03:41:33.009246Z","steps":["trace[1231509884] 'agreement among raft nodes before linearized reading' (duration: 645.103091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:33.009298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:32.363986Z","time spent":"645.304065ms","remote":"127.0.0.1:39892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-06-26T03:41:33.009954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.491652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-26T03:41:33.010013Z","caller":"traceutil/trace.go:171","msg":"trace[439882352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1374; }","duration":"871.712026ms","start":"2026-06-26T03:41:32.138284Z","end":"2026-06-26T03:41:33.009996Z","steps":["trace[439882352] 'agreement among raft nodes before linearized reading' (duration: 870.679476ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:33.010058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:32.13826Z","time spent":"871.787717ms","remote":"127.0.0.1:39600","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-26T03:41:33.010971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.857119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-06-26T03:41:33.011101Z","caller":"traceutil/trace.go:171","msg":"trace[874824590] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1374; }","duration":"837.417309ms","start":"2026-06-26T03:41:32.173654Z","end":"2026-06-26T03:41:33.011072Z","steps":["trace[874824590] 'agreement among raft nodes before linearized reading' (duration: 835.447972ms)"],"step_count":1} {"level":"warn","ts":"2026-06-26T03:41:33.011183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-26T03:41:32.173635Z","time spent":"837.523241ms","remote":"127.0.0.1:39710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":644,"request content":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-26T03:45:28.407113Z","caller":"traceutil/trace.go:171","msg":"trace[1858145951] transaction","detail":"{read_only:false; response_revision:2596; number_of_response:1; }","duration":"112.697272ms","start":"2026-06-26T03:45:28.294394Z","end":"2026-06-26T03:45:28.407091Z","steps":["trace[1858145951] 'process raft request' (duration: 112.56991ms)"],"step_count":1} {"level":"info","ts":"2026-06-26T03:45:28.407286Z","caller":"traceutil/trace.go:171","msg":"trace[145144199] transaction","detail":"{read_only:false; response_revision:2597; number_of_response:1; }","duration":"112.630621ms","start":"2026-06-26T03:45:28.294646Z","end":"2026-06-26T03:45:28.407277Z","steps":["trace[145144199] 'process raft request' (duration: 112.410757ms)"],"step_count":1}