{"level":"warn","ts":"2026-03-13T23:05:43.829189Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:05:43.829271Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-03-13T23:05:43.82936Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-13T23:05:43.829391Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-13T23:05:43.829424Z","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-03-13T23:05:43.830034Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-03-13T23:05:43.83013Z","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.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-03-13T23:05:43.834619Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.22939ms"} {"level":"info","ts":"2026-03-13T23:05:43.840502Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-03-13T23:05:43.840591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-03-13T23:05:43.840619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-03-13T23:05:43.840635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-13T23:05:43.84067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-03-13T23:05:43.840725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-03-13T23:05:43.8516Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-13T23:05:43.853072Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-13T23:05:43.8558Z","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-03-13T23:05:43.858713Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-13T23:05:43.859852Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-13T23:05:43.860045Z","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-03-13T23:05:43.863845Z","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-03-13T23:05:43.863938Z","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-03-13T23:05:43.864185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-03-13T23:05:43.87112Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-03-13T23:05:43.872078Z","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-03-13T23:05:43.872263Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-13T23:05:43.872288Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-13T23:05:43.872237Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-13T23:05:43.87235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-03-13T23:05:44.141586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-03-13T23:05:44.141655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-03-13T23:05:44.141681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-03-13T23:05:44.141696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-03-13T23:05:44.141704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-13T23:05:44.14172Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-03-13T23:05:44.141734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-03-13T23:05:44.14504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:44.145321Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-03-13T23:05:44.145477Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:05:44.145503Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-13T23:05:44.145698Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-13T23:05:44.145757Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-13T23:05:44.147538Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-13T23:05:44.148081Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-03-13T23:05:44.151099Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:44.151234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:05:44.151277Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-13T23:06:05.267565Z","caller":"traceutil/trace.go:171","msg":"trace[1980616716] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"157.232537ms","start":"2026-03-13T23:06:05.110307Z","end":"2026-03-13T23:06:05.267539Z","steps":["trace[1980616716] 'process raft request' (duration: 157.102824ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:05.869293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.457557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-13T23:06:05.869424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.073654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:05.869478Z","caller":"traceutil/trace.go:171","msg":"trace[889205483] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:456; }","duration":"133.693063ms","start":"2026-03-13T23:06:05.735765Z","end":"2026-03-13T23:06:05.869458Z","steps":["trace[889205483] 'range keys from in-memory index tree' (duration: 133.341214ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:05.869523Z","caller":"traceutil/trace.go:171","msg":"trace[950882676] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:456; }","duration":"394.213318ms","start":"2026-03-13T23:06:05.47529Z","end":"2026-03-13T23:06:05.869504Z","steps":["trace[950882676] 'range keys from in-memory index tree' (duration: 394.004383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:05.869578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:05.475234Z","time spent":"394.33168ms","remote":"127.0.0.1:57200","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-03-13T23:06:07.452406Z","caller":"traceutil/trace.go:171","msg":"trace[1536837464] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"111.919351ms","start":"2026-03-13T23:06:07.340454Z","end":"2026-03-13T23:06:07.452373Z","steps":["trace[1536837464] 'process raft request' (duration: 81.370393ms)","trace[1536837464] 'compare' (duration: 30.357024ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:07.50917Z","caller":"traceutil/trace.go:171","msg":"trace[699068678] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"166.171777ms","start":"2026-03-13T23:06:07.342973Z","end":"2026-03-13T23:06:07.509145Z","steps":["trace[699068678] 'process raft request' (duration: 165.814049ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:08.018376Z","caller":"traceutil/trace.go:171","msg":"trace[2072268403] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"134.364979ms","start":"2026-03-13T23:06:07.883984Z","end":"2026-03-13T23:06:08.018349Z","steps":["trace[2072268403] 'process raft request' (duration: 134.223596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:08.274503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.870242ms","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-03-13T23:06:08.274616Z","caller":"traceutil/trace.go:171","msg":"trace[97998367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"104.041616ms","start":"2026-03-13T23:06:08.170535Z","end":"2026-03-13T23:06:08.274577Z","steps":["trace[97998367] 'range keys from in-memory index tree' (duration: 103.682278ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:11.867314Z","caller":"traceutil/trace.go:171","msg":"trace[692274498] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"343.325521ms","start":"2026-03-13T23:06:11.523961Z","end":"2026-03-13T23:06:11.867286Z","steps":["trace[692274498] 'process raft request' (duration: 343.195308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:11.868114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:11.523923Z","time spent":"343.479594ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:13.039977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005804084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-13T23:06:13.040043Z","caller":"traceutil/trace.go:171","msg":"trace[1123989183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"1.005946417s","start":"2026-03-13T23:06:12.034083Z","end":"2026-03-13T23:06:13.040029Z","steps":["trace[1123989183] 'range keys from in-memory index tree' (duration: 1.005682342s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:13.040069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:12.034052Z","time spent":"1.006012309s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-13T23:06:13.04015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.483043ms","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-03-13T23:06:13.040224Z","caller":"traceutil/trace.go:171","msg":"trace[2094345239] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"662.606856ms","start":"2026-03-13T23:06:12.377599Z","end":"2026-03-13T23:06:13.040206Z","steps":["trace[2094345239] 'range keys from in-memory index tree' (duration: 662.34154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:13.040282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:12.377552Z","time spent":"662.721339ms","remote":"127.0.0.1:57436","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-03-13T23:06:13.275503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.312415ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:06:13.275747Z","caller":"traceutil/trace.go:171","msg":"trace[1615927097] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"232.014594ms","start":"2026-03-13T23:06:13.043714Z","end":"2026-03-13T23:06:13.275728Z","steps":["trace[1615927097] 'process raft request' (duration: 231.891161ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:13.27591Z","caller":"traceutil/trace.go:171","msg":"trace[1938447449] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"796.448672ms","start":"2026-03-13T23:06:12.479425Z","end":"2026-03-13T23:06:13.275874Z","steps":["trace[1938447449] 'read index received' (duration: 405.521443ms)","trace[1938447449] 'applied index is now lower than readState.Index' (duration: 390.921019ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:13.276082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.637327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:13.276104Z","caller":"traceutil/trace.go:171","msg":"trace[67114604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:472; }","duration":"796.703859ms","start":"2026-03-13T23:06:12.479394Z","end":"2026-03-13T23:06:13.276098Z","steps":["trace[67114604] 'agreement among raft nodes before linearized reading' (duration: 796.627657ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:13.27613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:12.479374Z","time spent":"796.750849ms","remote":"127.0.0.1:57200","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-03-13T23:06:13.286493Z","caller":"traceutil/trace.go:171","msg":"trace[854286368] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"242.705425ms","start":"2026-03-13T23:06:13.043772Z","end":"2026-03-13T23:06:13.286478Z","steps":["trace[854286368] 'process raft request' (duration: 242.49183ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:13.28668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.790114ms","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-03-13T23:06:13.286707Z","caller":"traceutil/trace.go:171","msg":"trace[1773757855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"603.866946ms","start":"2026-03-13T23:06:12.682832Z","end":"2026-03-13T23:06:13.286699Z","steps":["trace[1773757855] 'agreement among raft nodes before linearized reading' (duration: 603.804545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:13.286724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:12.682814Z","time spent":"603.904477ms","remote":"127.0.0.1:57436","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-03-13T23:06:15.18322Z","caller":"traceutil/trace.go:171","msg":"trace[339519934] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"503.847154ms","start":"2026-03-13T23:06:14.679346Z","end":"2026-03-13T23:06:15.183193Z","steps":["trace[339519934] 'process raft request' (duration: 503.456485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:15.18338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:14.679328Z","time spent":"503.972957ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:06:15.46072Z","caller":"traceutil/trace.go:171","msg":"trace[1297876307] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"181.712281ms","start":"2026-03-13T23:06:15.278988Z","end":"2026-03-13T23:06:15.4607Z","steps":["trace[1297876307] 'read index received' (duration: 176.95254ms)","trace[1297876307] 'applied index is now lower than readState.Index' (duration: 4.758861ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:15.460846Z","caller":"traceutil/trace.go:171","msg":"trace[911234898] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"190.858407ms","start":"2026-03-13T23:06:15.269939Z","end":"2026-03-13T23:06:15.460797Z","steps":["trace[911234898] 'process raft request' (duration: 185.957982ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:15.460884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.886105ms","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-03-13T23:06:15.460996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.401596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-13T23:06:15.461038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.919807ms","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-03-13T23:06:15.461077Z","caller":"traceutil/trace.go:171","msg":"trace[670530516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:479; }","duration":"170.553429ms","start":"2026-03-13T23:06:15.290509Z","end":"2026-03-13T23:06:15.461063Z","steps":["trace[670530516] 'agreement among raft nodes before linearized reading' (duration: 170.379495ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:15.461125Z","caller":"traceutil/trace.go:171","msg":"trace[766289919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:479; }","duration":"145.05209ms","start":"2026-03-13T23:06:15.316055Z","end":"2026-03-13T23:06:15.461107Z","steps":["trace[766289919] 'agreement among raft nodes before linearized reading' (duration: 144.868376ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:15.461011Z","caller":"traceutil/trace.go:171","msg":"trace[608619055] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:479; }","duration":"182.045509ms","start":"2026-03-13T23:06:15.278938Z","end":"2026-03-13T23:06:15.460984Z","steps":["trace[608619055] 'agreement among raft nodes before linearized reading' (duration: 181.858955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:15.901589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.458559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:15.901889Z","caller":"traceutil/trace.go:171","msg":"trace[944406455] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:496; }","duration":"428.37913ms","start":"2026-03-13T23:06:15.473486Z","end":"2026-03-13T23:06:15.901865Z","steps":["trace[944406455] 'read index received' (duration: 12.547565ms)","trace[944406455] 'applied index is now lower than readState.Index' (duration: 415.830145ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:15.901974Z","caller":"traceutil/trace.go:171","msg":"trace[1243887688] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"436.213324ms","start":"2026-03-13T23:06:15.465749Z","end":"2026-03-13T23:06:15.901962Z","steps":["trace[1243887688] 'process raft request' (duration: 435.949258ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:15.902005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.512453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:06:15.902023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.465743Z","time spent":"436.257015ms","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:06:15.902047Z","caller":"traceutil/trace.go:171","msg":"trace[1992983244] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:484; }","duration":"428.574404ms","start":"2026-03-13T23:06:15.473459Z","end":"2026-03-13T23:06:15.902034Z","steps":["trace[1992983244] 'agreement among raft nodes before linearized reading' (duration: 428.476122ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:15.902043Z","caller":"traceutil/trace.go:171","msg":"trace[2084982330] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"435.902017ms","start":"2026-03-13T23:06:15.466119Z","end":"2026-03-13T23:06:15.902021Z","steps":["trace[2084982330] 'process raft request' (duration: 435.689012ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:15.902071Z","caller":"traceutil/trace.go:171","msg":"trace[1920456617] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"437.32682ms","start":"2026-03-13T23:06:15.464727Z","end":"2026-03-13T23:06:15.902054Z","steps":["trace[1920456617] 'process raft request' (duration: 257.300129ms)","trace[1920456617] 'compare' (duration: 179.323875ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:15.902093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.473453Z","time spent":"428.626156ms","remote":"127.0.0.1:57200","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-03-13T23:06:15.902075Z","caller":"traceutil/trace.go:171","msg":"trace[1827959584] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"436.248515ms","start":"2026-03-13T23:06:15.465791Z","end":"2026-03-13T23:06:15.90204Z","steps":["trace[1827959584] 'process raft request' (duration: 435.974149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:15.902132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.464707Z","time spent":"437.392581ms","remote":"127.0.0.1:57436","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-03-13T23:06:15.902155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.466113Z","time spent":"435.975428ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:15.902193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.465788Z","time spent":"436.353727ms","remote":"127.0.0.1:57516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:16.406872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413368901,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:06:16.693318Z","caller":"traceutil/trace.go:171","msg":"trace[533840554] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"787.206576ms","start":"2026-03-13T23:06:15.906089Z","end":"2026-03-13T23:06:16.693295Z","steps":["trace[533840554] 'process raft request' (duration: 787.091143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:16.693462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.906073Z","time spent":"787.315428ms","remote":"127.0.0.1:57528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:16.95448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.209602ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:16.954729Z","caller":"traceutil/trace.go:171","msg":"trace[806130456] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:500; }","duration":"1.048129009s","start":"2026-03-13T23:06:15.906576Z","end":"2026-03-13T23:06:16.954705Z","steps":["trace[806130456] 'read index received' (duration: 786.631922ms)","trace[806130456] 'applied index is now lower than readState.Index' (duration: 261.495857ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:16.954746Z","caller":"traceutil/trace.go:171","msg":"trace[1728280504] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"824.755909ms","start":"2026-03-13T23:06:16.129964Z","end":"2026-03-13T23:06:16.95472Z","steps":["trace[1728280504] 'process raft request' (duration: 824.639866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:16.955954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049362699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:16.955786Z","caller":"traceutil/trace.go:171","msg":"trace[961620678] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.048413667s","start":"2026-03-13T23:06:15.906298Z","end":"2026-03-13T23:06:16.954712Z","steps":["trace[961620678] 'process raft request' (duration: 914.888108ms)","trace[961620678] 'compare' (duration: 133.026717ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:16.956073Z","caller":"traceutil/trace.go:171","msg":"trace[1110340045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:487; }","duration":"1.049478452s","start":"2026-03-13T23:06:15.906552Z","end":"2026-03-13T23:06:16.95603Z","steps":["trace[1110340045] 'agreement among raft nodes before linearized reading' (duration: 1.049334528s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:16.956152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.906544Z","time spent":"1.049594904s","remote":"127.0.0.1:57214","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-03-13T23:06:16.956182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.906289Z","time spent":"1.049807879s","remote":"127.0.0.1:57528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":734,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:16.956325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:16.129925Z","time spent":"826.08431ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:16.957344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030837703s","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-03-13T23:06:16.958869Z","caller":"traceutil/trace.go:171","msg":"trace[590338003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"1.030959276s","start":"2026-03-13T23:06:15.926473Z","end":"2026-03-13T23:06:16.957432Z","steps":["trace[590338003] 'agreement among raft nodes before linearized reading' (duration: 1.030779912s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:16.958991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:15.926464Z","time spent":"1.032509863s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:06:17.397291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.799403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:17.397518Z","caller":"traceutil/trace.go:171","msg":"trace[67036572] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:503; }","duration":"430.774305ms","start":"2026-03-13T23:06:16.96672Z","end":"2026-03-13T23:06:17.397494Z","steps":["trace[67036572] 'read index received' (duration: 97.660394ms)","trace[67036572] 'applied index is now lower than readState.Index' (duration: 333.112541ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:17.397697Z","caller":"traceutil/trace.go:171","msg":"trace[1633355852] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"433.333585ms","start":"2026-03-13T23:06:16.964336Z","end":"2026-03-13T23:06:17.397669Z","steps":["trace[1633355852] 'process raft request' (duration: 100.04601ms)","trace[1633355852] 'compare' (duration: 332.574658ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:17.397737Z","caller":"traceutil/trace.go:171","msg":"trace[941862413] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"433.038798ms","start":"2026-03-13T23:06:16.964638Z","end":"2026-03-13T23:06:17.397677Z","steps":["trace[941862413] 'process raft request' (duration: 432.781772ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:17.397789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.058482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:06:17.397827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.711488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T23:06:17.397889Z","caller":"traceutil/trace.go:171","msg":"trace[1571433923] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:489; }","duration":"203.810511ms","start":"2026-03-13T23:06:17.194064Z","end":"2026-03-13T23:06:17.397875Z","steps":["trace[1571433923] 'agreement among raft nodes before linearized reading' (duration: 203.646587ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:17.397885Z","caller":"traceutil/trace.go:171","msg":"trace[1863452186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"431.168485ms","start":"2026-03-13T23:06:16.966697Z","end":"2026-03-13T23:06:17.397865Z","steps":["trace[1863452186] 'agreement among raft nodes before linearized reading' (duration: 430.96282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:17.397989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:16.964625Z","time spent":"433.247374ms","remote":"127.0.0.1:57494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:17.397821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:16.964328Z","time spent":"433.425448ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:17.398013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:16.96669Z","time spent":"431.274907ms","remote":"127.0.0.1:57200","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-03-13T23:06:17.690008Z","caller":"traceutil/trace.go:171","msg":"trace[1921136809] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"289.116406ms","start":"2026-03-13T23:06:17.40086Z","end":"2026-03-13T23:06:17.689977Z","steps":["trace[1921136809] 'process raft request' (duration: 288.969593ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:17.716247Z","caller":"traceutil/trace.go:171","msg":"trace[902573832] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"226.462303ms","start":"2026-03-13T23:06:17.489753Z","end":"2026-03-13T23:06:17.716215Z","steps":["trace[902573832] 'read index received' (duration: 200.137354ms)","trace[902573832] 'applied index is now lower than readState.Index' (duration: 26.323449ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:17.716293Z","caller":"traceutil/trace.go:171","msg":"trace[442593677] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"229.372132ms","start":"2026-03-13T23:06:17.486894Z","end":"2026-03-13T23:06:17.716266Z","steps":["trace[442593677] 'process raft request' (duration: 229.139917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:17.716454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.690149ms","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-03-13T23:06:17.716509Z","caller":"traceutil/trace.go:171","msg":"trace[1058351235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:491; }","duration":"226.800792ms","start":"2026-03-13T23:06:17.489695Z","end":"2026-03-13T23:06:17.716495Z","steps":["trace[1058351235] 'agreement among raft nodes before linearized reading' (duration: 226.643728ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:17.964557Z","caller":"traceutil/trace.go:171","msg":"trace[2147199009] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:508; }","duration":"245.911331ms","start":"2026-03-13T23:06:17.718622Z","end":"2026-03-13T23:06:17.964533Z","steps":["trace[2147199009] 'read index received' (duration: 245.852469ms)","trace[2147199009] 'applied index is now lower than readState.Index' (duration: 57.962µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:17.964634Z","caller":"traceutil/trace.go:171","msg":"trace[2018209816] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"265.507502ms","start":"2026-03-13T23:06:17.69907Z","end":"2026-03-13T23:06:17.964578Z","steps":["trace[2018209816] 'process raft request' (duration: 265.322757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:17.964728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.094995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-13T23:06:17.96476Z","caller":"traceutil/trace.go:171","msg":"trace[812511503] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:492; }","duration":"246.182117ms","start":"2026-03-13T23:06:17.718569Z","end":"2026-03-13T23:06:17.964751Z","steps":["trace[812511503] 'agreement among raft nodes before linearized reading' (duration: 246.072794ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:18.372076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.680708ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:18.372219Z","caller":"traceutil/trace.go:171","msg":"trace[188749160] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"407.578541ms","start":"2026-03-13T23:06:17.964618Z","end":"2026-03-13T23:06:18.372196Z","steps":["trace[188749160] 'read index received' (duration: 72.663178ms)","trace[188749160] 'applied index is now lower than readState.Index' (duration: 334.913883ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:18.372281Z","caller":"traceutil/trace.go:171","msg":"trace[1573359642] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"651.696349ms","start":"2026-03-13T23:06:17.720535Z","end":"2026-03-13T23:06:18.372232Z","steps":["trace[1573359642] 'process raft request' (duration: 316.735145ms)","trace[1573359642] 'compare' (duration: 334.499644ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:18.372372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.81549ms","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-03-13T23:06:18.372438Z","caller":"traceutil/trace.go:171","msg":"trace[917148534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"465.939323ms","start":"2026-03-13T23:06:17.906483Z","end":"2026-03-13T23:06:18.372423Z","steps":["trace[917148534] 'agreement among raft nodes before linearized reading' (duration: 465.752128ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:18.372469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.720519Z","time spent":"651.853433ms","remote":"127.0.0.1:57436","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-03-13T23:06:18.372508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.906419Z","time spent":"466.079967ms","remote":"127.0.0.1:57436","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-03-13T23:06:18.372548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.760062ms","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-03-13T23:06:18.372635Z","caller":"traceutil/trace.go:171","msg":"trace[905523024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"466.914895ms","start":"2026-03-13T23:06:17.905701Z","end":"2026-03-13T23:06:18.372616Z","steps":["trace[905523024] 'agreement among raft nodes before linearized reading' (duration: 466.608568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:18.372685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.905677Z","time spent":"466.999028ms","remote":"127.0.0.1:57436","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-03-13T23:06:18.872349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413368923,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:06:19.150198Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.11286209s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-13T23:06:19.842023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.538526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:19.842265Z","caller":"traceutil/trace.go:171","msg":"trace[1581460238] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:510; }","duration":"1.469988485s","start":"2026-03-13T23:06:18.372249Z","end":"2026-03-13T23:06:19.842238Z","steps":["trace[1581460238] 'read index received' (duration: 778.198063ms)","trace[1581460238] 'applied index is now lower than readState.Index' (duration: 691.789632ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:19.842426Z","caller":"traceutil/trace.go:171","msg":"trace[423950198] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.463517913s","start":"2026-03-13T23:06:18.378879Z","end":"2026-03-13T23:06:19.842397Z","steps":["trace[423950198] 'process raft request' (duration: 1.463328349s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:19.842538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.875863456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-13T23:06:19.842564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:18.378863Z","time spent":"1.463632285s","remote":"127.0.0.1:57436","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-03-13T23:06:19.842586Z","caller":"traceutil/trace.go:171","msg":"trace[1675816431] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:497; }","duration":"1.875978939s","start":"2026-03-13T23:06:17.966597Z","end":"2026-03-13T23:06:19.842576Z","steps":["trace[1675816431] 'agreement among raft nodes before linearized reading' (duration: 1.875823716s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:19.842616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.966583Z","time spent":"1.87602766s","remote":"127.0.0.1:57228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-13T23:06:19.842613Z","caller":"traceutil/trace.go:171","msg":"trace[1185372665] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"1.464296282s","start":"2026-03-13T23:06:18.378294Z","end":"2026-03-13T23:06:19.84259Z","steps":["trace[1185372665] 'process raft request' (duration: 1.463868041s)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:19.84242Z","caller":"traceutil/trace.go:171","msg":"trace[895621882] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.869831145s","start":"2026-03-13T23:06:17.972556Z","end":"2026-03-13T23:06:19.842387Z","steps":["trace[895621882] 'process raft request' (duration: 1.177870899s)","trace[895621882] 'compare' (duration: 691.436513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:19.842692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:18.378267Z","time spent":"1.464381693s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:19.842719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.972543Z","time spent":"1.870127311s","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3827,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:06:19.842852Z","caller":"traceutil/trace.go:171","msg":"trace[1409387852] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"1.868100684s","start":"2026-03-13T23:06:17.974741Z","end":"2026-03-13T23:06:19.842842Z","steps":["trace[1409387852] 'process raft request' (duration: 1.867371587s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:19.842899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:17.974722Z","time spent":"1.868158345s","remote":"127.0.0.1:57684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:20.478452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000198149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-13T23:06:20.478571Z","caller":"traceutil/trace.go:171","msg":"trace[417850975] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000397364s","start":"2026-03-13T23:06:18.478155Z","end":"2026-03-13T23:06:20.478552Z","steps":["trace[417850975] 'agreement among raft nodes before linearized reading' (duration: 2.00023729s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.478621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:18.478131Z","time spent":"2.000479226s","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-13T23:06:20.715955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.873465ms","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-03-13T23:06:20.716032Z","caller":"traceutil/trace.go:171","msg":"trace[701552073] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:497; }","duration":"757.968597ms","start":"2026-03-13T23:06:19.958046Z","end":"2026-03-13T23:06:20.716015Z","steps":["trace[701552073] 'range keys from in-memory index tree' (duration: 757.858274ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.716294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.763208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:20.716419Z","caller":"traceutil/trace.go:171","msg":"trace[399143439] linearizableReadLoop","detail":"{readStateIndex:515; appliedIndex:514; }","duration":"874.102247ms","start":"2026-03-13T23:06:19.8423Z","end":"2026-03-13T23:06:20.716403Z","steps":["trace[399143439] 'read index received' (duration: 214.270427ms)","trace[399143439] 'applied index is now lower than readState.Index' (duration: 659.83032ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:20.716444Z","caller":"traceutil/trace.go:171","msg":"trace[186528820] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.21794851s","start":"2026-03-13T23:06:19.49848Z","end":"2026-03-13T23:06:20.716428Z","steps":["trace[186528820] 'process raft request' (duration: 557.964236ms)","trace[186528820] 'compare' (duration: 659.338829ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:20.716542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.498455Z","time spent":"1.218026362s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:20.716596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315079873s","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-03-13T23:06:20.716632Z","caller":"traceutil/trace.go:171","msg":"trace[1879822326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:498; }","duration":"1.315150845s","start":"2026-03-13T23:06:19.401472Z","end":"2026-03-13T23:06:20.716623Z","steps":["trace[1879822326] 'agreement among raft nodes before linearized reading' (duration: 1.315005842s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.716671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.40145Z","time spent":"1.315212846s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:06:20.72533Z","caller":"traceutil/trace.go:171","msg":"trace[288007090] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"876.412752ms","start":"2026-03-13T23:06:19.848902Z","end":"2026-03-13T23:06:20.725314Z","steps":["trace[288007090] 'process raft request' (duration: 876.208767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.725387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.848885Z","time spent":"876.477003ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-13T23:06:20.725921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"881.161424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-13T23:06:20.72602Z","caller":"traceutil/trace.go:171","msg":"trace[1143353304] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"799.232307ms","start":"2026-03-13T23:06:19.926778Z","end":"2026-03-13T23:06:20.72601Z","steps":["trace[1143353304] 'process raft request' (duration: 798.51926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.726071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.92675Z","time spent":"799.295069ms","remote":"127.0.0.1:57274","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:20.726093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.877593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:20.726168Z","caller":"traceutil/trace.go:171","msg":"trace[1570147710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"246.981525ms","start":"2026-03-13T23:06:20.479171Z","end":"2026-03-13T23:06:20.726152Z","steps":["trace[1570147710] 'agreement among raft nodes before linearized reading' (duration: 246.619067ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.726277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.680196ms","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-03-13T23:06:20.726339Z","caller":"traceutil/trace.go:171","msg":"trace[1469764507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:500; }","duration":"348.778629ms","start":"2026-03-13T23:06:20.377553Z","end":"2026-03-13T23:06:20.726332Z","steps":["trace[1469764507] 'agreement among raft nodes before linearized reading' (duration: 348.690427ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.726363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:20.377533Z","time spent":"348.825209ms","remote":"127.0.0.1:57436","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-03-13T23:06:20.72627Z","caller":"traceutil/trace.go:171","msg":"trace[705952602] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:500; }","duration":"881.365858ms","start":"2026-03-13T23:06:19.84467Z","end":"2026-03-13T23:06:20.726036Z","steps":["trace[705952602] 'agreement among raft nodes before linearized reading' (duration: 881.117142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.726488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.788297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:20.72656Z","caller":"traceutil/trace.go:171","msg":"trace[51834304] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:500; }","duration":"875.89434ms","start":"2026-03-13T23:06:19.85065Z","end":"2026-03-13T23:06:20.726544Z","steps":["trace[51834304] 'agreement among raft nodes before linearized reading' (duration: 875.789147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:20.726599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.850632Z","time spent":"875.957721ms","remote":"127.0.0.1:57316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-03-13T23:06:20.726533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:19.844649Z","time spent":"881.86833ms","remote":"127.0.0.1:57440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-13T23:06:25.994335Z","caller":"traceutil/trace.go:171","msg":"trace[1898863942] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"256.229253ms","start":"2026-03-13T23:06:25.738083Z","end":"2026-03-13T23:06:25.994313Z","steps":["trace[1898863942] 'process raft request' (duration: 256.064229ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:32.227279Z","caller":"traceutil/trace.go:171","msg":"trace[1244207664] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"187.001506ms","start":"2026-03-13T23:06:32.040255Z","end":"2026-03-13T23:06:32.227257Z","steps":["trace[1244207664] 'read index received' (duration: 186.793711ms)","trace[1244207664] 'applied index is now lower than readState.Index' (duration: 206.315µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:32.227394Z","caller":"traceutil/trace.go:171","msg":"trace[229059173] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"252.68212ms","start":"2026-03-13T23:06:31.974685Z","end":"2026-03-13T23:06:32.227367Z","steps":["trace[229059173] 'process raft request' (duration: 252.431414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:32.227552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.274312ms","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-03-13T23:06:32.227613Z","caller":"traceutil/trace.go:171","msg":"trace[1973918804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"187.385435ms","start":"2026-03-13T23:06:32.040214Z","end":"2026-03-13T23:06:32.227599Z","steps":["trace[1973918804] 'agreement among raft nodes before linearized reading' (duration: 187.20154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:32.228131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.232672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:06:32.228181Z","caller":"traceutil/trace.go:171","msg":"trace[1177511384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"187.535048ms","start":"2026-03-13T23:06:32.040633Z","end":"2026-03-13T23:06:32.228168Z","steps":["trace[1177511384] 'agreement among raft nodes before linearized reading' (duration: 187.013026ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:32.688237Z","caller":"traceutil/trace.go:171","msg":"trace[979350531] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"457.26534ms","start":"2026-03-13T23:06:32.23095Z","end":"2026-03-13T23:06:32.688215Z","steps":["trace[979350531] 'process raft request' (duration: 457.162367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:32.68839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:32.230931Z","time spent":"457.364962ms","remote":"127.0.0.1:57436","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-03-13T23:06:32.693224Z","caller":"traceutil/trace.go:171","msg":"trace[1548436775] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"460.083176ms","start":"2026-03-13T23:06:32.233111Z","end":"2026-03-13T23:06:32.693194Z","steps":["trace[1548436775] 'process raft request' (duration: 459.949403ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:32.693401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:32.233092Z","time spent":"460.217678ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:33.076739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.096808ms","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-03-13T23:06:33.07684Z","caller":"traceutil/trace.go:171","msg":"trace[1697210570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"204.244682ms","start":"2026-03-13T23:06:32.872571Z","end":"2026-03-13T23:06:33.076816Z","steps":["trace[1697210570] 'range keys from in-memory index tree' (duration: 203.921444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:33.076925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.521345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-13T23:06:33.076922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.705778ms","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-03-13T23:06:33.076976Z","caller":"traceutil/trace.go:171","msg":"trace[1503387477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"286.605867ms","start":"2026-03-13T23:06:32.790356Z","end":"2026-03-13T23:06:33.076962Z","steps":["trace[1503387477] 'range keys from in-memory index tree' (duration: 286.381632ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:33.07699Z","caller":"traceutil/trace.go:171","msg":"trace[1586744504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"297.816651ms","start":"2026-03-13T23:06:32.779156Z","end":"2026-03-13T23:06:33.076973Z","steps":["trace[1586744504] 'range keys from in-memory index tree' (duration: 297.503614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.601685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.124697445s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:35.60178Z","caller":"traceutil/trace.go:171","msg":"trace[324396946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"2.124801737s","start":"2026-03-13T23:06:33.476955Z","end":"2026-03-13T23:06:35.601757Z","steps":["trace[324396946] 'range keys from in-memory index tree' (duration: 2.124633493s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.601833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:33.476906Z","time spent":"2.12491427s","remote":"127.0.0.1:57200","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-03-13T23:06:35.601986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.935451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:06:35.602046Z","caller":"traceutil/trace.go:171","msg":"trace[1193189696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"905.039015ms","start":"2026-03-13T23:06:34.696988Z","end":"2026-03-13T23:06:35.602027Z","steps":["trace[1193189696] 'range keys from in-memory index tree' (duration: 904.749747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.602085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:34.696962Z","time spent":"905.112246ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:06:35.602248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.37752ms","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-03-13T23:06:35.602316Z","caller":"traceutil/trace.go:171","msg":"trace[774984667] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"910.510413ms","start":"2026-03-13T23:06:34.691787Z","end":"2026-03-13T23:06:35.602298Z","steps":["trace[774984667] 'range keys from in-memory index tree' (duration: 910.223675ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.602356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:34.691762Z","time spent":"910.585044ms","remote":"127.0.0.1:57436","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-03-13T23:06:35.602553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.981598ms","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-03-13T23:06:35.602602Z","caller":"traceutil/trace.go:171","msg":"trace[426059560] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"504.06083ms","start":"2026-03-13T23:06:35.098527Z","end":"2026-03-13T23:06:35.602588Z","steps":["trace[426059560] 'range keys from in-memory index tree' (duration: 503.902025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.602647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.098511Z","time spent":"504.124241ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:06:35.602642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.931816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:35.602703Z","caller":"traceutil/trace.go:171","msg":"trace[225539607] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"125.042989ms","start":"2026-03-13T23:06:35.477646Z","end":"2026-03-13T23:06:35.602689Z","steps":["trace[225539607] 'range keys from in-memory index tree' (duration: 124.794303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.602704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.218633ms","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-03-13T23:06:35.602743Z","caller":"traceutil/trace.go:171","msg":"trace[1343495663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"487.316916ms","start":"2026-03-13T23:06:35.115413Z","end":"2026-03-13T23:06:35.60273Z","steps":["trace[1343495663] 'range keys from in-memory index tree' (duration: 487.109671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.602771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.11539Z","time spent":"487.374787ms","remote":"127.0.0.1:57436","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-03-13T23:06:35.602868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.002658ms","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-03-13T23:06:35.602913Z","caller":"traceutil/trace.go:171","msg":"trace[1473701100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:604; }","duration":"487.06671ms","start":"2026-03-13T23:06:35.115837Z","end":"2026-03-13T23:06:35.602904Z","steps":["trace[1473701100] 'range keys from in-memory index tree' (duration: 486.936137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:35.60294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.115823Z","time spent":"487.110391ms","remote":"127.0.0.1:57436","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-03-13T23:06:36.108355Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413369164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:06:36.408113Z","caller":"traceutil/trace.go:171","msg":"trace[933999802] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"801.170354ms","start":"2026-03-13T23:06:35.606918Z","end":"2026-03-13T23:06:36.408089Z","steps":["trace[933999802] 'process raft request' (duration: 801.01777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.408334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.606893Z","time spent":"801.359618ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:06:36.557431Z","caller":"traceutil/trace.go:171","msg":"trace[1087925548] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"949.518401ms","start":"2026-03-13T23:06:35.607887Z","end":"2026-03-13T23:06:36.557406Z","steps":["trace[1087925548] 'process raft request' (duration: 949.333807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.557724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.607866Z","time spent":"949.774326ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:06:36.557801Z","caller":"traceutil/trace.go:171","msg":"trace[560038850] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"949.639454ms","start":"2026-03-13T23:06:35.608132Z","end":"2026-03-13T23:06:36.557771Z","steps":["trace[560038850] 'process raft request' (duration: 949.235804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.557951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.608122Z","time spent":"949.750156ms","remote":"127.0.0.1:57436","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-03-13T23:06:36.558368Z","caller":"traceutil/trace.go:171","msg":"trace[27035497] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"948.751913ms","start":"2026-03-13T23:06:35.609599Z","end":"2026-03-13T23:06:36.558351Z","steps":["trace[27035497] 'process raft request' (duration: 948.706522ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:36.558377Z","caller":"traceutil/trace.go:171","msg":"trace[1014379879] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"950.160566ms","start":"2026-03-13T23:06:35.608199Z","end":"2026-03-13T23:06:36.558359Z","steps":["trace[1014379879] 'process raft request' (duration: 949.987852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.558455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.609592Z","time spent":"948.819705ms","remote":"127.0.0.1:57436","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-03-13T23:06:36.558459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.608192Z","time spent":"950.225708ms","remote":"127.0.0.1:57436","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-03-13T23:06:36.558728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.629547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189c892d40a45daf\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-13T23:06:36.558816Z","caller":"traceutil/trace.go:171","msg":"trace[838126622] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189c892d40a45daf; range_end:; response_count:1; response_revision:609; }","duration":"950.73499ms","start":"2026-03-13T23:06:35.608067Z","end":"2026-03-13T23:06:36.558802Z","steps":["trace[838126622] 'agreement among raft nodes before linearized reading' (duration: 950.543215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.558855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.608059Z","time spent":"950.785971ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189c892d40a45daf\" "} {"level":"info","ts":"2026-03-13T23:06:36.558368Z","caller":"traceutil/trace.go:171","msg":"trace[987663539] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:625; }","duration":"950.258748ms","start":"2026-03-13T23:06:35.608087Z","end":"2026-03-13T23:06:36.558346Z","steps":["trace[987663539] 'read index received' (duration: 799.848882ms)","trace[987663539] 'applied index is now lower than readState.Index' (duration: 150.408916ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:36.559362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.587289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:36.559476Z","caller":"traceutil/trace.go:171","msg":"trace[1476036000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"948.711172ms","start":"2026-03-13T23:06:35.610751Z","end":"2026-03-13T23:06:36.559462Z","steps":["trace[1476036000] 'agreement among raft nodes before linearized reading' (duration: 948.560989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:36.55967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:35.610734Z","time spent":"948.911527ms","remote":"127.0.0.1:57200","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-03-13T23:06:47.399139Z","caller":"traceutil/trace.go:171","msg":"trace[1193345648] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"203.091059ms","start":"2026-03-13T23:06:47.196019Z","end":"2026-03-13T23:06:47.39911Z","steps":["trace[1193345648] 'read index received' (duration: 202.792972ms)","trace[1193345648] 'applied index is now lower than readState.Index' (duration: 295.667µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:47.399173Z","caller":"traceutil/trace.go:171","msg":"trace[1153364300] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"317.601468ms","start":"2026-03-13T23:06:47.081545Z","end":"2026-03-13T23:06:47.399147Z","steps":["trace[1153364300] 'process raft request' (duration: 317.306261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:47.399304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:47.081524Z","time spent":"317.716591ms","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18698,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:06:47.39938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.342265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T23:06:47.400615Z","caller":"traceutil/trace.go:171","msg":"trace[767385906] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:674; }","duration":"203.462017ms","start":"2026-03-13T23:06:47.195965Z","end":"2026-03-13T23:06:47.399427Z","steps":["trace[767385906] 'agreement among raft nodes before linearized reading' (duration: 203.245832ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:52.963705Z","caller":"traceutil/trace.go:171","msg":"trace[91103136] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"262.843861ms","start":"2026-03-13T23:06:52.700836Z","end":"2026-03-13T23:06:52.96368Z","steps":["trace[91103136] 'process raft request' (duration: 262.77848ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:52.963735Z","caller":"traceutil/trace.go:171","msg":"trace[871164586] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"263.431396ms","start":"2026-03-13T23:06:52.700279Z","end":"2026-03-13T23:06:52.96371Z","steps":["trace[871164586] 'process raft request' (duration: 213.752719ms)","trace[871164586] 'compare' (duration: 49.444031ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:55.20123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413369440,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:06:55.236083Z","caller":"traceutil/trace.go:171","msg":"trace[1931917196] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"535.434684ms","start":"2026-03-13T23:06:54.700625Z","end":"2026-03-13T23:06:55.23606Z","steps":["trace[1931917196] 'read index received' (duration: 535.288551ms)","trace[1931917196] 'applied index is now lower than readState.Index' (duration: 145.093µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:55.236186Z","caller":"traceutil/trace.go:171","msg":"trace[567092794] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"704.112695ms","start":"2026-03-13T23:06:54.532044Z","end":"2026-03-13T23:06:55.236156Z","steps":["trace[567092794] 'process raft request' (duration: 703.884349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:55.23631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.66591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-13T23:06:55.236327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:54.532026Z","time spent":"704.222698ms","remote":"127.0.0.1:57436","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-03-13T23:06:55.236348Z","caller":"traceutil/trace.go:171","msg":"trace[1611075996] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"535.759992ms","start":"2026-03-13T23:06:54.700575Z","end":"2026-03-13T23:06:55.236335Z","steps":["trace[1611075996] 'agreement among raft nodes before linearized reading' (duration: 535.597058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:55.236346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.621992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:06:55.236358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.288734ms","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-03-13T23:06:55.236382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:54.700554Z","time spent":"535.822323ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:06:55.236388Z","caller":"traceutil/trace.go:171","msg":"trace[1728299035] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"269.700484ms","start":"2026-03-13T23:06:54.966673Z","end":"2026-03-13T23:06:55.236374Z","steps":["trace[1728299035] 'agreement among raft nodes before linearized reading' (duration: 269.55294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:55.236369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.201717ms","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-03-13T23:06:55.236416Z","caller":"traceutil/trace.go:171","msg":"trace[301528561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:745; }","duration":"269.402066ms","start":"2026-03-13T23:06:54.966999Z","end":"2026-03-13T23:06:55.236401Z","steps":["trace[301528561] 'agreement among raft nodes before linearized reading' (duration: 269.244733ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:55.236447Z","caller":"traceutil/trace.go:171","msg":"trace[667580191] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:745; }","duration":"503.33542ms","start":"2026-03-13T23:06:54.733098Z","end":"2026-03-13T23:06:55.236433Z","steps":["trace[667580191] 'agreement among raft nodes before linearized reading' (duration: 503.127405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:55.236479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:54.733073Z","time spent":"503.399992ms","remote":"127.0.0.1:57436","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-03-13T23:06:55.236517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.650941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:55.236554Z","caller":"traceutil/trace.go:171","msg":"trace[1579847368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"524.708672ms","start":"2026-03-13T23:06:54.711837Z","end":"2026-03-13T23:06:55.236545Z","steps":["trace[1579847368] 'agreement among raft nodes before linearized reading' (duration: 524.355194ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:55.236585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:54.711826Z","time spent":"524.752463ms","remote":"127.0.0.1:57200","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-03-13T23:06:55.887492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413369443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:06:56.300537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.465272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:06:56.300701Z","caller":"traceutil/trace.go:171","msg":"trace[897497821] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.059870149s","start":"2026-03-13T23:06:55.240813Z","end":"2026-03-13T23:06:56.300684Z","steps":["trace[897497821] 'process raft request' (duration: 114.166201ms)","trace[897497821] 'compare' (duration: 945.169466ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:56.300759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.240612Z","time spent":"1.060119825s","remote":"127.0.0.1:57436","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-03-13T23:06:56.300915Z","caller":"traceutil/trace.go:171","msg":"trace[1065280214] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.059353477s","start":"2026-03-13T23:06:55.241543Z","end":"2026-03-13T23:06:56.300897Z","steps":["trace[1065280214] 'process raft request' (duration: 1.059083951s)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:56.300929Z","caller":"traceutil/trace.go:171","msg":"trace[1472712980] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.059314906s","start":"2026-03-13T23:06:55.241601Z","end":"2026-03-13T23:06:56.300916Z","steps":["trace[1472712980] 'process raft request' (duration: 1.059219623s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:56.301003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.29736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-13T23:06:56.301012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.241597Z","time spent":"1.059369308s","remote":"127.0.0.1:57436","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-03-13T23:06:56.301011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.241527Z","time spent":"1.059430619s","remote":"127.0.0.1:57436","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-03-13T23:06:56.301029Z","caller":"traceutil/trace.go:171","msg":"trace[1806920897] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:749; }","duration":"914.332861ms","start":"2026-03-13T23:06:55.38669Z","end":"2026-03-13T23:06:56.301023Z","steps":["trace[1806920897] 'agreement among raft nodes before linearized reading' (duration: 914.27031ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:56.30093Z","caller":"traceutil/trace.go:171","msg":"trace[1634992836] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:773; }","duration":"914.208959ms","start":"2026-03-13T23:06:55.386705Z","end":"2026-03-13T23:06:56.300914Z","steps":["trace[1634992836] 'read index received' (duration: 545.06134ms)","trace[1634992836] 'applied index is now lower than readState.Index' (duration: 369.146819ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:56.300962Z","caller":"traceutil/trace.go:171","msg":"trace[793682879] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.045069552s","start":"2026-03-13T23:06:55.255861Z","end":"2026-03-13T23:06:56.300931Z","steps":["trace[793682879] 'process raft request' (duration: 1.045025731s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:56.301052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.386626Z","time spent":"914.420214ms","remote":"127.0.0.1:57510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:56.301156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.187895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-13T23:06:56.301143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.255854Z","time spent":"1.045217075s","remote":"127.0.0.1:57436","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-03-13T23:06:56.301173Z","caller":"traceutil/trace.go:171","msg":"trace[1619772961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"184.241607ms","start":"2026-03-13T23:06:56.116927Z","end":"2026-03-13T23:06:56.301168Z","steps":["trace[1619772961] 'agreement among raft nodes before linearized reading' (duration: 184.212856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:56.301159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.925102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:56.301204Z","caller":"traceutil/trace.go:171","msg":"trace[439795232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"827.996514ms","start":"2026-03-13T23:06:55.473192Z","end":"2026-03-13T23:06:56.301188Z","steps":["trace[439795232] 'agreement among raft nodes before linearized reading' (duration: 827.920482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:56.301234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:55.473175Z","time spent":"828.052805ms","remote":"127.0.0.1:57214","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-03-13T23:06:58.460706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413369455,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:06:58.628846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.154354152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:58.628932Z","caller":"traceutil/trace.go:171","msg":"trace[1761156154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"2.154497195s","start":"2026-03-13T23:06:56.474415Z","end":"2026-03-13T23:06:58.628912Z","steps":["trace[1761156154] 'range keys from in-memory index tree' (duration: 2.154257639s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.628977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:56.474391Z","time spent":"2.154577746s","remote":"127.0.0.1:57214","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-03-13T23:06:58.629339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.432738486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-13T23:06:58.62938Z","caller":"traceutil/trace.go:171","msg":"trace[1803369215] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:749; }","duration":"1.432814627s","start":"2026-03-13T23:06:57.196555Z","end":"2026-03-13T23:06:58.629369Z","steps":["trace[1803369215] 'range keys from in-memory index tree' (duration: 1.432546861s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.629408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.196535Z","time spent":"1.432867019s","remote":"127.0.0.1:57228","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.207\" "} {"level":"warn","ts":"2026-03-13T23:06:58.629778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021909789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:06:58.629848Z","caller":"traceutil/trace.go:171","msg":"trace[1009395508] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:749; }","duration":"1.021994201s","start":"2026-03-13T23:06:57.607835Z","end":"2026-03-13T23:06:58.62983Z","steps":["trace[1009395508] 'count revisions from in-memory index tree' (duration: 1.021806005s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.629891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.607773Z","time spent":"1.022105993s","remote":"127.0.0.1:57440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:58.62993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584853548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:58.629994Z","caller":"traceutil/trace.go:171","msg":"trace[1594965071] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:749; }","duration":"1.584922489s","start":"2026-03-13T23:06:57.04506Z","end":"2026-03-13T23:06:58.629983Z","steps":["trace[1594965071] 'count revisions from in-memory index tree' (duration: 1.584700034s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.630028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.044987Z","time spent":"1.585030902s","remote":"127.0.0.1:57648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:58.630198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.557311621s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:58.630266Z","caller":"traceutil/trace.go:171","msg":"trace[2092794128] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:749; }","duration":"1.557431913s","start":"2026-03-13T23:06:57.072816Z","end":"2026-03-13T23:06:58.630248Z","steps":["trace[2092794128] 'count revisions from in-memory index tree' (duration: 1.557214108s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.630305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.072795Z","time spent":"1.557499806s","remote":"127.0.0.1:57328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:06:58.630642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390287098s","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-03-13T23:06:58.630687Z","caller":"traceutil/trace.go:171","msg":"trace[1711068205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:749; }","duration":"1.390375431s","start":"2026-03-13T23:06:57.240302Z","end":"2026-03-13T23:06:58.630677Z","steps":["trace[1711068205] 'range keys from in-memory index tree' (duration: 1.389333957s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.630717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.240282Z","time spent":"1.390428062s","remote":"127.0.0.1:57436","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-03-13T23:06:58.699909Z","caller":"traceutil/trace.go:171","msg":"trace[1530185472] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"944.970371ms","start":"2026-03-13T23:06:57.75492Z","end":"2026-03-13T23:06:58.69989Z","steps":["trace[1530185472] 'process raft request' (duration: 944.819247ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.700037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.754896Z","time spent":"945.081564ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-13T23:06:58.705985Z","caller":"traceutil/trace.go:171","msg":"trace[2144722972] linearizableReadLoop","detail":"{readStateIndex:779; appliedIndex:778; }","duration":"745.837825ms","start":"2026-03-13T23:06:57.96013Z","end":"2026-03-13T23:06:58.705968Z","steps":["trace[2144722972] 'read index received' (duration: 739.846364ms)","trace[2144722972] 'applied index is now lower than readState.Index' (duration: 5.990791ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:06:58.706091Z","caller":"traceutil/trace.go:171","msg":"trace[1541816122] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"915.154021ms","start":"2026-03-13T23:06:57.790928Z","end":"2026-03-13T23:06:58.706082Z","steps":["trace[1541816122] 'process raft request' (duration: 914.698481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.790896Z","time spent":"915.223133ms","remote":"127.0.0.1:57436","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-03-13T23:06:58.706315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.862229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:06:58.706362Z","caller":"traceutil/trace.go:171","msg":"trace[157127669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"218.973313ms","start":"2026-03-13T23:06:58.487375Z","end":"2026-03-13T23:06:58.706348Z","steps":["trace[157127669] 'agreement among raft nodes before linearized reading' (duration: 218.897341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.798976ms","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-03-13T23:06:58.706425Z","caller":"traceutil/trace.go:171","msg":"trace[1270151099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:752; }","duration":"401.884588ms","start":"2026-03-13T23:06:58.304529Z","end":"2026-03-13T23:06:58.706413Z","steps":["trace[1270151099] 'agreement among raft nodes before linearized reading' (duration: 401.739995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.740284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-13T23:06:58.706452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:58.304512Z","time spent":"401.934229ms","remote":"127.0.0.1:57436","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-03-13T23:06:58.706463Z","caller":"traceutil/trace.go:171","msg":"trace[899844381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"401.806916ms","start":"2026-03-13T23:06:58.304647Z","end":"2026-03-13T23:06:58.706454Z","steps":["trace[899844381] 'agreement among raft nodes before linearized reading' (duration: 401.730654ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.665509ms","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-03-13T23:06:58.706482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:58.304625Z","time spent":"401.852257ms","remote":"127.0.0.1:57436","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-03-13T23:06:58.706501Z","caller":"traceutil/trace.go:171","msg":"trace[404748740] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"400.750921ms","start":"2026-03-13T23:06:58.305743Z","end":"2026-03-13T23:06:58.706494Z","steps":["trace[404748740] 'agreement among raft nodes before linearized reading' (duration: 400.648399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:58.305729Z","time spent":"400.790832ms","remote":"127.0.0.1:57436","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-03-13T23:06:58.706559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.948519ms","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-03-13T23:06:58.706581Z","caller":"traceutil/trace.go:171","msg":"trace[964843626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"401.98708ms","start":"2026-03-13T23:06:58.304587Z","end":"2026-03-13T23:06:58.706574Z","steps":["trace[964843626] 'agreement among raft nodes before linearized reading' (duration: 401.904828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:58.304581Z","time spent":"402.01526ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:06:58.706595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.45901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:06:58.706636Z","caller":"traceutil/trace.go:171","msg":"trace[1461008748] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:752; }","duration":"746.538501ms","start":"2026-03-13T23:06:57.960084Z","end":"2026-03-13T23:06:58.706623Z","steps":["trace[1461008748] 'agreement among raft nodes before linearized reading' (duration: 746.428408ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:06:58.706667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:06:57.960061Z","time spent":"746.597063ms","remote":"127.0.0.1:57642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:06:58.867077Z","caller":"traceutil/trace.go:171","msg":"trace[1489754566] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"155.506292ms","start":"2026-03-13T23:06:58.711547Z","end":"2026-03-13T23:06:58.867053Z","steps":["trace[1489754566] 'process raft request' (duration: 155.43234ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:58.867085Z","caller":"traceutil/trace.go:171","msg":"trace[1168567622] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"155.926402ms","start":"2026-03-13T23:06:58.711113Z","end":"2026-03-13T23:06:58.86704Z","steps":["trace[1168567622] 'process raft request' (duration: 155.733958ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:58.867336Z","caller":"traceutil/trace.go:171","msg":"trace[1092655440] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"155.591254ms","start":"2026-03-13T23:06:58.711736Z","end":"2026-03-13T23:06:58.867327Z","steps":["trace[1092655440] 'process raft request' (duration: 155.291827ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:58.867424Z","caller":"traceutil/trace.go:171","msg":"trace[124314293] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"155.126203ms","start":"2026-03-13T23:06:58.712271Z","end":"2026-03-13T23:06:58.867398Z","steps":["trace[124314293] 'process raft request' (duration: 154.980909ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:58.867461Z","caller":"traceutil/trace.go:171","msg":"trace[1119001595] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:782; }","duration":"147.844132ms","start":"2026-03-13T23:06:58.719608Z","end":"2026-03-13T23:06:58.867453Z","steps":["trace[1119001595] 'read index received' (duration: 147.223127ms)","trace[1119001595] 'applied index is now lower than readState.Index' (duration: 620.335µs)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:06:58.867591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.963605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-13T23:06:58.867609Z","caller":"traceutil/trace.go:171","msg":"trace[31097085] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"151.960588ms","start":"2026-03-13T23:06:58.715637Z","end":"2026-03-13T23:06:58.867597Z","steps":["trace[31097085] 'process raft request' (duration: 151.718943ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:06:58.867625Z","caller":"traceutil/trace.go:171","msg":"trace[135954694] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:758; }","duration":"148.031247ms","start":"2026-03-13T23:06:58.719583Z","end":"2026-03-13T23:06:58.867614Z","steps":["trace[135954694] 'agreement among raft nodes before linearized reading' (duration: 147.895003ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:17.385802Z","caller":"traceutil/trace.go:171","msg":"trace[614659288] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"179.097356ms","start":"2026-03-13T23:07:17.20666Z","end":"2026-03-13T23:07:17.385757Z","steps":["trace[614659288] 'process raft request' (duration: 164.37851ms)","trace[614659288] 'compare' (duration: 14.4651ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:18.992982Z","caller":"traceutil/trace.go:171","msg":"trace[1607845481] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"114.011517ms","start":"2026-03-13T23:07:18.878946Z","end":"2026-03-13T23:07:18.992958Z","steps":["trace[1607845481] 'read index received' (duration: 113.808022ms)","trace[1607845481] 'applied index is now lower than readState.Index' (duration: 202.705µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:18.993214Z","caller":"traceutil/trace.go:171","msg":"trace[2028907767] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"136.850094ms","start":"2026-03-13T23:07:18.856353Z","end":"2026-03-13T23:07:18.993203Z","steps":["trace[2028907767] 'process raft request' (duration: 136.464565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:18.993448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.483349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:18.993485Z","caller":"traceutil/trace.go:171","msg":"trace[1826795625] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:814; }","duration":"114.586551ms","start":"2026-03-13T23:07:18.878889Z","end":"2026-03-13T23:07:18.993476Z","steps":["trace[1826795625] 'agreement among raft nodes before linearized reading' (duration: 114.505279ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:19.191495Z","caller":"traceutil/trace.go:171","msg":"trace[363432410] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"144.099734ms","start":"2026-03-13T23:07:19.047364Z","end":"2026-03-13T23:07:19.191464Z","steps":["trace[363432410] 'process raft request' (duration: 103.207093ms)","trace[363432410] 'compare' (duration: 40.717827ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:31.65855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413369818,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-13T23:07:31.944672Z","caller":"traceutil/trace.go:171","msg":"trace[1086980706] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"786.771546ms","start":"2026-03-13T23:07:31.15788Z","end":"2026-03-13T23:07:31.944652Z","steps":["trace[1086980706] 'read index received' (duration: 786.571001ms)","trace[1086980706] 'applied index is now lower than readState.Index' (duration: 199.845µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:31.944778Z","caller":"traceutil/trace.go:171","msg":"trace[1202490512] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"866.272452ms","start":"2026-03-13T23:07:31.078498Z","end":"2026-03-13T23:07:31.94477Z","steps":["trace[1202490512] 'process raft request' (duration: 865.999676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.944856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.078479Z","time spent":"866.319414ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:07:31.944948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"787.068532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:31.944967Z","caller":"traceutil/trace.go:171","msg":"trace[15869925] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:899; }","duration":"787.120644ms","start":"2026-03-13T23:07:31.157838Z","end":"2026-03-13T23:07:31.944959Z","steps":["trace[15869925] 'agreement among raft nodes before linearized reading' (duration: 787.090113ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.944981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.157822Z","time spent":"787.155435ms","remote":"127.0.0.1:57392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:07:31.945272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.209539ms","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-03-13T23:07:31.945286Z","caller":"traceutil/trace.go:171","msg":"trace[423836281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:899; }","duration":"693.251889ms","start":"2026-03-13T23:07:31.25203Z","end":"2026-03-13T23:07:31.945282Z","steps":["trace[423836281] 'agreement among raft nodes before linearized reading' (duration: 693.180018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.9453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.252017Z","time spent":"693.27953ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:07:31.945282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.757678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:07:31.945358Z","caller":"traceutil/trace.go:171","msg":"trace[1887621995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:899; }","duration":"735.875881ms","start":"2026-03-13T23:07:31.209462Z","end":"2026-03-13T23:07:31.945338Z","steps":["trace[1887621995] 'agreement among raft nodes before linearized reading' (duration: 735.653726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.945401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.074104ms","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-03-13T23:07:31.945398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.209443Z","time spent":"735.945942ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-13T23:07:31.945416Z","caller":"traceutil/trace.go:171","msg":"trace[877353706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"687.136527ms","start":"2026-03-13T23:07:31.258275Z","end":"2026-03-13T23:07:31.945412Z","steps":["trace[877353706] 'agreement among raft nodes before linearized reading' (duration: 687.093716ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.945429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.258264Z","time spent":"687.162327ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:07:31.945588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.374158ms","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-03-13T23:07:31.945616Z","caller":"traceutil/trace.go:171","msg":"trace[1065131784] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:899; }","duration":"732.4563ms","start":"2026-03-13T23:07:31.213151Z","end":"2026-03-13T23:07:31.945607Z","steps":["trace[1065131784] 'agreement among raft nodes before linearized reading' (duration: 732.354148ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:31.945643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:31.213132Z","time spent":"732.505132ms","remote":"127.0.0.1:57436","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-03-13T23:07:47.70307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.336455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:47.703148Z","caller":"traceutil/trace.go:171","msg":"trace[526164006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:943; }","duration":"226.450318ms","start":"2026-03-13T23:07:47.476678Z","end":"2026-03-13T23:07:47.703129Z","steps":["trace[526164006] 'range keys from in-memory index tree' (duration: 226.265953ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:48.25282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.393674ms","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-03-13T23:07:48.252901Z","caller":"traceutil/trace.go:171","msg":"trace[1966903854] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:945; }","duration":"142.517686ms","start":"2026-03-13T23:07:48.110367Z","end":"2026-03-13T23:07:48.252885Z","steps":["trace[1966903854] 'range keys from in-memory index tree' (duration: 142.172848ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:48.2529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.579724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:07:48.252982Z","caller":"traceutil/trace.go:171","msg":"trace[881875022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:945; }","duration":"112.724507ms","start":"2026-03-13T23:07:48.140236Z","end":"2026-03-13T23:07:48.25296Z","steps":["trace[881875022] 'range keys from in-memory index tree' (duration: 112.362598ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:49.016052Z","caller":"traceutil/trace.go:171","msg":"trace[1658290853] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:986; }","duration":"718.413401ms","start":"2026-03-13T23:07:48.297621Z","end":"2026-03-13T23:07:49.016035Z","steps":["trace[1658290853] 'read index received' (duration: 18.373591ms)","trace[1658290853] 'applied index is now lower than readState.Index' (duration: 700.03898ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:49.016125Z","caller":"traceutil/trace.go:171","msg":"trace[202814905] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"758.554824ms","start":"2026-03-13T23:07:48.257537Z","end":"2026-03-13T23:07:49.016092Z","steps":["trace[202814905] 'process raft request' (duration: 758.341299ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:49.016137Z","caller":"traceutil/trace.go:171","msg":"trace[1511364366] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"757.646893ms","start":"2026-03-13T23:07:48.258466Z","end":"2026-03-13T23:07:49.016113Z","steps":["trace[1511364366] 'process raft request' (duration: 757.53887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:49.016188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.553405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:07:49.016212Z","caller":"traceutil/trace.go:171","msg":"trace[941738555] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:948; }","duration":"718.629896ms","start":"2026-03-13T23:07:48.297575Z","end":"2026-03-13T23:07:49.016205Z","steps":["trace[941738555] 'agreement among raft nodes before linearized reading' (duration: 718.551284ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:49.01623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:48.297558Z","time spent":"718.667377ms","remote":"127.0.0.1:57420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:07:49.016266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:48.258454Z","time spent":"757.742045ms","remote":"127.0.0.1:57436","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-03-13T23:07:49.016273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:48.257512Z","time spent":"758.679676ms","remote":"127.0.0.1:57436","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-03-13T23:07:50.552343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.997561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:07:50.552454Z","caller":"traceutil/trace.go:171","msg":"trace[1129386254] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:991; }","duration":"232.05929ms","start":"2026-03-13T23:07:50.320376Z","end":"2026-03-13T23:07:50.552435Z","steps":["trace[1129386254] 'read index received' (duration: 56.641µs)","trace[1129386254] 'applied index is now lower than readState.Index' (duration: 232.001299ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:50.552541Z","caller":"traceutil/trace.go:171","msg":"trace[1455058401] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"447.392566ms","start":"2026-03-13T23:07:50.105113Z","end":"2026-03-13T23:07:50.552505Z","steps":["trace[1455058401] 'process raft request' (duration: 19.138699ms)","trace[1455058401] 'compare' (duration: 427.800887ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:50.55265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:50.105087Z","time spent":"447.500559ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:07:50.552663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.290935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-13T23:07:50.552694Z","caller":"traceutil/trace.go:171","msg":"trace[347703615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"232.385087ms","start":"2026-03-13T23:07:50.320299Z","end":"2026-03-13T23:07:50.552684Z","steps":["trace[347703615] 'agreement among raft nodes before linearized reading' (duration: 232.223854ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:51.408973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.409691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-13T23:07:51.409163Z","caller":"traceutil/trace.go:171","msg":"trace[7554741] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:992; }","duration":"389.274901ms","start":"2026-03-13T23:07:51.019873Z","end":"2026-03-13T23:07:51.409148Z","steps":["trace[7554741] 'read index received' (duration: 369.431185ms)","trace[7554741] 'applied index is now lower than readState.Index' (duration: 19.842506ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:07:51.409334Z","caller":"traceutil/trace.go:171","msg":"trace[1333514333] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"853.253827ms","start":"2026-03-13T23:07:50.556069Z","end":"2026-03-13T23:07:51.409322Z","steps":["trace[1333514333] 'process raft request' (duration: 375.425786ms)","trace[1333514333] 'compare' (duration: 477.039453ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:51.4094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:50.556046Z","time spent":"853.32341ms","remote":"127.0.0.1:57436","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-03-13T23:07:51.409556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:50.966718Z","time spent":"442.836339ms","remote":"127.0.0.1:57262","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-13T23:07:51.409784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.907926ms","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-03-13T23:07:51.409824Z","caller":"traceutil/trace.go:171","msg":"trace[112222646] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"389.982938ms","start":"2026-03-13T23:07:51.01983Z","end":"2026-03-13T23:07:51.409813Z","steps":["trace[112222646] 'agreement among raft nodes before linearized reading' (duration: 389.861735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:51.409857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:51.019811Z","time spent":"390.03665ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-13T23:07:51.410037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.136272ms","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-03-13T23:07:51.410072Z","caller":"traceutil/trace.go:171","msg":"trace[1773889403] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"390.207654ms","start":"2026-03-13T23:07:51.019856Z","end":"2026-03-13T23:07:51.410064Z","steps":["trace[1773889403] 'agreement among raft nodes before linearized reading' (duration: 390.113012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:51.410097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:51.019838Z","time spent":"390.254315ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-13T23:07:51.629909Z","caller":"traceutil/trace.go:171","msg":"trace[1002357866] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"218.099333ms","start":"2026-03-13T23:07:51.411785Z","end":"2026-03-13T23:07:51.629884Z","steps":["trace[1002357866] 'process raft request' (duration: 209.216824ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:51.64076Z","caller":"traceutil/trace.go:171","msg":"trace[1980519530] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"226.388826ms","start":"2026-03-13T23:07:51.414348Z","end":"2026-03-13T23:07:51.640737Z","steps":["trace[1980519530] 'process raft request' (duration: 226.333025ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:07:51.640951Z","caller":"traceutil/trace.go:171","msg":"trace[1563666316] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"226.888078ms","start":"2026-03-13T23:07:51.414052Z","end":"2026-03-13T23:07:51.64094Z","steps":["trace[1563666316] 'process raft request' (duration: 226.498989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.192727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.627156ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-13T23:07:53.192951Z","caller":"traceutil/trace.go:171","msg":"trace[1245441742] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"636.80749ms","start":"2026-03-13T23:07:52.556112Z","end":"2026-03-13T23:07:53.19292Z","steps":["trace[1245441742] 'read index received' (duration: 148.940259ms)","trace[1245441742] 'applied index is now lower than readState.Index' (duration: 487.864991ms)"],"step_count":2} {"level":"warn","ts":"2026-03-13T23:07:53.193211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.082717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:07:53.19329Z","caller":"traceutil/trace.go:171","msg":"trace[1255670364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:956; }","duration":"637.195949ms","start":"2026-03-13T23:07:52.55607Z","end":"2026-03-13T23:07:53.193266Z","steps":["trace[1255670364] 'agreement among raft nodes before linearized reading' (duration: 637.000034ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.193347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:52.556052Z","time spent":"637.283062ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:07:53.193549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.10682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:53.193613Z","caller":"traceutil/trace.go:171","msg":"trace[1483814877] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:957; }","duration":"111.207663ms","start":"2026-03-13T23:07:53.082389Z","end":"2026-03-13T23:07:53.193597Z","steps":["trace[1483814877] 'agreement among raft nodes before linearized reading' (duration: 111.10529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.193574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.26023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:07:53.193815Z","caller":"traceutil/trace.go:171","msg":"trace[1213355124] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:957; }","duration":"445.548917ms","start":"2026-03-13T23:07:52.748239Z","end":"2026-03-13T23:07:53.193787Z","steps":["trace[1213355124] 'agreement among raft nodes before linearized reading' (duration: 445.22169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.194042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:52.74822Z","time spent":"445.717951ms","remote":"127.0.0.1:57528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-03-13T23:07:53.193508Z","caller":"traceutil/trace.go:171","msg":"trace[146024849] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"378.073862ms","start":"2026-03-13T23:07:52.815406Z","end":"2026-03-13T23:07:53.19348Z","steps":["trace[146024849] 'process raft request' (duration: 377.428057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.194583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:07:52.815382Z","time spent":"378.961622ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:07:53.517801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.577813ms","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-03-13T23:07:53.517991Z","caller":"traceutil/trace.go:171","msg":"trace[859323837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:958; }","duration":"103.813979ms","start":"2026-03-13T23:07:53.414152Z","end":"2026-03-13T23:07:53.517966Z","steps":["trace[859323837] 'range keys from in-memory index tree' (duration: 103.386088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.854077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.987343ms","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-03-13T23:07:53.854159Z","caller":"traceutil/trace.go:171","msg":"trace[135760646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:960; }","duration":"210.101175ms","start":"2026-03-13T23:07:53.64404Z","end":"2026-03-13T23:07:53.854141Z","steps":["trace[135760646] 'range keys from in-memory index tree' (duration: 209.825589ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:07:53.854224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.197919ms","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-03-13T23:07:53.854309Z","caller":"traceutil/trace.go:171","msg":"trace[673076719] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:960; }","duration":"210.335381ms","start":"2026-03-13T23:07:53.643954Z","end":"2026-03-13T23:07:53.85429Z","steps":["trace[673076719] 'range keys from in-memory index tree' (duration: 209.813039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:03.306367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413370139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:08:03.806808Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413370139,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:08:04.12678Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.923759643s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:08:04.127563Z","caller":"traceutil/trace.go:171","msg":"trace[1934743513] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.92451149s","start":"2026-03-13T23:08:02.202907Z","end":"2026-03-13T23:08:04.127418Z","steps":["trace[1934743513] 'process raft request' (duration: 1.924327966s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.127795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:02.202891Z","time spent":"1.924819748s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-13T23:08:04.13135Z","caller":"traceutil/trace.go:171","msg":"trace[2120607944] linearizableReadLoop","detail":"{readStateIndex:1090; appliedIndex:1087; }","duration":"1.328963369s","start":"2026-03-13T23:08:02.802371Z","end":"2026-03-13T23:08:04.131334Z","steps":["trace[2120607944] 'read index received' (duration: 1.324817682s)","trace[2120607944] 'applied index is now lower than readState.Index' (duration: 4.144697ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:08:04.131386Z","caller":"traceutil/trace.go:171","msg":"trace[2121365482] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"792.092068ms","start":"2026-03-13T23:08:03.339278Z","end":"2026-03-13T23:08:04.13137Z","steps":["trace[2121365482] 'process raft request' (duration: 791.946895ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.131474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.339259Z","time spent":"792.17344ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:08:04.131559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329205595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T23:08:04.131602Z","caller":"traceutil/trace.go:171","msg":"trace[915173248] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1045; }","duration":"1.329303428s","start":"2026-03-13T23:08:02.802283Z","end":"2026-03-13T23:08:04.131586Z","steps":["trace[915173248] 'agreement among raft nodes before linearized reading' (duration: 1.329174995s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.131724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.073058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-13T23:08:04.131763Z","caller":"traceutil/trace.go:171","msg":"trace[110144122] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1045; }","duration":"941.106469ms","start":"2026-03-13T23:08:03.190638Z","end":"2026-03-13T23:08:04.131745Z","steps":["trace[110144122] 'agreement among raft nodes before linearized reading' (duration: 941.054918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.131785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.190581Z","time spent":"941.197591ms","remote":"127.0.0.1:41456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-03-13T23:08:04.131914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.868697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-13T23:08:04.13194Z","caller":"traceutil/trace.go:171","msg":"trace[2120512322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"655.919288ms","start":"2026-03-13T23:08:03.476014Z","end":"2026-03-13T23:08:04.131933Z","steps":["trace[2120512322] 'agreement among raft nodes before linearized reading' (duration: 655.879838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.131959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.475997Z","time spent":"655.957419ms","remote":"127.0.0.1:57200","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-03-13T23:08:04.132072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.730269ms","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-03-13T23:08:04.132136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.005818ms","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:507"} {"level":"info","ts":"2026-03-13T23:08:04.13215Z","caller":"traceutil/trace.go:171","msg":"trace[286667827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1045; }","duration":"204.838022ms","start":"2026-03-13T23:08:03.927293Z","end":"2026-03-13T23:08:04.132131Z","steps":["trace[286667827] 'agreement among raft nodes before linearized reading' (duration: 204.566526ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:08:04.132193Z","caller":"traceutil/trace.go:171","msg":"trace[420062940] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1045; }","duration":"907.102509ms","start":"2026-03-13T23:08:03.225071Z","end":"2026-03-13T23:08:04.132174Z","steps":["trace[420062940] 'agreement among raft nodes before linearized reading' (duration: 906.931455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.132167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.232898ms","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-03-13T23:08:04.13224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.225037Z","time spent":"907.194941ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-13T23:08:04.132267Z","caller":"traceutil/trace.go:171","msg":"trace[765239265] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1045; }","duration":"224.388102ms","start":"2026-03-13T23:08:03.907857Z","end":"2026-03-13T23:08:04.132245Z","steps":["trace[765239265] 'agreement among raft nodes before linearized reading' (duration: 224.048184ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.132337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:02.802244Z","time spent":"1.32940773s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-13T23:08:04.132476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.042459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-13T23:08:04.132514Z","caller":"traceutil/trace.go:171","msg":"trace[1911041977] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1045; }","duration":"873.979091ms","start":"2026-03-13T23:08:03.258524Z","end":"2026-03-13T23:08:04.132503Z","steps":["trace[1911041977] 'agreement among raft nodes before linearized reading' (duration: 873.879759ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.132536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.258504Z","time spent":"874.026923ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-13T23:08:04.132904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.011251ms","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-03-13T23:08:04.132995Z","caller":"traceutil/trace.go:171","msg":"trace[1366365297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"510.169775ms","start":"2026-03-13T23:08:03.622797Z","end":"2026-03-13T23:08:04.132966Z","steps":["trace[1366365297] 'agreement among raft nodes before linearized reading' (duration: 508.907655ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:08:04.133056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:08:03.622776Z","time spent":"510.269978ms","remote":"127.0.0.1:57436","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-03-13T23:10:14.947245Z","caller":"traceutil/trace.go:171","msg":"trace[1641597887] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1649; }","duration":"197.626062ms","start":"2026-03-13T23:10:14.749591Z","end":"2026-03-13T23:10:14.947217Z","steps":["trace[1641597887] 'read index received' (duration: 197.359826ms)","trace[1641597887] 'applied index is now lower than readState.Index' (duration: 264.756µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:10:14.947418Z","caller":"traceutil/trace.go:171","msg":"trace[1368484482] transaction","detail":"{read_only:false; response_revision:1579; number_of_response:1; }","duration":"208.674683ms","start":"2026-03-13T23:10:14.738623Z","end":"2026-03-13T23:10:14.947298Z","steps":["trace[1368484482] 'process raft request' (duration: 208.440647ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:14.947756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.706085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-13T23:10:14.947851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.356968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4219"} {"level":"warn","ts":"2026-03-13T23:10:14.947859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.120998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-13T23:10:14.947825Z","caller":"traceutil/trace.go:171","msg":"trace[541678462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1579; }","duration":"186.823117ms","start":"2026-03-13T23:10:14.760983Z","end":"2026-03-13T23:10:14.947806Z","steps":["trace[541678462] 'agreement among raft nodes before linearized reading' (duration: 186.448818ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:14.947924Z","caller":"traceutil/trace.go:171","msg":"trace[1804921291] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:1579; }","duration":"100.454099ms","start":"2026-03-13T23:10:14.847449Z","end":"2026-03-13T23:10:14.947904Z","steps":["trace[1804921291] 'agreement among raft nodes before linearized reading' (duration: 100.276015ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:14.947958Z","caller":"traceutil/trace.go:171","msg":"trace[406732821] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1579; }","duration":"113.257282ms","start":"2026-03-13T23:10:14.834676Z","end":"2026-03-13T23:10:14.947933Z","steps":["trace[406732821] 'agreement among raft nodes before linearized reading' (duration: 112.900383ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:14.947991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.342718ms","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-03-13T23:10:14.948096Z","caller":"traceutil/trace.go:171","msg":"trace[1878796250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1579; }","duration":"181.472931ms","start":"2026-03-13T23:10:14.7666Z","end":"2026-03-13T23:10:14.948073Z","steps":["trace[1878796250] 'agreement among raft nodes before linearized reading' (duration: 181.283137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:14.948169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.831336ms","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-03-13T23:10:14.948247Z","caller":"traceutil/trace.go:171","msg":"trace[760631652] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1579; }","duration":"180.978379ms","start":"2026-03-13T23:10:14.767254Z","end":"2026-03-13T23:10:14.948233Z","steps":["trace[760631652] 'agreement among raft nodes before linearized reading' (duration: 180.532608ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:14.948343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.753108ms","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-03-13T23:10:14.948418Z","caller":"traceutil/trace.go:171","msg":"trace[1933808007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1579; }","duration":"198.863701ms","start":"2026-03-13T23:10:14.749542Z","end":"2026-03-13T23:10:14.948406Z","steps":["trace[1933808007] 'agreement among raft nodes before linearized reading' (duration: 198.661576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:14.948432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.609429ms","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-03-13T23:10:14.94872Z","caller":"traceutil/trace.go:171","msg":"trace[794561064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1579; }","duration":"110.897286ms","start":"2026-03-13T23:10:14.8378Z","end":"2026-03-13T23:10:14.948698Z","steps":["trace[794561064] 'agreement among raft nodes before linearized reading' (duration: 110.270491ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:10:19.686409Z","caller":"traceutil/trace.go:171","msg":"trace[2099402347] linearizableReadLoop","detail":"{readStateIndex:1673; appliedIndex:1671; }","duration":"244.693903ms","start":"2026-03-13T23:10:19.441695Z","end":"2026-03-13T23:10:19.686388Z","steps":["trace[2099402347] 'read index received' (duration: 217.059211ms)","trace[2099402347] 'applied index is now lower than readState.Index' (duration: 27.633662ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:10:19.686416Z","caller":"traceutil/trace.go:171","msg":"trace[359415410] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"703.530326ms","start":"2026-03-13T23:10:18.982854Z","end":"2026-03-13T23:10:19.686385Z","steps":["trace[359415410] 'process raft request' (duration: 675.884943ms)","trace[359415410] 'compare' (duration: 27.144651ms)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:10:19.686473Z","caller":"traceutil/trace.go:171","msg":"trace[29112456] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"701.942719ms","start":"2026-03-13T23:10:18.984524Z","end":"2026-03-13T23:10:19.686467Z","steps":["trace[29112456] 'process raft request' (duration: 701.761725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:19.686545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:18.982846Z","time spent":"703.629579ms","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:10:19.686572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:18.984515Z","time spent":"701.98392ms","remote":"127.0.0.1:57436","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-03-13T23:10:19.686669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.967069ms","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:508"} {"level":"info","ts":"2026-03-13T23:10:19.686738Z","caller":"traceutil/trace.go:171","msg":"trace[742997956] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1601; }","duration":"245.069462ms","start":"2026-03-13T23:10:19.441653Z","end":"2026-03-13T23:10:19.686722Z","steps":["trace[742997956] 'agreement among raft nodes before linearized reading' (duration: 244.910398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:21.482382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413371574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:10:22.345685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978058685413371574,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-13T23:10:22.363764Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.381959474s","expected-duration":"1s"} {"level":"info","ts":"2026-03-13T23:10:22.364231Z","caller":"traceutil/trace.go:171","msg":"trace[170488798] linearizableReadLoop","detail":"{readStateIndex:1677; appliedIndex:1676; }","duration":"1.382301052s","start":"2026-03-13T23:10:20.981899Z","end":"2026-03-13T23:10:22.3642Z","steps":["trace[170488798] 'read index received' (duration: 1.382120287s)","trace[170488798] 'applied index is now lower than readState.Index' (duration: 179.585µs)"],"step_count":2} {"level":"info","ts":"2026-03-13T23:10:22.364331Z","caller":"traceutil/trace.go:171","msg":"trace[2053861500] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"1.382613159s","start":"2026-03-13T23:10:20.981693Z","end":"2026-03-13T23:10:22.364306Z","steps":["trace[2053861500] 'process raft request' (duration: 1.382346843s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:22.364488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:20.981665Z","time spent":"1.382713652s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-13T23:10:22.364477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.382557237s","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-03-13T23:10:22.364558Z","caller":"traceutil/trace.go:171","msg":"trace[1619152440] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1605; }","duration":"1.382689631s","start":"2026-03-13T23:10:20.981849Z","end":"2026-03-13T23:10:22.364538Z","steps":["trace[1619152440] 'agreement among raft nodes before linearized reading' (duration: 1.382452895s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:22.364609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:20.981828Z","time spent":"1.382771893s","remote":"127.0.0.1:57436","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-03-13T23:10:22.367354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383083671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-13T23:10:22.367407Z","caller":"traceutil/trace.go:171","msg":"trace[427991900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1605; }","duration":"1.383173812s","start":"2026-03-13T23:10:20.98422Z","end":"2026-03-13T23:10:22.367394Z","steps":["trace[427991900] 'agreement among raft nodes before linearized reading' (duration: 1.383022328s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:22.36744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:20.98421Z","time spent":"1.383223334s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-13T23:10:22.367653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361801868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-13T23:10:22.36769Z","caller":"traceutil/trace.go:171","msg":"trace[381886577] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1605; }","duration":"1.36184882s","start":"2026-03-13T23:10:21.00583Z","end":"2026-03-13T23:10:22.367679Z","steps":["trace[381886577] 'agreement among raft nodes before linearized reading' (duration: 1.361732397s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:22.367722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:21.00582Z","time spent":"1.361894961s","remote":"127.0.0.1:57436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-13T23:10:22.367853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383556712s","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-03-13T23:10:22.367883Z","caller":"traceutil/trace.go:171","msg":"trace[1877183271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1605; }","duration":"1.383617913s","start":"2026-03-13T23:10:20.984257Z","end":"2026-03-13T23:10:22.367875Z","steps":["trace[1877183271] 'agreement among raft nodes before linearized reading' (duration: 1.383538182s)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:10:22.367913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-13T23:10:20.984245Z","time spent":"1.383662414s","remote":"127.0.0.1:57436","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-03-13T23:11:16.744132Z","caller":"traceutil/trace.go:171","msg":"trace[274547641] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"106.6593ms","start":"2026-03-13T23:11:16.637445Z","end":"2026-03-13T23:11:16.744104Z","steps":["trace[274547641] 'process raft request' (duration: 106.427745ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:16.754191Z","caller":"traceutil/trace.go:171","msg":"trace[1956857529] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"115.774435ms","start":"2026-03-13T23:11:16.638395Z","end":"2026-03-13T23:11:16.754169Z","steps":["trace[1956857529] 'process raft request' (duration: 115.626701ms)"],"step_count":1} {"level":"info","ts":"2026-03-13T23:11:16.754507Z","caller":"traceutil/trace.go:171","msg":"trace[1676715347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1859; }","duration":"100.020523ms","start":"2026-03-13T23:11:16.654472Z","end":"2026-03-13T23:11:16.754493Z","steps":["trace[1676715347] 'agreement among raft nodes before linearized reading' (duration: 99.974961ms)"],"step_count":1} {"level":"warn","ts":"2026-03-13T23:11:41.138378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.901618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:token-cleaner\" ","response":"range_response_count:1 size:670"} {"level":"info","ts":"2026-03-13T23:11:41.138476Z","caller":"traceutil/trace.go:171","msg":"trace[311190312] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:token-cleaner; range_end:; response_count:1; response_revision:1970; }","duration":"124.045082ms","start":"2026-03-13T23:11:41.014408Z","end":"2026-03-13T23:11:41.138453Z","steps":["trace[311190312] 'range keys from in-memory index tree' (duration: 123.726473ms)"],"step_count":1}