{"level":"warn","ts":"2026-10-26T00:55:57.528837Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:55:57.528959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.105: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://162.253.55.105:2380","--initial-cluster=instance=https://162.253.55.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.105: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-10-26T00:55:57.529039Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-26T00:55:57.529057Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-10-26T00:55:57.529083Z","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-10-26T00:55:57.531601Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"]} {"level":"info","ts":"2026-10-26T00:55:57.531698Z","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://162.253.55.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.105: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-10-26T00:55:57.540116Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.148083ms"} {"level":"info","ts":"2026-10-26T00:55:57.550183Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"15a19459cc268924","cluster-id":"9aaad1c0fc15509e"} {"level":"info","ts":"2026-10-26T00:55:57.550249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=()"} {"level":"info","ts":"2026-10-26T00:55:57.550267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 0"} {"level":"info","ts":"2026-10-26T00:55:57.550287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 15a19459cc268924 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-26T00:55:57.550299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became follower at term 1"} {"level":"info","ts":"2026-10-26T00:55:57.55034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"warn","ts":"2026-10-26T00:55:57.557346Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-26T00:55:57.559031Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-26T00:55:57.560382Z","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-10-26T00:55:57.562757Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"15a19459cc268924","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-26T00:55:57.563057Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"15a19459cc268924","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-26T00:55:57.563058Z","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-10-26T00:55:57.563211Z","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-10-26T00:55:57.563227Z","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-10-26T00:55:57.56891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 switched to configuration voters=(1558690059444980004)"} {"level":"info","ts":"2026-10-26T00:55:57.569268Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","added-peer-id":"15a19459cc268924","added-peer-peer-urls":["https://162.253.55.105:2380"]} {"level":"info","ts":"2026-10-26T00:55:57.570832Z","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-10-26T00:55:57.570956Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-10-26T00:55:57.571017Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.105:2380"} {"level":"info","ts":"2026-10-26T00:55:57.571253Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"15a19459cc268924","initial-advertise-peer-urls":["https://162.253.55.105:2380"],"listen-peer-urls":["https://162.253.55.105:2380"],"advertise-client-urls":["https://162.253.55.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-26T00:55:57.571306Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-26T00:55:57.951075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 is starting a new election at term 1"} {"level":"info","ts":"2026-10-26T00:55:57.951149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-26T00:55:57.9512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgPreVoteResp from 15a19459cc268924 at term 1"} {"level":"info","ts":"2026-10-26T00:55:57.951224Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became candidate at term 2"} {"level":"info","ts":"2026-10-26T00:55:57.951306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 received MsgVoteResp from 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-10-26T00:55:57.951326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"15a19459cc268924 became leader at term 2"} {"level":"info","ts":"2026-10-26T00:55:57.95134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 15a19459cc268924 elected leader 15a19459cc268924 at term 2"} {"level":"info","ts":"2026-10-26T00:55:58.010695Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:58.01294Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"15a19459cc268924","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.105:2379]}","request-path":"/0/members/15a19459cc268924/attributes","cluster-id":"9aaad1c0fc15509e","publish-timeout":"7s"} {"level":"info","ts":"2026-10-26T00:55:58.013117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:55:58.013178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-26T00:55:58.013361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-26T00:55:58.013528Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-26T00:55:58.013748Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9aaad1c0fc15509e","local-member-id":"15a19459cc268924","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:58.013896Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:58.013937Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-26T00:55:58.015779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.105:2379"} {"level":"info","ts":"2026-10-26T00:55:58.018976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-26T00:56:00.929578Z","caller":"traceutil/trace.go:171","msg":"trace[1680097611] transaction","detail":"{read_only:false; response_revision:134; number_of_response:1; }","duration":"124.870032ms","start":"2026-10-26T00:56:00.804677Z","end":"2026-10-26T00:56:00.929547Z","steps":["trace[1680097611] 'process raft request' (duration: 60.484067ms)","trace[1680097611] 'compare' (duration: 64.16852ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:02.052685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.597435ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:02.052856Z","caller":"traceutil/trace.go:171","msg":"trace[466034086] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"183.9754ms","start":"2026-10-26T00:56:01.868856Z","end":"2026-10-26T00:56:02.052831Z","steps":["trace[466034086] 'process raft request' (duration: 30.555436ms)","trace[466034086] 'compare' (duration: 152.423781ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:02.053785Z","caller":"traceutil/trace.go:171","msg":"trace[310971790] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"140.623145ms","start":"2026-10-26T00:56:01.913137Z","end":"2026-10-26T00:56:02.05376Z","steps":["trace[310971790] 'process raft request' (duration: 140.529003ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:18.121033Z","caller":"traceutil/trace.go:171","msg":"trace[152021221] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"107.587925ms","start":"2026-10-26T00:56:18.013418Z","end":"2026-10-26T00:56:18.121006Z","steps":["trace[152021221] 'process raft request' (duration: 107.38195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:18.860364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.070995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T00:56:18.860364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.85233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:18.86049Z","caller":"traceutil/trace.go:171","msg":"trace[12390769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:426; }","duration":"571.28511ms","start":"2026-10-26T00:56:18.289178Z","end":"2026-10-26T00:56:18.860463Z","steps":["trace[12390769] 'range keys from in-memory index tree' (duration: 570.81846ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:18.86054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:18.289146Z","time spent":"571.382452ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T00:56:18.860832Z","caller":"traceutil/trace.go:171","msg":"trace[974166325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:426; }","duration":"120.371521ms","start":"2026-10-26T00:56:18.740411Z","end":"2026-10-26T00:56:18.860783Z","steps":["trace[974166325] 'range keys from in-memory index tree' (duration: 119.756708ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:19.131669Z","caller":"traceutil/trace.go:171","msg":"trace[1692409205] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"266.006909ms","start":"2026-10-26T00:56:18.865589Z","end":"2026-10-26T00:56:19.131596Z","steps":["trace[1692409205] 'process raft request' (duration: 265.795984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:19.319807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.367307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:19.320401Z","caller":"traceutil/trace.go:171","msg":"trace[676498162] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"183.283493ms","start":"2026-10-26T00:56:19.136717Z","end":"2026-10-26T00:56:19.320001Z","steps":["trace[676498162] 'process raft request' (duration: 60.033768ms)","trace[676498162] 'compare' (duration: 121.968927ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:24.459041Z","caller":"traceutil/trace.go:171","msg":"trace[1138841793] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"311.426139ms","start":"2026-10-26T00:56:24.147583Z","end":"2026-10-26T00:56:24.45901Z","steps":["trace[1138841793] 'process raft request' (duration: 311.277535ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:24.459246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:24.147563Z","time spent":"311.572122ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:56:29.935778Z","caller":"traceutil/trace.go:171","msg":"trace[708118731] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"222.198851ms","start":"2026-10-26T00:56:29.713553Z","end":"2026-10-26T00:56:29.935752Z","steps":["trace[708118731] 'process raft request' (duration: 222.063598ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:44.970995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.695435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:44.971125Z","caller":"traceutil/trace.go:171","msg":"trace[1236716001] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:552; }","duration":"142.901471ms","start":"2026-10-26T00:56:44.828194Z","end":"2026-10-26T00:56:44.971096Z","steps":["trace[1236716001] 'range keys from in-memory index tree' (duration: 142.589264ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:47.512348Z","caller":"traceutil/trace.go:171","msg":"trace[742782410] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"100.132443ms","start":"2026-10-26T00:56:47.412183Z","end":"2026-10-26T00:56:47.512316Z","steps":["trace[742782410] 'read index received' (duration: 67.780954ms)","trace[742782410] 'applied index is now lower than readState.Index' (duration: 32.349919ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:47.512676Z","caller":"traceutil/trace.go:171","msg":"trace[265658681] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"103.699142ms","start":"2026-10-26T00:56:47.408711Z","end":"2026-10-26T00:56:47.51241Z","steps":["trace[265658681] 'process raft request' (duration: 71.359244ms)","trace[265658681] 'compare' (duration: 31.953028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:47.512727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.48073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-10-26T00:56:47.512975Z","caller":"traceutil/trace.go:171","msg":"trace[239182648] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:630; }","duration":"100.743176ms","start":"2026-10-26T00:56:47.41218Z","end":"2026-10-26T00:56:47.512923Z","steps":["trace[239182648] 'agreement among raft nodes before linearized reading' (duration: 100.285756ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:49.833056Z","caller":"traceutil/trace.go:171","msg":"trace[911517399] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"169.456701ms","start":"2026-10-26T00:56:49.663567Z","end":"2026-10-26T00:56:49.833024Z","steps":["trace[911517399] 'process raft request' (duration: 168.56641ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.503526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.447034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:50.503641Z","caller":"traceutil/trace.go:171","msg":"trace[1750979081] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:641; }","duration":"582.586787ms","start":"2026-10-26T00:56:49.921027Z","end":"2026-10-26T00:56:50.503614Z","steps":["trace[1750979081] 'range keys from in-memory index tree' (duration: 582.327392ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.503689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:49.920954Z","time spent":"582.721841ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-26T00:56:50.503697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.733872ms","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-10-26T00:56:50.503744Z","caller":"traceutil/trace.go:171","msg":"trace[1556878935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"441.816354ms","start":"2026-10-26T00:56:50.061914Z","end":"2026-10-26T00:56:50.50373Z","steps":["trace[1556878935] 'range keys from in-memory index tree' (duration: 441.581019ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:50.503774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:50.061892Z","time spent":"441.874195ms","remote":"127.0.0.1:51732","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-10-26T00:56:51.279365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.651723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:51.279828Z","caller":"traceutil/trace.go:171","msg":"trace[283681479] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"615.536549ms","start":"2026-10-26T00:56:50.66422Z","end":"2026-10-26T00:56:51.279757Z","steps":["trace[283681479] 'process raft request' (duration: 478.367905ms)","trace[283681479] 'compare' (duration: 136.482739ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:51.280071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:50.664202Z","time spent":"615.737803ms","remote":"127.0.0.1:51732","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-10-26T00:56:51.28033Z","caller":"traceutil/trace.go:171","msg":"trace[2054289026] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"361.665707ms","start":"2026-10-26T00:56:50.918642Z","end":"2026-10-26T00:56:51.280308Z","steps":["trace[2054289026] 'process raft request' (duration: 361.618476ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:51.280414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:50.918612Z","time spent":"361.761618ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:56:51.280465Z","caller":"traceutil/trace.go:171","msg":"trace[448049098] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"372.787232ms","start":"2026-10-26T00:56:50.907663Z","end":"2026-10-26T00:56:51.28045Z","steps":["trace[448049098] 'process raft request' (duration: 372.050675ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:51.280534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:50.907607Z","time spent":"372.885454ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:56:52.107534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.521943ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:52.107698Z","caller":"traceutil/trace.go:171","msg":"trace[1448497483] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:672; }","duration":"471.784089ms","start":"2026-10-26T00:56:51.635884Z","end":"2026-10-26T00:56:52.107668Z","steps":["trace[1448497483] 'read index received' (duration: 70.162µs)","trace[1448497483] 'applied index is now lower than readState.Index' (duration: 471.711737ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:52.107815Z","caller":"traceutil/trace.go:171","msg":"trace[248385430] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"745.998854ms","start":"2026-10-26T00:56:51.361791Z","end":"2026-10-26T00:56:52.107789Z","steps":["trace[248385430] 'process raft request' (duration: 69.089773ms)","trace[248385430] 'compare' (duration: 676.320217ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:52.108011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.112326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:56:52.108061Z","caller":"traceutil/trace.go:171","msg":"trace[1256571285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"472.226059ms","start":"2026-10-26T00:56:51.635821Z","end":"2026-10-26T00:56:52.108047Z","steps":["trace[1256571285] 'agreement among raft nodes before linearized reading' (duration: 471.975723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:52.108079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:51.36177Z","time spent":"746.096317ms","remote":"127.0.0.1:51672","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-10-26T00:56:52.108102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:51.635795Z","time spent":"472.295821ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:56:52.108109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.301924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:56:52.108188Z","caller":"traceutil/trace.go:171","msg":"trace[176602140] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:646; }","duration":"268.410776ms","start":"2026-10-26T00:56:51.839756Z","end":"2026-10-26T00:56:52.108167Z","steps":["trace[176602140] 'agreement among raft nodes before linearized reading' (duration: 268.264763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:52.108329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.814112ms","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-10-26T00:56:52.108515Z","caller":"traceutil/trace.go:171","msg":"trace[1704626626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:646; }","duration":"368.091869ms","start":"2026-10-26T00:56:51.740383Z","end":"2026-10-26T00:56:52.108475Z","steps":["trace[1704626626] 'agreement among raft nodes before linearized reading' (duration: 367.635999ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:52.108595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:51.740356Z","time spent":"368.222611ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-10-26T00:56:52.954311Z","caller":"traceutil/trace.go:171","msg":"trace[52590761] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"344.222258ms","start":"2026-10-26T00:56:52.610015Z","end":"2026-10-26T00:56:52.954237Z","steps":["trace[52590761] 'read index received' (duration: 335.914494ms)","trace[52590761] 'applied index is now lower than readState.Index' (duration: 8.305334ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:52.95433Z","caller":"traceutil/trace.go:171","msg":"trace[320803779] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"384.795299ms","start":"2026-10-26T00:56:52.569485Z","end":"2026-10-26T00:56:52.954281Z","steps":["trace[320803779] 'process raft request' (duration: 377.025687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:52.954684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:52.569438Z","time spent":"385.135208ms","remote":"127.0.0.1:51580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:56:53.376951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.419305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v56pg\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-26T00:56:53.377055Z","caller":"traceutil/trace.go:171","msg":"trace[145042524] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v56pg; range_end:; response_count:1; response_revision:653; }","duration":"482.536527ms","start":"2026-10-26T00:56:52.894494Z","end":"2026-10-26T00:56:53.37703Z","steps":["trace[145042524] 'agreement among raft nodes before linearized reading' (duration: 60.258127ms)","trace[145042524] 'range keys from in-memory index tree' (duration: 421.971583ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:53.377102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:52.894383Z","time spent":"482.707441ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-v56pg\" "} {"level":"warn","ts":"2026-10-26T00:56:53.377098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.037951ms","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-10-26T00:56:53.377218Z","caller":"traceutil/trace.go:171","msg":"trace[2057470799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"767.222855ms","start":"2026-10-26T00:56:52.609961Z","end":"2026-10-26T00:56:53.377184Z","steps":["trace[2057470799] 'agreement among raft nodes before linearized reading' (duration: 344.449043ms)","trace[2057470799] 'range keys from in-memory index tree' (duration: 422.415634ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:53.377272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:52.609938Z","time spent":"767.322217ms","remote":"127.0.0.1:51732","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-10-26T00:56:53.377604Z","caller":"traceutil/trace.go:171","msg":"trace[1721953829] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"339.994074ms","start":"2026-10-26T00:56:53.03759Z","end":"2026-10-26T00:56:53.377584Z","steps":["trace[1721953829] 'process raft request' (duration: 295.498887ms)","trace[1721953829] 'compare' (duration: 43.670608ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:53.377903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:53.037557Z","time spent":"340.090427ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:56:54.453852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.493345ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:54.454061Z","caller":"traceutil/trace.go:171","msg":"trace[1840884980] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:655; }","duration":"381.74009ms","start":"2026-10-26T00:56:54.072292Z","end":"2026-10-26T00:56:54.454032Z","steps":["trace[1840884980] 'range keys from in-memory index tree' (duration: 381.465445ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:54.454311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.290229ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:54.454584Z","caller":"traceutil/trace.go:171","msg":"trace[2021876612] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"110.235747ms","start":"2026-10-26T00:56:54.34433Z","end":"2026-10-26T00:56:54.454566Z","steps":["trace[2021876612] 'process raft request' (duration: 110.188936ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:54.4548Z","caller":"traceutil/trace.go:171","msg":"trace[76966069] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"1.051779879s","start":"2026-10-26T00:56:53.40301Z","end":"2026-10-26T00:56:54.45479Z","steps":["trace[76966069] 'process raft request' (duration: 1.051405601s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:54.454867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:53.402947Z","time spent":"1.051884911s","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:56:54.454958Z","caller":"traceutil/trace.go:171","msg":"trace[1810022985] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"1.061957065s","start":"2026-10-26T00:56:53.392991Z","end":"2026-10-26T00:56:54.454948Z","steps":["trace[1810022985] 'read index received' (duration: 27.844698ms)","trace[1810022985] 'applied index is now lower than readState.Index' (duration: 1.034111287s)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:54.45506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.062068697s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:54.45505Z","caller":"traceutil/trace.go:171","msg":"trace[866963421] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"1.071283052s","start":"2026-10-26T00:56:53.383701Z","end":"2026-10-26T00:56:54.454984Z","steps":["trace[866963421] 'process raft request' (duration: 110.216516ms)","trace[866963421] 'compare' (duration: 960.126964ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:54.455097Z","caller":"traceutil/trace.go:171","msg":"trace[2206224] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:658; }","duration":"1.062163659s","start":"2026-10-26T00:56:53.392921Z","end":"2026-10-26T00:56:54.455085Z","steps":["trace[2206224] 'agreement among raft nodes before linearized reading' (duration: 1.062108288s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:54.455131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:53.392891Z","time spent":"1.062234321s","remote":"127.0.0.1:51802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-10-26T00:56:54.455203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"938.989056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:56:54.455249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:53.383671Z","time spent":"1.071449176s","remote":"127.0.0.1:51732","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-10-26T00:56:54.45539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.232469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:56:54.455449Z","caller":"traceutil/trace.go:171","msg":"trace[2018698724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:658; }","duration":"331.308141ms","start":"2026-10-26T00:56:54.124107Z","end":"2026-10-26T00:56:54.455415Z","steps":["trace[2018698724] 'agreement among raft nodes before linearized reading' (duration: 331.180128ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:54.455487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.124085Z","time spent":"331.392763ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:56:54.455491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.533278ms","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-10-26T00:56:54.455531Z","caller":"traceutil/trace.go:171","msg":"trace[1548851237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:658; }","duration":"332.619341ms","start":"2026-10-26T00:56:54.122901Z","end":"2026-10-26T00:56:54.45552Z","steps":["trace[1548851237] 'agreement among raft nodes before linearized reading' (duration: 332.494357ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:54.455263Z","caller":"traceutil/trace.go:171","msg":"trace[827023306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"939.102428ms","start":"2026-10-26T00:56:53.516144Z","end":"2026-10-26T00:56:54.455247Z","steps":["trace[827023306] 'agreement among raft nodes before linearized reading' (duration: 939.016876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:54.455565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.122875Z","time spent":"332.682923ms","remote":"127.0.0.1:51732","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-10-26T00:56:54.455599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:53.516085Z","time spent":"939.500918ms","remote":"127.0.0.1:51470","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-10-26T00:56:54.965688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277334425,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T00:56:55.173987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.540727ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:56:55.174118Z","caller":"traceutil/trace.go:171","msg":"trace[1283716791] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"712.816918ms","start":"2026-10-26T00:56:54.461277Z","end":"2026-10-26T00:56:55.174094Z","steps":["trace[1283716791] 'process raft request' (duration: 512.85718ms)","trace[1283716791] 'compare' (duration: 199.409915ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:55.17418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.46124Z","time spent":"712.91159ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:56:55.180482Z","caller":"traceutil/trace.go:171","msg":"trace[725961113] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"715.378965ms","start":"2026-10-26T00:56:54.465063Z","end":"2026-10-26T00:56:55.180442Z","steps":["trace[725961113] 'process raft request' (duration: 715.14801ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:55.180722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.465037Z","time spent":"715.537438ms","remote":"127.0.0.1:51732","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-10-26T00:56:55.180987Z","caller":"traceutil/trace.go:171","msg":"trace[1725237363] linearizableReadLoop","detail":"{readStateIndex:689; appliedIndex:686; }","duration":"715.866526ms","start":"2026-10-26T00:56:54.465096Z","end":"2026-10-26T00:56:55.180962Z","steps":["trace[1725237363] 'read index received' (duration: 509.069867ms)","trace[1725237363] 'applied index is now lower than readState.Index' (duration: 206.795619ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:55.181076Z","caller":"traceutil/trace.go:171","msg":"trace[1755375667] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"296.798996ms","start":"2026-10-26T00:56:54.884262Z","end":"2026-10-26T00:56:55.181061Z","steps":["trace[1755375667] 'process raft request' (duration: 296.246183ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:55.181288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.189312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v56pg\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-10-26T00:56:55.181335Z","caller":"traceutil/trace.go:171","msg":"trace[910733016] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v56pg; range_end:; response_count:1; response_revision:661; }","duration":"716.286075ms","start":"2026-10-26T00:56:54.465035Z","end":"2026-10-26T00:56:55.181321Z","steps":["trace[910733016] 'agreement among raft nodes before linearized reading' (duration: 716.106521ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:55.181369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.46502Z","time spent":"716.339976ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-v56pg\" "} {"level":"warn","ts":"2026-10-26T00:56:55.18156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.325373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:55.181838Z","caller":"traceutil/trace.go:171","msg":"trace[2020315053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"665.866825ms","start":"2026-10-26T00:56:54.515901Z","end":"2026-10-26T00:56:55.181768Z","steps":["trace[2020315053] 'agreement among raft nodes before linearized reading' (duration: 665.310763ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:56:55.181995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:56:54.515877Z","time spent":"666.0915ms","remote":"127.0.0.1:51470","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-10-26T00:56:57.800886Z","caller":"traceutil/trace.go:171","msg":"trace[1165728144] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"132.161023ms","start":"2026-10-26T00:56:57.668673Z","end":"2026-10-26T00:56:57.800834Z","steps":["trace[1165728144] 'process raft request' (duration: 66.771262ms)","trace[1165728144] 'compare' (duration: 65.070814ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:57.801053Z","caller":"traceutil/trace.go:171","msg":"trace[383175516] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"128.948941ms","start":"2026-10-26T00:56:57.672078Z","end":"2026-10-26T00:56:57.801027Z","steps":["trace[383175516] 'process raft request' (duration: 128.695306ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:57.800956Z","caller":"traceutil/trace.go:171","msg":"trace[1265441356] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"131.715873ms","start":"2026-10-26T00:56:57.669196Z","end":"2026-10-26T00:56:57.800912Z","steps":["trace[1265441356] 'process raft request' (duration: 131.509528ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:58.071999Z","caller":"traceutil/trace.go:171","msg":"trace[1270548598] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"128.019831ms","start":"2026-10-26T00:56:57.943937Z","end":"2026-10-26T00:56:58.071957Z","steps":["trace[1270548598] 'process raft request' (duration: 85.643451ms)","trace[1270548598] 'compare' (duration: 42.123484ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:58.072164Z","caller":"traceutil/trace.go:171","msg":"trace[829283036] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"111.222968ms","start":"2026-10-26T00:56:57.960825Z","end":"2026-10-26T00:56:58.072048Z","steps":["trace[829283036] 'process raft request' (duration: 111.019743ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:58.194773Z","caller":"traceutil/trace.go:171","msg":"trace[960919611] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"108.948527ms","start":"2026-10-26T00:56:58.085781Z","end":"2026-10-26T00:56:58.194729Z","steps":["trace[960919611] 'process raft request' (duration: 88.277979ms)","trace[960919611] 'compare' (duration: 20.463274ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:58.319947Z","caller":"traceutil/trace.go:171","msg":"trace[2071511520] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"113.837687ms","start":"2026-10-26T00:56:58.206084Z","end":"2026-10-26T00:56:58.319921Z","steps":["trace[2071511520] 'process raft request' (duration: 112.61423ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:58.694851Z","caller":"traceutil/trace.go:171","msg":"trace[515016975] linearizableReadLoop","detail":"{readStateIndex:741; appliedIndex:740; }","duration":"110.570284ms","start":"2026-10-26T00:56:58.584234Z","end":"2026-10-26T00:56:58.694804Z","steps":["trace[515016975] 'read index received' (duration: 110.38914ms)","trace[515016975] 'applied index is now lower than readState.Index' (duration: 179.664µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:56:58.69503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.771358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:56:58.695071Z","caller":"traceutil/trace.go:171","msg":"trace[1150533240] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:712; }","duration":"110.89059ms","start":"2026-10-26T00:56:58.584168Z","end":"2026-10-26T00:56:58.695058Z","steps":["trace[1150533240] 'agreement among raft nodes before linearized reading' (duration: 110.762968ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:58.695319Z","caller":"traceutil/trace.go:171","msg":"trace[1887173540] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"130.466186ms","start":"2026-10-26T00:56:58.564804Z","end":"2026-10-26T00:56:58.69527Z","steps":["trace[1887173540] 'process raft request' (duration: 129.854802ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:58.873588Z","caller":"traceutil/trace.go:171","msg":"trace[564000517] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"113.424427ms","start":"2026-10-26T00:56:58.760131Z","end":"2026-10-26T00:56:58.873556Z","steps":["trace[564000517] 'process raft request' (duration: 36.95593ms)","trace[564000517] 'compare' (duration: 76.279403ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:59.704882Z","caller":"traceutil/trace.go:171","msg":"trace[1018914354] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"118.012799ms","start":"2026-10-26T00:56:59.586832Z","end":"2026-10-26T00:56:59.704845Z","steps":["trace[1018914354] 'process raft request' (duration: 116.966996ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:59.825804Z","caller":"traceutil/trace.go:171","msg":"trace[2092625316] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"115.724438ms","start":"2026-10-26T00:56:59.710045Z","end":"2026-10-26T00:56:59.82577Z","steps":["trace[2092625316] 'process raft request' (duration: 59.48724ms)","trace[2092625316] 'compare' (duration: 55.980652ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:56:59.845596Z","caller":"traceutil/trace.go:171","msg":"trace[1990139849] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"105.325136ms","start":"2026-10-26T00:56:59.740262Z","end":"2026-10-26T00:56:59.845587Z","steps":["trace[1990139849] 'process raft request' (duration: 105.258875ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:56:59.894519Z","caller":"traceutil/trace.go:171","msg":"trace[2026234542] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"116.545735ms","start":"2026-10-26T00:56:59.777933Z","end":"2026-10-26T00:56:59.894479Z","steps":["trace[2026234542] 'process raft request' (duration: 116.2805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:00.558103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.786383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:57:00.558224Z","caller":"traceutil/trace.go:171","msg":"trace[595294565] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"399.74543ms","start":"2026-10-26T00:57:00.158459Z","end":"2026-10-26T00:57:00.558205Z","steps":["trace[595294565] 'read index received' (duration: 204.645661ms)","trace[595294565] 'applied index is now lower than readState.Index' (duration: 195.098589ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:00.558284Z","caller":"traceutil/trace.go:171","msg":"trace[1343365156] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"456.45542ms","start":"2026-10-26T00:57:00.101781Z","end":"2026-10-26T00:57:00.558236Z","steps":["trace[1343365156] 'process raft request' (duration: 261.435022ms)","trace[1343365156] 'compare' (duration: 194.622569ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:00.558483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:00.10173Z","time spent":"456.629563ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:57:00.558617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.690101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-10-26T00:57:00.558715Z","caller":"traceutil/trace.go:171","msg":"trace[1496696608] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:747; }","duration":"199.884525ms","start":"2026-10-26T00:57:00.358814Z","end":"2026-10-26T00:57:00.558699Z","steps":["trace[1496696608] 'agreement among raft nodes before linearized reading' (duration: 199.521367ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:00.558779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.324083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-10-26T00:57:00.558876Z","caller":"traceutil/trace.go:171","msg":"trace[316049127] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:747; }","duration":"400.443706ms","start":"2026-10-26T00:57:00.158418Z","end":"2026-10-26T00:57:00.558862Z","steps":["trace[316049127] 'agreement among raft nodes before linearized reading' (duration: 399.879713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:00.558912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:00.158333Z","time spent":"400.568399ms","remote":"127.0.0.1:51536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-10-26T00:57:00.820645Z","caller":"traceutil/trace.go:171","msg":"trace[1324954910] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"112.693891ms","start":"2026-10-26T00:57:00.707918Z","end":"2026-10-26T00:57:00.820612Z","steps":["trace[1324954910] 'process raft request' (duration: 112.261771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.010835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.58454ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:57:02.011117Z","caller":"traceutil/trace.go:171","msg":"trace[1427588767] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"154.327064ms","start":"2026-10-26T00:57:01.85675Z","end":"2026-10-26T00:57:02.011077Z","steps":["trace[1427588767] 'compare' (duration: 144.18046ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.269453Z","caller":"traceutil/trace.go:171","msg":"trace[63840738] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"110.787609ms","start":"2026-10-26T00:57:02.158546Z","end":"2026-10-26T00:57:02.269334Z","steps":["trace[63840738] 'read index received' (duration: 110.325689ms)","trace[63840738] 'applied index is now lower than readState.Index' (duration: 460.66µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:02.269548Z","caller":"traceutil/trace.go:171","msg":"trace[2029825042] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"236.665791ms","start":"2026-10-26T00:57:02.032846Z","end":"2026-10-26T00:57:02.269511Z","steps":["trace[2029825042] 'process raft request' (duration: 236.330984ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.269958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.396852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-10-26T00:57:02.269996Z","caller":"traceutil/trace.go:171","msg":"trace[528051403] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:760; }","duration":"111.458954ms","start":"2026-10-26T00:57:02.158526Z","end":"2026-10-26T00:57:02.269985Z","steps":["trace[528051403] 'agreement among raft nodes before linearized reading' (duration: 111.027233ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.551481Z","caller":"traceutil/trace.go:171","msg":"trace[1238567272] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"180.653988ms","start":"2026-10-26T00:57:02.370762Z","end":"2026-10-26T00:57:02.551416Z","steps":["trace[1238567272] 'process raft request' (duration: 114.322406ms)","trace[1238567272] 'compare' (duration: 65.822361ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:02.702716Z","caller":"traceutil/trace.go:171","msg":"trace[104039483] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"142.812439ms","start":"2026-10-26T00:57:02.559869Z","end":"2026-10-26T00:57:02.702681Z","steps":["trace[104039483] 'process raft request' (duration: 94.839544ms)","trace[104039483] 'compare' (duration: 47.511274ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:02.702736Z","caller":"traceutil/trace.go:171","msg":"trace[365487356] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"127.333576ms","start":"2026-10-26T00:57:02.575373Z","end":"2026-10-26T00:57:02.702707Z","steps":["trace[365487356] 'read index received' (duration: 44.648231ms)","trace[365487356] 'applied index is now lower than readState.Index' (duration: 82.680025ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:02.703166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.755424ms","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-10-26T00:57:02.703225Z","caller":"traceutil/trace.go:171","msg":"trace[1263750408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"127.898007ms","start":"2026-10-26T00:57:02.575304Z","end":"2026-10-26T00:57:02.703202Z","steps":["trace[1263750408] 'agreement among raft nodes before linearized reading' (duration: 127.529009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.703325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.014979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.703379Z","caller":"traceutil/trace.go:171","msg":"trace[351514107] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:767; }","duration":"100.156142ms","start":"2026-10-26T00:57:02.603208Z","end":"2026-10-26T00:57:02.703365Z","steps":["trace[351514107] 'agreement among raft nodes before linearized reading' (duration: 99.995818ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.703325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.610088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-26T00:57:02.703386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.292415ms","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-10-26T00:57:02.70389Z","caller":"traceutil/trace.go:171","msg":"trace[1908504632] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:767; }","duration":"100.836908ms","start":"2026-10-26T00:57:02.60304Z","end":"2026-10-26T00:57:02.703877Z","steps":["trace[1908504632] 'agreement among raft nodes before linearized reading' (duration: 100.317136ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.703485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.834341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.703995Z","caller":"traceutil/trace.go:171","msg":"trace[1116902101] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:767; }","duration":"121.367633ms","start":"2026-10-26T00:57:02.582618Z","end":"2026-10-26T00:57:02.703986Z","steps":["trace[1116902101] 'agreement among raft nodes before linearized reading' (duration: 120.847451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.703519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.841084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.704138Z","caller":"traceutil/trace.go:171","msg":"trace[1590354832] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:767; }","duration":"122.490109ms","start":"2026-10-26T00:57:02.581633Z","end":"2026-10-26T00:57:02.704123Z","steps":["trace[1590354832] 'agreement among raft nodes before linearized reading' (duration: 121.865965ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.70345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.761716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.704523Z","caller":"traceutil/trace.go:171","msg":"trace[1004648266] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:767; }","duration":"101.85284ms","start":"2026-10-26T00:57:02.602653Z","end":"2026-10-26T00:57:02.704506Z","steps":["trace[1004648266] 'agreement among raft nodes before linearized reading' (duration: 100.752226ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.703814Z","caller":"traceutil/trace.go:171","msg":"trace[349135648] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:767; }","duration":"122.232673ms","start":"2026-10-26T00:57:02.581565Z","end":"2026-10-26T00:57:02.703797Z","steps":["trace[349135648] 'agreement among raft nodes before linearized reading' (duration: 121.581608ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.849963Z","caller":"traceutil/trace.go:171","msg":"trace[1596605904] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"135.364004ms","start":"2026-10-26T00:57:02.714567Z","end":"2026-10-26T00:57:02.849931Z","steps":["trace[1596605904] 'process raft request' (duration: 135.287212ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:02.850028Z","caller":"traceutil/trace.go:171","msg":"trace[1111152828] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"135.437085ms","start":"2026-10-26T00:57:02.714525Z","end":"2026-10-26T00:57:02.849962Z","steps":["trace[1111152828] 'process raft request' (duration: 100.866938ms)","trace[1111152828] 'compare' (duration: 34.268751ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:02.85371Z","caller":"traceutil/trace.go:171","msg":"trace[899601151] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:798; }","duration":"103.596229ms","start":"2026-10-26T00:57:02.750094Z","end":"2026-10-26T00:57:02.85369Z","steps":["trace[899601151] 'read index received' (duration: 65.313459ms)","trace[899601151] 'applied index is now lower than readState.Index' (duration: 38.28125ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:02.854007Z","caller":"traceutil/trace.go:171","msg":"trace[1317402199] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"138.960363ms","start":"2026-10-26T00:57:02.715033Z","end":"2026-10-26T00:57:02.853993Z","steps":["trace[1317402199] 'process raft request' (duration: 138.512213ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.854247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.11327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.854297Z","caller":"traceutil/trace.go:171","msg":"trace[815928781] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:770; }","duration":"104.238023ms","start":"2026-10-26T00:57:02.750047Z","end":"2026-10-26T00:57:02.854285Z","steps":["trace[815928781] 'agreement among raft nodes before linearized reading' (duration: 104.1192ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.854501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.337294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.854537Z","caller":"traceutil/trace.go:171","msg":"trace[1527807491] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:770; }","duration":"104.377035ms","start":"2026-10-26T00:57:02.750151Z","end":"2026-10-26T00:57:02.854528Z","steps":["trace[1527807491] 'agreement among raft nodes before linearized reading' (duration: 104.321084ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:02.85478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.67936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:02.854816Z","caller":"traceutil/trace.go:171","msg":"trace[1490509974] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:770; }","duration":"103.766852ms","start":"2026-10-26T00:57:02.75104Z","end":"2026-10-26T00:57:02.854807Z","steps":["trace[1490509974] 'agreement among raft nodes before linearized reading' (duration: 103.694991ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:03.307241Z","caller":"traceutil/trace.go:171","msg":"trace[17874943] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"117.961228ms","start":"2026-10-26T00:57:03.189248Z","end":"2026-10-26T00:57:03.307209Z","steps":["trace[17874943] 'process raft request' (duration: 117.547548ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:03.424941Z","caller":"traceutil/trace.go:171","msg":"trace[746334326] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"111.859372ms","start":"2026-10-26T00:57:03.313044Z","end":"2026-10-26T00:57:03.424903Z","steps":["trace[746334326] 'process raft request' (duration: 70.756249ms)","trace[746334326] 'compare' (duration: 40.922258ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:03.486853Z","caller":"traceutil/trace.go:171","msg":"trace[16854486] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"173.708854ms","start":"2026-10-26T00:57:03.31311Z","end":"2026-10-26T00:57:03.486819Z","steps":["trace[16854486] 'process raft request' (duration: 173.50251ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:03.487108Z","caller":"traceutil/trace.go:171","msg":"trace[1416482536] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"173.49219ms","start":"2026-10-26T00:57:03.313546Z","end":"2026-10-26T00:57:03.487039Z","steps":["trace[1416482536] 'process raft request' (duration: 173.228405ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:03.668481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.450081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T00:57:03.668577Z","caller":"traceutil/trace.go:171","msg":"trace[371361742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:776; }","duration":"105.633595ms","start":"2026-10-26T00:57:03.562923Z","end":"2026-10-26T00:57:03.668556Z","steps":["trace[371361742] 'range keys from in-memory index tree' (duration: 105.229155ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:03.810739Z","caller":"traceutil/trace.go:171","msg":"trace[64794071] transaction","detail":"{read_only:false; number_of_response:0; response_revision:779; }","duration":"133.605894ms","start":"2026-10-26T00:57:03.677106Z","end":"2026-10-26T00:57:03.810712Z","steps":["trace[64794071] 'process raft request' (duration: 133.553283ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:03.810982Z","caller":"traceutil/trace.go:171","msg":"trace[324471381] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"134.032393ms","start":"2026-10-26T00:57:03.676909Z","end":"2026-10-26T00:57:03.810941Z","steps":["trace[324471381] 'process raft request' (duration: 133.625575ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.659059Z","caller":"traceutil/trace.go:171","msg":"trace[1043553390] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:814; }","duration":"143.655898ms","start":"2026-10-26T00:57:04.515362Z","end":"2026-10-26T00:57:04.659018Z","steps":["trace[1043553390] 'read index received' (duration: 10.457912ms)","trace[1043553390] 'applied index is now lower than readState.Index' (duration: 133.196836ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:04.659297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.845203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:04.659377Z","caller":"traceutil/trace.go:171","msg":"trace[1855827994] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:784; }","duration":"144.046106ms","start":"2026-10-26T00:57:04.515317Z","end":"2026-10-26T00:57:04.659363Z","steps":["trace[1855827994] 'agreement among raft nodes before linearized reading' (duration: 143.846442ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:04.65936Z","caller":"traceutil/trace.go:171","msg":"trace[689998948] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"159.350716ms","start":"2026-10-26T00:57:04.499967Z","end":"2026-10-26T00:57:04.659318Z","steps":["trace[689998948] 'process raft request' (duration: 89.623189ms)","trace[689998948] 'compare' (duration: 69.268487ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.018946Z","caller":"traceutil/trace.go:171","msg":"trace[670997668] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"164.462769ms","start":"2026-10-26T00:57:04.854459Z","end":"2026-10-26T00:57:05.018922Z","steps":["trace[670997668] 'read index received' (duration: 164.278875ms)","trace[670997668] 'applied index is now lower than readState.Index' (duration: 182.514µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:05.019321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.663933ms","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-10-26T00:57:05.019313Z","caller":"traceutil/trace.go:171","msg":"trace[2036759869] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"226.69263ms","start":"2026-10-26T00:57:04.792541Z","end":"2026-10-26T00:57:05.019233Z","steps":["trace[2036759869] 'process raft request' (duration: 226.224ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.019372Z","caller":"traceutil/trace.go:171","msg":"trace[2058582885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:785; }","duration":"164.97737ms","start":"2026-10-26T00:57:04.854381Z","end":"2026-10-26T00:57:05.019358Z","steps":["trace[2058582885] 'agreement among raft nodes before linearized reading' (duration: 164.644242ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.19684Z","caller":"traceutil/trace.go:171","msg":"trace[334851907] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"171.351023ms","start":"2026-10-26T00:57:05.025443Z","end":"2026-10-26T00:57:05.196794Z","steps":["trace[334851907] 'process raft request' (duration: 115.229277ms)","trace[334851907] 'compare' (duration: 55.962432ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.616711Z","caller":"traceutil/trace.go:171","msg":"trace[1374045755] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"118.766866ms","start":"2026-10-26T00:57:05.497915Z","end":"2026-10-26T00:57:05.616682Z","steps":["trace[1374045755] 'process raft request' (duration: 118.564531ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.762585Z","caller":"traceutil/trace.go:171","msg":"trace[973882595] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"125.421353ms","start":"2026-10-26T00:57:05.637135Z","end":"2026-10-26T00:57:05.762556Z","steps":["trace[973882595] 'read index received' (duration: 39.249001ms)","trace[973882595] 'applied index is now lower than readState.Index' (duration: 86.165382ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.762603Z","caller":"traceutil/trace.go:171","msg":"trace[472821219] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"264.288884ms","start":"2026-10-26T00:57:05.498254Z","end":"2026-10-26T00:57:05.762543Z","steps":["trace[472821219] 'process raft request' (duration: 178.095733ms)","trace[472821219] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/kube-scheduler; req_size:460; } (duration: 85.886376ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:05.762911Z","caller":"traceutil/trace.go:171","msg":"trace[737219981] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"107.646778ms","start":"2026-10-26T00:57:05.655209Z","end":"2026-10-26T00:57:05.762855Z","steps":["trace[737219981] 'process raft request' (duration: 107.2527ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:05.763125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.947385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v56pg\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-10-26T00:57:05.763225Z","caller":"traceutil/trace.go:171","msg":"trace[1390606865] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v56pg; range_end:; response_count:1; response_revision:789; }","duration":"126.084088ms","start":"2026-10-26T00:57:05.637116Z","end":"2026-10-26T00:57:05.7632Z","steps":["trace[1390606865] 'agreement among raft nodes before linearized reading' (duration: 125.575456ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:05.958933Z","caller":"traceutil/trace.go:171","msg":"trace[274533339] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"172.720852ms","start":"2026-10-26T00:57:05.78617Z","end":"2026-10-26T00:57:05.958891Z","steps":["trace[274533339] 'process raft request' (duration: 139.010685ms)","trace[274533339] 'compare' (duration: 33.417111ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:06.132951Z","caller":"traceutil/trace.go:171","msg":"trace[67169857] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"199.417856ms","start":"2026-10-26T00:57:05.933503Z","end":"2026-10-26T00:57:06.132921Z","steps":["trace[67169857] 'read index received' (duration: 137.666466ms)","trace[67169857] 'applied index is now lower than readState.Index' (duration: 61.75005ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:06.133093Z","caller":"traceutil/trace.go:171","msg":"trace[190924022] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"311.438671ms","start":"2026-10-26T00:57:05.82164Z","end":"2026-10-26T00:57:06.133078Z","steps":["trace[190924022] 'process raft request' (duration: 249.592479ms)","trace[190924022] 'compare' (duration: 61.237188ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:57:06.133307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.821612Z","time spent":"311.554634ms","remote":"127.0.0.1:58850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-26T00:57:06.133828Z","caller":"traceutil/trace.go:171","msg":"trace[57783321] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"311.159274ms","start":"2026-10-26T00:57:05.822655Z","end":"2026-10-26T00:57:06.133814Z","steps":["trace[57783321] 'process raft request' (duration: 310.139282ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:06.133898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:05.822644Z","time spent":"311.226906ms","remote":"127.0.0.1:58868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2173,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:57:06.134287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.775026ms","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-10-26T00:57:06.134335Z","caller":"traceutil/trace.go:171","msg":"trace[2133370619] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:793; }","duration":"200.879078ms","start":"2026-10-26T00:57:05.933445Z","end":"2026-10-26T00:57:06.134324Z","steps":["trace[2133370619] 'agreement among raft nodes before linearized reading' (duration: 200.650643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:06.134682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.353772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-v56pg\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-10-26T00:57:06.134724Z","caller":"traceutil/trace.go:171","msg":"trace[68746167] range","detail":"{range_begin:/registry/pods/kube-system/cilium-v56pg; range_end:; response_count:1; response_revision:793; }","duration":"171.419903ms","start":"2026-10-26T00:57:05.963294Z","end":"2026-10-26T00:57:06.134714Z","steps":["trace[68746167] 'agreement among raft nodes before linearized reading' (duration: 171.310681ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.142269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277334812,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:57:12.461885Z","caller":"traceutil/trace.go:171","msg":"trace[399246838] linearizableReadLoop","detail":"{readStateIndex:864; appliedIndex:863; }","duration":"819.69473ms","start":"2026-10-26T00:57:11.642149Z","end":"2026-10-26T00:57:12.461844Z","steps":["trace[399246838] 'read index received' (duration: 819.441495ms)","trace[399246838] 'applied index is now lower than readState.Index' (duration: 251.585µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:12.462141Z","caller":"traceutil/trace.go:171","msg":"trace[546033459] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"834.757193ms","start":"2026-10-26T00:57:11.627178Z","end":"2026-10-26T00:57:12.461935Z","steps":["trace[546033459] 'process raft request' (duration: 834.407705ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.462258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.697141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T00:57:12.462186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.015057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:57:12.462315Z","caller":"traceutil/trace.go:171","msg":"trace[1747382513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:831; }","duration":"660.827444ms","start":"2026-10-26T00:57:11.801472Z","end":"2026-10-26T00:57:12.4623Z","steps":["trace[1747382513] 'agreement among raft nodes before linearized reading' (duration: 660.589639ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:12.462348Z","caller":"traceutil/trace.go:171","msg":"trace[953772678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:831; }","duration":"820.236431ms","start":"2026-10-26T00:57:11.642085Z","end":"2026-10-26T00:57:12.462321Z","steps":["trace[953772678] 'agreement among raft nodes before linearized reading' (duration: 819.917064ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.462367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:11.801445Z","time spent":"660.911446ms","remote":"127.0.0.1:51732","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-10-26T00:57:12.462337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.565416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T00:57:12.462412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:11.64203Z","time spent":"820.364674ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:57:12.462468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.749652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T00:57:12.462556Z","caller":"traceutil/trace.go:171","msg":"trace[218957005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:831; }","duration":"231.876585ms","start":"2026-10-26T00:57:12.230662Z","end":"2026-10-26T00:57:12.462538Z","steps":["trace[218957005] 'agreement among raft nodes before linearized reading' (duration: 231.732692ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.462578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.464977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:57:12.462459Z","caller":"traceutil/trace.go:171","msg":"trace[2108087179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:831; }","duration":"609.75284ms","start":"2026-10-26T00:57:11.85268Z","end":"2026-10-26T00:57:12.462433Z","steps":["trace[2108087179] 'agreement among raft nodes before linearized reading' (duration: 609.478564ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:12.462615Z","caller":"traceutil/trace.go:171","msg":"trace[1122442129] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:831; }","duration":"620.544629ms","start":"2026-10-26T00:57:11.84206Z","end":"2026-10-26T00:57:12.462605Z","steps":["trace[1122442129] 'agreement among raft nodes before linearized reading' (duration: 620.434397ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.462563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:11.627145Z","time spent":"835.110211ms","remote":"127.0.0.1:51732","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-10-26T00:57:12.462678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:11.852653Z","time spent":"609.988375ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T00:57:12.462659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:11.842035Z","time spent":"620.610061ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.105\" "} {"level":"warn","ts":"2026-10-26T00:57:12.462337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.377664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5nvp2\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-10-26T00:57:12.462891Z","caller":"traceutil/trace.go:171","msg":"trace[1298565483] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5nvp2; range_end:; response_count:1; response_revision:831; }","duration":"428.012688ms","start":"2026-10-26T00:57:12.034865Z","end":"2026-10-26T00:57:12.462878Z","steps":["trace[1298565483] 'agreement among raft nodes before linearized reading' (duration: 427.21897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:12.462942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:12.034832Z","time spent":"428.09623ms","remote":"127.0.0.1:51654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5nvp2\" "} {"level":"info","ts":"2026-10-26T00:57:19.967277Z","caller":"traceutil/trace.go:171","msg":"trace[1877570600] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"169.761847ms","start":"2026-10-26T00:57:19.79749Z","end":"2026-10-26T00:57:19.967252Z","steps":["trace[1877570600] 'process raft request' (duration: 169.400529ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:19.967406Z","caller":"traceutil/trace.go:171","msg":"trace[372771427] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"175.601296ms","start":"2026-10-26T00:57:19.791739Z","end":"2026-10-26T00:57:19.967341Z","steps":["trace[372771427] 'process raft request' (duration: 175.012293ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:20.69283Z","caller":"traceutil/trace.go:171","msg":"trace[1125814540] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"177.828086ms","start":"2026-10-26T00:57:20.514982Z","end":"2026-10-26T00:57:20.69281Z","steps":["trace[1125814540] 'read index received' (duration: 177.684122ms)","trace[1125814540] 'applied index is now lower than readState.Index' (duration: 142.654µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:20.692928Z","caller":"traceutil/trace.go:171","msg":"trace[1457996321] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"198.899714ms","start":"2026-10-26T00:57:20.493989Z","end":"2026-10-26T00:57:20.692888Z","steps":["trace[1457996321] 'process raft request' (duration: 198.683858ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:20.693021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.0155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:57:20.69305Z","caller":"traceutil/trace.go:171","msg":"trace[280705232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"178.118052ms","start":"2026-10-26T00:57:20.514923Z","end":"2026-10-26T00:57:20.693041Z","steps":["trace[280705232] 'agreement among raft nodes before linearized reading' (duration: 178.01987ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:53.758894Z","caller":"traceutil/trace.go:171","msg":"trace[1903151671] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"148.256486ms","start":"2026-10-26T00:57:53.610602Z","end":"2026-10-26T00:57:53.758859Z","steps":["trace[1903151671] 'process raft request' (duration: 148.016001ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.497019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335427,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:57:55.71379Z","caller":"traceutil/trace.go:171","msg":"trace[1974000827] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"730.117478ms","start":"2026-10-26T00:57:54.983635Z","end":"2026-10-26T00:57:55.713752Z","steps":["trace[1974000827] 'process raft request' (duration: 729.896723ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.714027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:54.983615Z","time spent":"730.279691ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:57:55.716244Z","caller":"traceutil/trace.go:171","msg":"trace[380193664] linearizableReadLoop","detail":"{readStateIndex:1168; appliedIndex:1166; }","duration":"719.703165ms","start":"2026-10-26T00:57:54.996511Z","end":"2026-10-26T00:57:55.716214Z","steps":["trace[380193664] 'read index received' (duration: 717.042675ms)","trace[380193664] 'applied index is now lower than readState.Index' (duration: 2.65923ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:55.716297Z","caller":"traceutil/trace.go:171","msg":"trace[877643247] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"225.152231ms","start":"2026-10-26T00:57:55.491117Z","end":"2026-10-26T00:57:55.716269Z","steps":["trace[877643247] 'process raft request' (duration: 224.951056ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.716508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.997212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T00:57:55.71655Z","caller":"traceutil/trace.go:171","msg":"trace[1704913642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"720.047373ms","start":"2026-10-26T00:57:54.996492Z","end":"2026-10-26T00:57:55.716539Z","steps":["trace[1704913642] 'agreement among raft nodes before linearized reading' (duration: 719.853168ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.716582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:54.99646Z","time spent":"720.111983ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T00:57:55.716678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.529483ms","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-10-26T00:57:55.716722Z","caller":"traceutil/trace.go:171","msg":"trace[1314976575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1124; }","duration":"712.590425ms","start":"2026-10-26T00:57:55.004115Z","end":"2026-10-26T00:57:55.716706Z","steps":["trace[1314976575] 'agreement among raft nodes before linearized reading' (duration: 712.460002ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.716754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:55.004107Z","time spent":"712.639286ms","remote":"127.0.0.1:51732","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-10-26T00:57:55.716909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.809335ms","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-10-26T00:57:55.717077Z","caller":"traceutil/trace.go:171","msg":"trace[708051982] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"715.033039ms","start":"2026-10-26T00:57:55.001989Z","end":"2026-10-26T00:57:55.717022Z","steps":["trace[708051982] 'agreement among raft nodes before linearized reading' (duration: 714.541588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.717207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:57:55.001979Z","time spent":"715.211564ms","remote":"127.0.0.1:51732","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-10-26T00:57:55.892738Z","caller":"traceutil/trace.go:171","msg":"trace[1424008449] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"168.91139ms","start":"2026-10-26T00:57:55.723791Z","end":"2026-10-26T00:57:55.892702Z","steps":["trace[1424008449] 'process raft request' (duration: 168.644164ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:55.89289Z","caller":"traceutil/trace.go:171","msg":"trace[225239304] linearizableReadLoop","detail":"{readStateIndex:1171; appliedIndex:1169; }","duration":"129.937505ms","start":"2026-10-26T00:57:55.762916Z","end":"2026-10-26T00:57:55.892854Z","steps":["trace[225239304] 'read index received' (duration: 129.453114ms)","trace[225239304] 'applied index is now lower than readState.Index' (duration: 475.281µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:57:55.893003Z","caller":"traceutil/trace.go:171","msg":"trace[2044973574] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"168.663473ms","start":"2026-10-26T00:57:55.724295Z","end":"2026-10-26T00:57:55.892959Z","steps":["trace[2044973574] 'process raft request' (duration: 168.360617ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:57:55.893178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.247472ms","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-10-26T00:57:55.893208Z","caller":"traceutil/trace.go:171","msg":"trace[280568015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1127; }","duration":"130.352004ms","start":"2026-10-26T00:57:55.762845Z","end":"2026-10-26T00:57:55.893197Z","steps":["trace[280568015] 'agreement among raft nodes before linearized reading' (duration: 130.123479ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:55.962984Z","caller":"traceutil/trace.go:171","msg":"trace[511568903] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"200.023237ms","start":"2026-10-26T00:57:55.762935Z","end":"2026-10-26T00:57:55.962959Z","steps":["trace[511568903] 'process raft request' (duration: 199.884894ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:57:56.057215Z","caller":"traceutil/trace.go:171","msg":"trace[2011498004] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"159.15083ms","start":"2026-10-26T00:57:55.898024Z","end":"2026-10-26T00:57:56.057175Z","steps":["trace[2011498004] 'process raft request' (duration: 99.237126ms)","trace[2011498004] 'compare' (duration: 59.75225ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:10.253928Z","caller":"traceutil/trace.go:171","msg":"trace[847988772] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"184.199782ms","start":"2026-10-26T00:58:10.069689Z","end":"2026-10-26T00:58:10.253889Z","steps":["trace[847988772] 'read index received' (duration: 183.770753ms)","trace[847988772] 'applied index is now lower than readState.Index' (duration: 427.239µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:10.254053Z","caller":"traceutil/trace.go:171","msg":"trace[2003339205] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"248.566956ms","start":"2026-10-26T00:58:10.005423Z","end":"2026-10-26T00:58:10.25399Z","steps":["trace[2003339205] 'process raft request' (duration: 248.244879ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:10.254288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.56409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T00:58:10.254353Z","caller":"traceutil/trace.go:171","msg":"trace[460083745] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"184.712694ms","start":"2026-10-26T00:58:10.06962Z","end":"2026-10-26T00:58:10.254332Z","steps":["trace[460083745] 'agreement among raft nodes before linearized reading' (duration: 184.463718ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:10.254467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.567767ms","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-10-26T00:58:10.254578Z","caller":"traceutil/trace.go:171","msg":"trace[416479300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1172; }","duration":"113.773872ms","start":"2026-10-26T00:58:10.140778Z","end":"2026-10-26T00:58:10.254552Z","steps":["trace[416479300] 'agreement among raft nodes before linearized reading' (duration: 113.451555ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:14.95736Z","caller":"traceutil/trace.go:171","msg":"trace[81415438] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"312.728365ms","start":"2026-10-26T00:58:14.644597Z","end":"2026-10-26T00:58:14.957325Z","steps":["trace[81415438] 'process raft request' (duration: 312.50008ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:14.957638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:14.64457Z","time spent":"312.93688ms","remote":"127.0.0.1:51732","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-10-26T00:58:16.461081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.725984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:16.461194Z","caller":"traceutil/trace.go:171","msg":"trace[1016571682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1208; }","duration":"945.935189ms","start":"2026-10-26T00:58:15.515232Z","end":"2026-10-26T00:58:16.461168Z","steps":["trace[1016571682] 'range keys from in-memory index tree' (duration: 945.626273ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:16.461248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.515202Z","time spent":"946.033121ms","remote":"127.0.0.1:51470","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-10-26T00:58:16.476296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335623,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:58:16.496737Z","caller":"traceutil/trace.go:171","msg":"trace[1894173999] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"563.208285ms","start":"2026-10-26T00:58:15.933494Z","end":"2026-10-26T00:58:16.496703Z","steps":["trace[1894173999] 'process raft request' (duration: 562.99147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:16.49698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.933463Z","time spent":"563.370328ms","remote":"127.0.0.1:51732","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-10-26T00:58:17.341968Z","caller":"traceutil/trace.go:171","msg":"trace[1899463603] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1257; }","duration":"1.36592861s","start":"2026-10-26T00:58:15.976006Z","end":"2026-10-26T00:58:17.341935Z","steps":["trace[1899463603] 'read index received' (duration: 520.424133ms)","trace[1899463603] 'applied index is now lower than readState.Index' (duration: 845.503297ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:17.342328Z","caller":"traceutil/trace.go:171","msg":"trace[432995009] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"1.034310392s","start":"2026-10-26T00:58:16.308Z","end":"2026-10-26T00:58:17.34231Z","steps":["trace[432995009] 'process raft request' (duration: 1.033724739s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.342499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.366474924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T00:58:17.342518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.307981Z","time spent":"1.034383544s","remote":"127.0.0.1:51732","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-10-26T00:58:17.342564Z","caller":"traceutil/trace.go:171","msg":"trace[1146438759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1210; }","duration":"1.366595327s","start":"2026-10-26T00:58:15.97595Z","end":"2026-10-26T00:58:17.342545Z","steps":["trace[1146438759] 'agreement among raft nodes before linearized reading' (duration: 1.366344681s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.342597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.975926Z","time spent":"1.366665398s","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:17.776926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.708258ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-26T00:58:17.777194Z","caller":"traceutil/trace.go:171","msg":"trace[115654573] linearizableReadLoop","detail":"{readStateIndex:1260; appliedIndex:1259; }","duration":"435.09772ms","start":"2026-10-26T00:58:17.342048Z","end":"2026-10-26T00:58:17.777146Z","steps":["trace[115654573] 'read index received' (duration: 47.172628ms)","trace[115654573] 'applied index is now lower than readState.Index' (duration: 387.921332ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:17.777288Z","caller":"traceutil/trace.go:171","msg":"trace[2042073774] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"429.35971ms","start":"2026-10-26T00:58:17.347902Z","end":"2026-10-26T00:58:17.777262Z","steps":["trace[2042073774] 'process raft request' (duration: 429.176847ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.77735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.485372981s","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-10-26T00:58:17.777367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.488318196s","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-10-26T00:58:17.777462Z","caller":"traceutil/trace.go:171","msg":"trace[2050861499] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1210; }","duration":"1.485524574s","start":"2026-10-26T00:58:16.291923Z","end":"2026-10-26T00:58:17.777448Z","steps":["trace[2050861499] 'agreement among raft nodes before linearized reading' (duration: 1.485239027s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.777466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.347868Z","time spent":"429.465094ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:17.777473Z","caller":"traceutil/trace.go:171","msg":"trace[1988729184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1211; }","duration":"1.48845311s","start":"2026-10-26T00:58:16.289003Z","end":"2026-10-26T00:58:17.777456Z","steps":["trace[1988729184] 'agreement among raft nodes before linearized reading' (duration: 1.488239605s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.777499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.291912Z","time spent":"1.485577304s","remote":"127.0.0.1:51732","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-10-26T00:58:17.777507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.288973Z","time spent":"1.488526081s","remote":"127.0.0.1:51732","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-10-26T00:58:17.777505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779519679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:58:17.777561Z","caller":"traceutil/trace.go:171","msg":"trace[1193569648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1211; }","duration":"1.779665912s","start":"2026-10-26T00:58:15.997883Z","end":"2026-10-26T00:58:17.777548Z","steps":["trace[1193569648] 'agreement among raft nodes before linearized reading' (duration: 1.779385496s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.777598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:15.99785Z","time spent":"1.779737984s","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-10-26T00:58:17.777605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313618057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:17.777636Z","caller":"traceutil/trace.go:171","msg":"trace[1984309081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1211; }","duration":"1.313644438s","start":"2026-10-26T00:58:16.46398Z","end":"2026-10-26T00:58:17.777625Z","steps":["trace[1984309081] 'agreement among raft nodes before linearized reading' (duration: 1.31324085s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.777637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492847967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-10-26T00:58:17.77766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.463906Z","time spent":"1.31374867s","remote":"127.0.0.1:51486","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-10-26T00:58:17.777672Z","caller":"traceutil/trace.go:171","msg":"trace[136282688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"1.492920799s","start":"2026-10-26T00:58:16.284741Z","end":"2026-10-26T00:58:17.777661Z","steps":["trace[136282688] 'agreement among raft nodes before linearized reading' (duration: 1.492823297s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:17.777695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:16.284712Z","time spent":"1.492977331s","remote":"127.0.0.1:51732","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-10-26T00:58:18.089009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.950619ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:18.089219Z","caller":"traceutil/trace.go:171","msg":"trace[1890748974] linearizableReadLoop","detail":"{readStateIndex:1265; appliedIndex:1261; }","duration":"302.568227ms","start":"2026-10-26T00:58:17.786636Z","end":"2026-10-26T00:58:18.089204Z","steps":["trace[1890748974] 'read index received' (duration: 60.251172ms)","trace[1890748974] 'applied index is now lower than readState.Index' (duration: 242.316095ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:18.089221Z","caller":"traceutil/trace.go:171","msg":"trace[206438247] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"305.594044ms","start":"2026-10-26T00:58:17.783605Z","end":"2026-10-26T00:58:18.089199Z","steps":["trace[206438247] 'process raft request' (duration: 95.374949ms)","trace[206438247] 'compare' (duration: 209.824767ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:18.089305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.783564Z","time spent":"305.699097ms","remote":"127.0.0.1:51732","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-10-26T00:58:18.089334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.69416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:18.08936Z","caller":"traceutil/trace.go:171","msg":"trace[1740920813] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"302.742021ms","start":"2026-10-26T00:58:17.786609Z","end":"2026-10-26T00:58:18.089351Z","steps":["trace[1740920813] 'agreement among raft nodes before linearized reading' (duration: 302.641398ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:18.089455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.786599Z","time spent":"302.781431ms","remote":"127.0.0.1:51470","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-10-26T00:58:18.089595Z","caller":"traceutil/trace.go:171","msg":"trace[847617537] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"305.796429ms","start":"2026-10-26T00:58:17.783748Z","end":"2026-10-26T00:58:18.089544Z","steps":["trace[847617537] 'process raft request' (duration: 305.350379ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:18.089664Z","caller":"traceutil/trace.go:171","msg":"trace[538453038] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"305.680997ms","start":"2026-10-26T00:58:17.783969Z","end":"2026-10-26T00:58:18.08965Z","steps":["trace[538453038] 'process raft request' (duration: 305.197797ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:18.089722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.783961Z","time spent":"305.728508ms","remote":"127.0.0.1:51732","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-10-26T00:58:18.089839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:17.783699Z","time spent":"306.000753ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:19.282248Z","caller":"traceutil/trace.go:171","msg":"trace[435359630] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"921.312008ms","start":"2026-10-26T00:58:18.360903Z","end":"2026-10-26T00:58:19.282215Z","steps":["trace[435359630] 'process raft request' (duration: 921.112563ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:19.282477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:18.36087Z","time spent":"921.482671ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:58:20.080211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.383122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:20.080313Z","caller":"traceutil/trace.go:171","msg":"trace[365022197] linearizableReadLoop","detail":"{readStateIndex:1267; appliedIndex:1265; }","duration":"941.336987ms","start":"2026-10-26T00:58:19.138958Z","end":"2026-10-26T00:58:20.080295Z","steps":["trace[365022197] 'read index received' (duration: 143.146901ms)","trace[365022197] 'applied index is now lower than readState.Index' (duration: 798.188856ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:20.080397Z","caller":"traceutil/trace.go:171","msg":"trace[2058852959] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"1.575643216s","start":"2026-10-26T00:58:18.504742Z","end":"2026-10-26T00:58:20.080385Z","steps":["trace[2058852959] 'process raft request' (duration: 820.985227ms)","trace[2058852959] 'compare' (duration: 754.043275ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:20.080493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:18.504708Z","time spent":"1.575753897s","remote":"127.0.0.1:51732","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-10-26T00:58:20.080725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.772766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:20.080769Z","caller":"traceutil/trace.go:171","msg":"trace[445162786] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1217; }","duration":"941.870458ms","start":"2026-10-26T00:58:19.138885Z","end":"2026-10-26T00:58:20.080756Z","steps":["trace[445162786] 'agreement among raft nodes before linearized reading' (duration: 941.801376ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.0808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.138855Z","time spent":"941.935939ms","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:20.081033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.246542ms","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-10-26T00:58:20.081168Z","caller":"traceutil/trace.go:171","msg":"trace[1188438064] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1217; }","duration":"689.397206ms","start":"2026-10-26T00:58:19.391748Z","end":"2026-10-26T00:58:20.081145Z","steps":["trace[1188438064] 'agreement among raft nodes before linearized reading' (duration: 689.15257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.081269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.391666Z","time spent":"689.586789ms","remote":"127.0.0.1:59044","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":"warn","ts":"2026-10-26T00:58:20.081367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.046281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:58:20.081455Z","caller":"traceutil/trace.go:171","msg":"trace[567947088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"230.168564ms","start":"2026-10-26T00:58:19.851275Z","end":"2026-10-26T00:58:20.081444Z","steps":["trace[567947088] 'agreement among raft nodes before linearized reading' (duration: 230.032511ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.081273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.127358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-10-26T00:58:20.081712Z","caller":"traceutil/trace.go:171","msg":"trace[1909354601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"298.616298ms","start":"2026-10-26T00:58:19.783085Z","end":"2026-10-26T00:58:20.081702Z","steps":["trace[1909354601] 'agreement among raft nodes before linearized reading' (duration: 298.058876ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.081677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.021545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:20.081802Z","caller":"traceutil/trace.go:171","msg":"trace[1299191287] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"565.144698ms","start":"2026-10-26T00:58:19.516645Z","end":"2026-10-26T00:58:20.08179Z","steps":["trace[1299191287] 'agreement among raft nodes before linearized reading' (duration: 565.001325ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.081965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:19.516628Z","time spent":"565.25682ms","remote":"127.0.0.1:51470","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-10-26T00:58:20.593701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335640,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:58:20.756064Z","caller":"traceutil/trace.go:171","msg":"trace[153010482] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1267; }","duration":"663.313921ms","start":"2026-10-26T00:58:20.092727Z","end":"2026-10-26T00:58:20.756041Z","steps":["trace[153010482] 'read index received' (duration: 576.144355ms)","trace[153010482] 'applied index is now lower than readState.Index' (duration: 87.168386ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:20.756151Z","caller":"traceutil/trace.go:171","msg":"trace[976157025] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"667.114745ms","start":"2026-10-26T00:58:20.089008Z","end":"2026-10-26T00:58:20.756122Z","steps":["trace[976157025] 'process raft request' (duration: 579.746615ms)","trace[976157025] 'compare' (duration: 87.116985ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:20.756176Z","caller":"traceutil/trace.go:171","msg":"trace[303892210] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"665.355315ms","start":"2026-10-26T00:58:20.090811Z","end":"2026-10-26T00:58:20.756166Z","steps":["trace[303892210] 'process raft request' (duration: 665.187322ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.756271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.090796Z","time spent":"665.400536ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:20.756272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.088978Z","time spent":"667.219378ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:20.756283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.167602ms","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-10-26T00:58:20.756328Z","caller":"traceutil/trace.go:171","msg":"trace[237841587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"661.234563ms","start":"2026-10-26T00:58:20.09508Z","end":"2026-10-26T00:58:20.756315Z","steps":["trace[237841587] 'agreement among raft nodes before linearized reading' (duration: 661.0949ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.756357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.095068Z","time spent":"661.282264ms","remote":"127.0.0.1:51732","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-10-26T00:58:20.756453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.528341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-26T00:58:20.75648Z","caller":"traceutil/trace.go:171","msg":"trace[395281000] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1219; }","duration":"152.560222ms","start":"2026-10-26T00:58:20.603911Z","end":"2026-10-26T00:58:20.756472Z","steps":["trace[395281000] 'agreement among raft nodes before linearized reading' (duration: 152.47884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.756507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.272574ms","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-10-26T00:58:20.756537Z","caller":"traceutil/trace.go:171","msg":"trace[1046083508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"661.347765ms","start":"2026-10-26T00:58:20.09518Z","end":"2026-10-26T00:58:20.756528Z","steps":["trace[1046083508] 'agreement among raft nodes before linearized reading' (duration: 661.266713ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.756559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.095162Z","time spent":"661.391697ms","remote":"127.0.0.1:51732","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-10-26T00:58:20.756694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.962825ms","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-10-26T00:58:20.75672Z","caller":"traceutil/trace.go:171","msg":"trace[1744877194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"664.010605ms","start":"2026-10-26T00:58:20.092701Z","end":"2026-10-26T00:58:20.756712Z","steps":["trace[1744877194] 'agreement among raft nodes before linearized reading' (duration: 663.928883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:20.756743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.092686Z","time spent":"664.050746ms","remote":"127.0.0.1:51732","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-10-26T00:58:20.968163Z","caller":"traceutil/trace.go:171","msg":"trace[1681096494] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"205.94487ms","start":"2026-10-26T00:58:20.762189Z","end":"2026-10-26T00:58:20.968134Z","steps":["trace[1681096494] 'process raft request' (duration: 114.512069ms)","trace[1681096494] 'compare' (duration: 91.273357ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:21.426628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T00:58:21.927055Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335644,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-10-26T00:58:22.126082Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.249450767s","expected-duration":"1s"} {"level":"info","ts":"2026-10-26T00:58:22.126875Z","caller":"traceutil/trace.go:171","msg":"trace[1523525127] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1270; }","duration":"1.200748186s","start":"2026-10-26T00:58:20.926108Z","end":"2026-10-26T00:58:22.126856Z","steps":["trace[1523525127] 'read index received' (duration: 1.200356437s)","trace[1523525127] 'applied index is now lower than readState.Index' (duration: 389.789µs)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:22.126776Z","caller":"traceutil/trace.go:171","msg":"trace[45429158] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"1.364400847s","start":"2026-10-26T00:58:20.762324Z","end":"2026-10-26T00:58:22.126725Z","steps":["trace[45429158] 'process raft request' (duration: 1.364183482s)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:22.127007Z","caller":"traceutil/trace.go:171","msg":"trace[1471962176] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"1.364477359s","start":"2026-10-26T00:58:20.762426Z","end":"2026-10-26T00:58:22.126904Z","steps":["trace[1471962176] 'process raft request' (duration: 1.364231944s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:22.127168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.762308Z","time spent":"1.364785205s","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:22.127067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20093075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:22.127251Z","caller":"traceutil/trace.go:171","msg":"trace[1379217896] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1222; }","duration":"1.201173605s","start":"2026-10-26T00:58:20.926051Z","end":"2026-10-26T00:58:22.127225Z","steps":["trace[1379217896] 'agreement among raft nodes before linearized reading' (duration: 1.200885659s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:22.127317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.926031Z","time spent":"1.201270787s","remote":"127.0.0.1:59080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:22.127498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.762381Z","time spent":"1.364798696s","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:22.144194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162109969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:22.144301Z","caller":"traceutil/trace.go:171","msg":"trace[1277319313] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1223; }","duration":"1.162273963s","start":"2026-10-26T00:58:20.982002Z","end":"2026-10-26T00:58:22.144276Z","steps":["trace[1277319313] 'agreement among raft nodes before linearized reading' (duration: 1.162063308s)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:22.144357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:20.98199Z","time spent":"1.162350464s","remote":"127.0.0.1:58992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:22.144353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.614335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:22.144469Z","caller":"traceutil/trace.go:171","msg":"trace[427638821] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1223; }","duration":"975.784339ms","start":"2026-10-26T00:58:21.168662Z","end":"2026-10-26T00:58:22.144446Z","steps":["trace[427638821] 'agreement among raft nodes before linearized reading' (duration: 975.601015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:22.144521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:21.16864Z","time spent":"975.868322ms","remote":"127.0.0.1:59142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:22.144224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.57448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:22.144801Z","caller":"traceutil/trace.go:171","msg":"trace[1859238918] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1223; }","duration":"629.181625ms","start":"2026-10-26T00:58:21.515599Z","end":"2026-10-26T00:58:22.14478Z","steps":["trace[1859238918] 'agreement among raft nodes before linearized reading' (duration: 628.56108ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:22.144869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:21.515576Z","time spent":"629.279447ms","remote":"127.0.0.1:51470","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-10-26T00:58:22.14467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.70217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:58:22.145302Z","caller":"traceutil/trace.go:171","msg":"trace[1926212274] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:1223; }","duration":"299.399406ms","start":"2026-10-26T00:58:21.845877Z","end":"2026-10-26T00:58:22.145276Z","steps":["trace[1926212274] 'agreement among raft nodes before linearized reading' (duration: 298.23571ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.069936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.821804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:23.070034Z","caller":"traceutil/trace.go:171","msg":"trace[1211182212] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1224; }","duration":"553.983097ms","start":"2026-10-26T00:58:22.516032Z","end":"2026-10-26T00:58:23.070015Z","steps":["trace[1211182212] 'range keys from in-memory index tree' (duration: 553.722451ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.070073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:22.516007Z","time spent":"554.056398ms","remote":"127.0.0.1:51470","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-10-26T00:58:23.070206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.417849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:58:23.070285Z","caller":"traceutil/trace.go:171","msg":"trace[77920358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"308.538202ms","start":"2026-10-26T00:58:22.761727Z","end":"2026-10-26T00:58:23.070265Z","steps":["trace[77920358] 'range keys from in-memory index tree' (duration: 308.246195ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.070206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.420679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-26T00:58:23.070333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:22.761705Z","time spent":"308.606974ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-10-26T00:58:23.07036Z","caller":"traceutil/trace.go:171","msg":"trace[476527309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"308.603763ms","start":"2026-10-26T00:58:22.761727Z","end":"2026-10-26T00:58:23.07033Z","steps":["trace[476527309] 'range keys from in-memory index tree' (duration: 308.210124ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.070476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:22.761712Z","time spent":"308.731577ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-26T00:58:23.666074Z","caller":"traceutil/trace.go:171","msg":"trace[1851614445] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"591.54818ms","start":"2026-10-26T00:58:23.074498Z","end":"2026-10-26T00:58:23.666046Z","steps":["trace[1851614445] 'process raft request' (duration: 591.326835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:23.666264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.074471Z","time spent":"591.689812ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:24.061144Z","caller":"traceutil/trace.go:171","msg":"trace[154281559] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"986.337876ms","start":"2026-10-26T00:58:23.074776Z","end":"2026-10-26T00:58:24.061114Z","steps":["trace[154281559] 'process raft request' (duration: 986.118211ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.061354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.074758Z","time spent":"986.473179ms","remote":"127.0.0.1:51732","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-10-26T00:58:24.061367Z","caller":"traceutil/trace.go:171","msg":"trace[1159698693] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1275; }","duration":"545.853045ms","start":"2026-10-26T00:58:23.515472Z","end":"2026-10-26T00:58:24.061325Z","steps":["trace[1159698693] 'read index received' (duration: 150.329213ms)","trace[1159698693] 'applied index is now lower than readState.Index' (duration: 395.521052ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:24.06157Z","caller":"traceutil/trace.go:171","msg":"trace[1852674846] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"986.02658ms","start":"2026-10-26T00:58:23.075531Z","end":"2026-10-26T00:58:24.061558Z","steps":["trace[1852674846] 'process raft request' (duration: 985.543848ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.061611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.147091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:24.061659Z","caller":"traceutil/trace.go:171","msg":"trace[1765078523] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:1227; }","duration":"348.308094ms","start":"2026-10-26T00:58:23.713338Z","end":"2026-10-26T00:58:24.061647Z","steps":["trace[1765078523] 'agreement among raft nodes before linearized reading' (duration: 348.227452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.061697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.71331Z","time spent":"348.377566ms","remote":"127.0.0.1:58988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:24.061745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.07552Z","time spent":"986.090971ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:24.061833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.350207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:24.061923Z","caller":"traceutil/trace.go:171","msg":"trace[204115455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"546.448519ms","start":"2026-10-26T00:58:23.515455Z","end":"2026-10-26T00:58:24.061904Z","steps":["trace[204115455] 'agreement among raft nodes before linearized reading' (duration: 546.309766ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.061968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:23.515368Z","time spent":"546.591582ms","remote":"127.0.0.1:51486","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-10-26T00:58:24.455349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.009952ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:24.455659Z","caller":"traceutil/trace.go:171","msg":"trace[1707448767] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"385.918198ms","start":"2026-10-26T00:58:24.069655Z","end":"2026-10-26T00:58:24.455573Z","steps":["trace[1707448767] 'process raft request' (duration: 261.608449ms)","trace[1707448767] 'compare' (duration: 123.558001ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:24.455744Z","caller":"traceutil/trace.go:171","msg":"trace[1386763190] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"305.697497ms","start":"2026-10-26T00:58:24.150028Z","end":"2026-10-26T00:58:24.455725Z","steps":["trace[1386763190] 'process raft request' (duration: 305.546554ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:24.455705Z","caller":"traceutil/trace.go:171","msg":"trace[211870536] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"324.081059ms","start":"2026-10-26T00:58:24.13159Z","end":"2026-10-26T00:58:24.455671Z","steps":["trace[211870536] 'read index received' (duration: 199.674908ms)","trace[211870536] 'applied index is now lower than readState.Index' (duration: 124.402821ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:24.455841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.247273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-10-26T00:58:24.455889Z","caller":"traceutil/trace.go:171","msg":"trace[898464860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"324.331844ms","start":"2026-10-26T00:58:24.131541Z","end":"2026-10-26T00:58:24.455873Z","steps":["trace[898464860] 'agreement among raft nodes before linearized reading' (duration: 324.214842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.455897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.150012Z","time spent":"305.798299ms","remote":"127.0.0.1:51732","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-10-26T00:58:24.455914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.131518Z","time spent":"324.389907ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-26T00:58:24.455939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.069614Z","time spent":"386.145052ms","remote":"127.0.0.1:51562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-26T00:58:24.456245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.959844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-10-26T00:58:24.456327Z","caller":"traceutil/trace.go:171","msg":"trace[43015818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"323.106347ms","start":"2026-10-26T00:58:24.133209Z","end":"2026-10-26T00:58:24.456315Z","steps":["trace[43015818] 'agreement among raft nodes before linearized reading' (duration: 322.903582ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.456489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:24.133187Z","time spent":"323.289302ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-10-26T00:58:24.675704Z","caller":"traceutil/trace.go:171","msg":"trace[355304399] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1281; }","duration":"161.743528ms","start":"2026-10-26T00:58:24.513932Z","end":"2026-10-26T00:58:24.675676Z","steps":["trace[355304399] 'read index received' (duration: 15.1735ms)","trace[355304399] 'applied index is now lower than readState.Index' (duration: 146.568678ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:58:24.675862Z","caller":"traceutil/trace.go:171","msg":"trace[1000819726] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"198.471183ms","start":"2026-10-26T00:58:24.477356Z","end":"2026-10-26T00:58:24.675828Z","steps":["trace[1000819726] 'process raft request' (duration: 198.037243ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:24.675936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.951022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:24.675981Z","caller":"traceutil/trace.go:171","msg":"trace[1155245038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1233; }","duration":"162.082226ms","start":"2026-10-26T00:58:24.513885Z","end":"2026-10-26T00:58:24.675968Z","steps":["trace[1155245038] 'agreement among raft nodes before linearized reading' (duration: 161.903892ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:24.676212Z","caller":"traceutil/trace.go:171","msg":"trace[1726051997] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"196.203091ms","start":"2026-10-26T00:58:24.479957Z","end":"2026-10-26T00:58:24.67616Z","steps":["trace[1726051997] 'process raft request' (duration: 195.661249ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:25.278491Z","caller":"traceutil/trace.go:171","msg":"trace[1694775277] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"107.86403ms","start":"2026-10-26T00:58:25.170607Z","end":"2026-10-26T00:58:25.278471Z","steps":["trace[1694775277] 'process raft request' (duration: 107.703666ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:27.713578Z","caller":"traceutil/trace.go:171","msg":"trace[1576723941] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"169.687216ms","start":"2026-10-26T00:58:27.543844Z","end":"2026-10-26T00:58:27.713531Z","steps":["trace[1576723941] 'process raft request' (duration: 169.281277ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:30.915265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.102174ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-26T00:58:30.915642Z","caller":"traceutil/trace.go:171","msg":"trace[529732294] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"337.531792ms","start":"2026-10-26T00:58:30.578076Z","end":"2026-10-26T00:58:30.915608Z","steps":["trace[529732294] 'process raft request' (duration: 68.946276ms)","trace[529732294] 'compare' (duration: 266.866247ms)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:30.915754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:30.578026Z","time spent":"337.678585ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-26T00:58:30.915834Z","caller":"traceutil/trace.go:171","msg":"trace[1074262900] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"328.938159ms","start":"2026-10-26T00:58:30.586861Z","end":"2026-10-26T00:58:30.915799Z","steps":["trace[1074262900] 'process raft request' (duration: 328.576321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:30.917223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:30.586829Z","time spent":"329.092792ms","remote":"127.0.0.1:51900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:31.763862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.120916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:31.763953Z","caller":"traceutil/trace.go:171","msg":"trace[1499135905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"248.26074ms","start":"2026-10-26T00:58:31.515667Z","end":"2026-10-26T00:58:31.763928Z","steps":["trace[1499135905] 'range keys from in-memory index tree' (duration: 248.040355ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:31.763985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.872391ms","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-10-26T00:58:31.76408Z","caller":"traceutil/trace.go:171","msg":"trace[284709270] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1290; }","duration":"713.046946ms","start":"2026-10-26T00:58:31.051009Z","end":"2026-10-26T00:58:31.764056Z","steps":["trace[284709270] 'count revisions from in-memory index tree' (duration: 712.786469ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:31.764127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:31.050987Z","time spent":"713.127008ms","remote":"127.0.0.1:59058","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":"warn","ts":"2026-10-26T00:58:32.276222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9882201009277335775,"retry-timeout":"500ms"} {"level":"info","ts":"2026-10-26T00:58:32.289011Z","caller":"traceutil/trace.go:171","msg":"trace[1159896023] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"513.099061ms","start":"2026-10-26T00:58:31.775884Z","end":"2026-10-26T00:58:32.288983Z","steps":["trace[1159896023] 'read index received' (duration: 513.024899ms)","trace[1159896023] 'applied index is now lower than readState.Index' (duration: 72.762µs)"],"step_count":2} {"level":"warn","ts":"2026-10-26T00:58:32.289361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.457588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-10-26T00:58:32.289463Z","caller":"traceutil/trace.go:171","msg":"trace[2113510391] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1291; }","duration":"513.623192ms","start":"2026-10-26T00:58:31.775827Z","end":"2026-10-26T00:58:32.28945Z","steps":["trace[2113510391] 'agreement among raft nodes before linearized reading' (duration: 513.280655ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:32.289528Z","caller":"traceutil/trace.go:171","msg":"trace[1857083940] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"520.100847ms","start":"2026-10-26T00:58:31.769379Z","end":"2026-10-26T00:58:32.28948Z","steps":["trace[1857083940] 'process raft request' (duration: 519.422202ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:32.2895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:31.775807Z","time spent":"513.681423ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-10-26T00:58:32.289749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:31.769339Z","time spent":"520.266ms","remote":"127.0.0.1:51732","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-26T00:58:32.289782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.783013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-10-26T00:58:32.289781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.604741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-26T00:58:32.289834Z","caller":"traceutil/trace.go:171","msg":"trace[679597804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1291; }","duration":"187.898305ms","start":"2026-10-26T00:58:32.10192Z","end":"2026-10-26T00:58:32.289818Z","steps":["trace[679597804] 'agreement among raft nodes before linearized reading' (duration: 187.6764ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:32.289822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.845397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-26T00:58:32.289872Z","caller":"traceutil/trace.go:171","msg":"trace[1221448701] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1291; }","duration":"398.782846ms","start":"2026-10-26T00:58:31.891063Z","end":"2026-10-26T00:58:32.289846Z","steps":["trace[1221448701] 'agreement among raft nodes before linearized reading' (duration: 398.588711ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:58:32.289931Z","caller":"traceutil/trace.go:171","msg":"trace[2024577314] range","detail":"{range_begin:/registry/masterleases/162.253.55.105; range_end:; response_count:1; response_revision:1291; }","duration":"444.024561ms","start":"2026-10-26T00:58:31.845881Z","end":"2026-10-26T00:58:32.289906Z","steps":["trace[2024577314] 'agreement among raft nodes before linearized reading' (duration: 443.667893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:58:32.290001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:31.891029Z","time spent":"398.96277ms","remote":"127.0.0.1:58874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-10-26T00:58:32.290018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-26T00:58:31.845861Z","time spent":"444.143943ms","remote":"127.0.0.1:51524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.105\" "} {"level":"warn","ts":"2026-10-26T00:58:32.289999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.740951ms","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-10-26T00:58:32.290094Z","caller":"traceutil/trace.go:171","msg":"trace[2099238196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1291; }","duration":"187.868774ms","start":"2026-10-26T00:58:32.1022Z","end":"2026-10-26T00:58:32.290069Z","steps":["trace[2099238196] 'agreement among raft nodes before linearized reading' (duration: 187.515377ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:39.079974Z","caller":"traceutil/trace.go:171","msg":"trace[975604827] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"263.561911ms","start":"2026-10-26T00:59:38.816379Z","end":"2026-10-26T00:59:39.079941Z","steps":["trace[975604827] 'process raft request' (duration: 263.342216ms)"],"step_count":1} {"level":"info","ts":"2026-10-26T00:59:39.080332Z","caller":"traceutil/trace.go:171","msg":"trace[776895941] linearizableReadLoop","detail":"{readStateIndex:1665; appliedIndex:1663; }","duration":"239.500779ms","start":"2026-10-26T00:59:38.840797Z","end":"2026-10-26T00:59:39.080298Z","steps":["trace[776895941] 'read index received' (duration: 45.033887ms)","trace[776895941] 'applied index is now lower than readState.Index' (duration: 194.465002ms)"],"step_count":2} {"level":"info","ts":"2026-10-26T00:59:39.080528Z","caller":"traceutil/trace.go:171","msg":"trace[1546913920] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"217.86807ms","start":"2026-10-26T00:59:38.862643Z","end":"2026-10-26T00:59:39.080511Z","steps":["trace[1546913920] 'process raft request' (duration: 217.251246ms)"],"step_count":1} {"level":"warn","ts":"2026-10-26T00:59:39.080813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.997529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-26T00:59:39.080867Z","caller":"traceutil/trace.go:171","msg":"trace[963064577] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1600; }","duration":"240.123372ms","start":"2026-10-26T00:59:38.840727Z","end":"2026-10-26T00:59:39.080851Z","steps":["trace[963064577] 'agreement among raft nodes before linearized reading' (duration: 239.998739ms)"],"step_count":1}