{"level":"warn","ts":"2026-02-02T18:06:37.655148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T18:06:37.65539Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.52: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.52:2380","--initial-cluster=instance=https://162.253.55.52:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.52:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.52: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-02-02T18:06:37.655558Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T18:06:37.655586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-02T18:06:37.655635Z","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-02-02T18:06:37.656554Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"]} {"level":"info","ts":"2026-02-02T18:06:37.65664Z","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":2,"max-cpu-available":2,"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.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.52: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-02-02T18:06:37.661812Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.144056ms"} {"level":"info","ts":"2026-02-02T18:06:37.682438Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b11e7fc5714be64b","cluster-id":"9349e4f32a52a90a"} {"level":"info","ts":"2026-02-02T18:06:37.682573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T18:06:37.68274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 0"} {"level":"info","ts":"2026-02-02T18:06:37.682814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b11e7fc5714be64b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T18:06:37.682874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became follower at term 1"} {"level":"info","ts":"2026-02-02T18:06:37.682966Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"warn","ts":"2026-02-02T18:06:37.694843Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T18:06:37.708454Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T18:06:37.716187Z","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-02-02T18:06:37.724888Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b11e7fc5714be64b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T18:06:37.725465Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b11e7fc5714be64b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T18:06:37.737104Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b switched to configuration voters=(12762778880000648779)"} {"level":"info","ts":"2026-02-02T18:06:37.737377Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","added-peer-id":"b11e7fc5714be64b","added-peer-peer-urls":["https://162.253.55.52:2380"]} {"level":"info","ts":"2026-02-02T18:06:37.73752Z","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-02-02T18:06:37.744203Z","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-02-02T18:06:37.744253Z","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-02-02T18:06:37.744306Z","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-02-02T18:06:37.744672Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b11e7fc5714be64b","initial-advertise-peer-urls":["https://162.253.55.52:2380"],"listen-peer-urls":["https://162.253.55.52:2380"],"advertise-client-urls":["https://162.253.55.52:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.52:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T18:06:37.7452Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-02T18:06:37.745306Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.52:2380"} {"level":"info","ts":"2026-02-02T18:06:37.74534Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T18:06:38.184136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T18:06:38.184244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T18:06:38.184282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgPreVoteResp from b11e7fc5714be64b at term 1"} {"level":"info","ts":"2026-02-02T18:06:38.184323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became candidate at term 2"} {"level":"info","ts":"2026-02-02T18:06:38.184349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b received MsgVoteResp from b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-02T18:06:38.184373Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b11e7fc5714be64b became leader at term 2"} {"level":"info","ts":"2026-02-02T18:06:38.18444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b11e7fc5714be64b elected leader b11e7fc5714be64b at term 2"} {"level":"info","ts":"2026-02-02T18:06:38.207773Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:38.209202Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b11e7fc5714be64b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.52:2379]}","request-path":"/0/members/b11e7fc5714be64b/attributes","cluster-id":"9349e4f32a52a90a","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T18:06:38.209244Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:06:38.209279Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T18:06:38.211235Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.52:2379"} {"level":"info","ts":"2026-02-02T18:06:38.215099Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T18:06:38.215119Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T18:06:38.216659Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T18:06:38.27565Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9349e4f32a52a90a","local-member-id":"b11e7fc5714be64b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:38.275808Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T18:06:38.275851Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-02-02T18:06:49.421343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.726353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-02T18:06:49.421431Z","caller":"traceutil/trace.go:171","msg":"trace[1889606191] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:345; }","duration":"750.882257ms","start":"2026-02-02T18:06:48.670533Z","end":"2026-02-02T18:06:49.421416Z","steps":["trace[1889606191] 'range keys from in-memory index tree' (duration: 750.61141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:06:49.421507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:48.670516Z","time spent":"750.98108ms","remote":"127.0.0.1:39008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-02-02T18:07:00.27249Z","caller":"traceutil/trace.go:171","msg":"trace[716174387] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"122.748845ms","start":"2026-02-02T18:07:00.149726Z","end":"2026-02-02T18:07:00.272474Z","steps":["trace[716174387] 'read index received' (duration: 122.608702ms)","trace[716174387] 'applied index is now lower than readState.Index' (duration: 139.463µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:00.27279Z","caller":"traceutil/trace.go:171","msg":"trace[2100192425] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"550.607354ms","start":"2026-02-02T18:06:59.722147Z","end":"2026-02-02T18:07:00.272754Z","steps":["trace[2100192425] 'process raft request' (duration: 550.236036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:00.273157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.555894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T18:07:00.273216Z","caller":"traceutil/trace.go:171","msg":"trace[933007171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:426; }","duration":"120.656286ms","start":"2026-02-02T18:07:00.152546Z","end":"2026-02-02T18:07:00.273202Z","steps":["trace[933007171] 'agreement among raft nodes before linearized reading' (duration: 120.557294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:00.273507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.786718ms","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-02-02T18:07:00.273534Z","caller":"traceutil/trace.go:171","msg":"trace[889419537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:426; }","duration":"123.87018ms","start":"2026-02-02T18:07:00.149658Z","end":"2026-02-02T18:07:00.273528Z","steps":["trace[889419537] 'agreement among raft nodes before linearized reading' (duration: 123.822849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:00.274975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:06:59.722133Z","time spent":"550.747027ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:06.092029Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:06.592867Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:07.093886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021651,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:07.242147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.735882947s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:07:07.242804Z","caller":"traceutil/trace.go:171","msg":"trace[964193732] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"1.736615794s","start":"2026-02-02T18:07:05.506165Z","end":"2026-02-02T18:07:07.242781Z","steps":["trace[964193732] 'process raft request' (duration: 1.736487581s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.242941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:05.506145Z","time spent":"1.736725196s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:07.592025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000611998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T18:07:07.592088Z","caller":"traceutil/trace.go:171","msg":"trace[342249653] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000693049s","start":"2026-02-02T18:07:05.59138Z","end":"2026-02-02T18:07:07.592073Z","steps":["trace[342249653] 'agreement among raft nodes before linearized reading' (duration: 2.000606678s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.592124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:05.591366Z","time spent":"2.00075086s","remote":"127.0.0.1:38794","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-02-02T18:07:07.714081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.050516ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-02T18:07:07.71416Z","caller":"traceutil/trace.go:171","msg":"trace[1470835770] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:457; }","duration":"2.122764175s","start":"2026-02-02T18:07:05.591384Z","end":"2026-02-02T18:07:07.714149Z","steps":["trace[1470835770] 'read index received' (duration: 1.651980322s)","trace[1470835770] 'applied index is now lower than readState.Index' (duration: 470.783193ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:07.714249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416679925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:07:07.714262Z","caller":"traceutil/trace.go:171","msg":"trace[561472430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:440; }","duration":"1.416746946s","start":"2026-02-02T18:07:06.297511Z","end":"2026-02-02T18:07:07.714258Z","steps":["trace[561472430] 'agreement among raft nodes before linearized reading' (duration: 1.416701325s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.714312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:06.297485Z","time spent":"1.416791738s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:07:07.714316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.101765635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:07.714397Z","caller":"traceutil/trace.go:171","msg":"trace[1847919031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"1.101895178s","start":"2026-02-02T18:07:06.612479Z","end":"2026-02-02T18:07:07.714374Z","steps":["trace[1847919031] 'agreement among raft nodes before linearized reading' (duration: 1.101730934s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.714453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:06.612461Z","time spent":"1.10197941s","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:07:07.714471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403591157s","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-02-02T18:07:07.714488Z","caller":"traceutil/trace.go:171","msg":"trace[1556123596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:440; }","duration":"1.403632147s","start":"2026-02-02T18:07:06.310852Z","end":"2026-02-02T18:07:07.714484Z","steps":["trace[1556123596] 'agreement among raft nodes before linearized reading' (duration: 1.403604457s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.7145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:06.310841Z","time spent":"1.403656049s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:07:07.714589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.409863921s","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-02-02T18:07:07.714614Z","caller":"traceutil/trace.go:171","msg":"trace[1539733018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:440; }","duration":"1.409890221s","start":"2026-02-02T18:07:06.30472Z","end":"2026-02-02T18:07:07.71461Z","steps":["trace[1539733018] 'agreement among raft nodes before linearized reading' (duration: 1.40985484s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:07.714626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:06.30468Z","time spent":"1.409943203s","remote":"127.0.0.1:39082","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-02-02T18:07:07.714881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.756222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:07.714925Z","caller":"traceutil/trace.go:171","msg":"trace[1217110424] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:440; }","duration":"122.844254ms","start":"2026-02-02T18:07:07.592068Z","end":"2026-02-02T18:07:07.714912Z","steps":["trace[1217110424] 'agreement among raft nodes before linearized reading' (duration: 122.752552ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:07:15.696492Z","caller":"traceutil/trace.go:171","msg":"trace[1669147536] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"249.112243ms","start":"2026-02-02T18:07:15.447363Z","end":"2026-02-02T18:07:15.696475Z","steps":["trace[1669147536] 'read index received' (duration: 248.997501ms)","trace[1669147536] 'applied index is now lower than readState.Index' (duration: 114.202µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:15.696667Z","caller":"traceutil/trace.go:171","msg":"trace[51628902] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"349.975975ms","start":"2026-02-02T18:07:15.346685Z","end":"2026-02-02T18:07:15.696661Z","steps":["trace[51628902] 'process raft request' (duration: 349.707709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:15.696743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:15.34667Z","time spent":"350.011466ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:15.696842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.487611ms","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-02-02T18:07:15.696858Z","caller":"traceutil/trace.go:171","msg":"trace[1342588307] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:528; }","duration":"249.564032ms","start":"2026-02-02T18:07:15.447289Z","end":"2026-02-02T18:07:15.696853Z","steps":["trace[1342588307] 'agreement among raft nodes before linearized reading' (duration: 249.530412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:15.696954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.460748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-02T18:07:15.696968Z","caller":"traceutil/trace.go:171","msg":"trace[120061266] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:528; }","duration":"209.494818ms","start":"2026-02-02T18:07:15.487468Z","end":"2026-02-02T18:07:15.696963Z","steps":["trace[120061266] 'agreement among raft nodes before linearized reading' (duration: 209.440967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:15.697178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.584215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:15.697192Z","caller":"traceutil/trace.go:171","msg":"trace[152440960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"104.621506ms","start":"2026-02-02T18:07:15.592566Z","end":"2026-02-02T18:07:15.697187Z","steps":["trace[152440960] 'agreement among raft nodes before linearized reading' (duration: 104.558454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.588329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021821,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:16.615346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.09816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:07:16.615403Z","caller":"traceutil/trace.go:171","msg":"trace[2890678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:529; }","duration":"818.241823ms","start":"2026-02-02T18:07:15.797147Z","end":"2026-02-02T18:07:16.615389Z","steps":["trace[2890678] 'range keys from in-memory index tree' (duration: 817.993969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.615442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:15.797109Z","time spent":"818.325075ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:07:16.61571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.244611ms","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-02-02T18:07:16.615746Z","caller":"traceutil/trace.go:171","msg":"trace[995344761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"808.323153ms","start":"2026-02-02T18:07:15.807416Z","end":"2026-02-02T18:07:16.615739Z","steps":["trace[995344761] 'range keys from in-memory index tree' (duration: 808.065287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.615764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:15.807394Z","time spent":"808.365763ms","remote":"127.0.0.1:39082","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-02-02T18:07:16.616492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.857182ms","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-02-02T18:07:16.616536Z","caller":"traceutil/trace.go:171","msg":"trace[361888911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"790.930144ms","start":"2026-02-02T18:07:15.825595Z","end":"2026-02-02T18:07:16.616525Z","steps":["trace[361888911] 'range keys from in-memory index tree' (duration: 790.769521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.616564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:15.825581Z","time spent":"790.972975ms","remote":"127.0.0.1:39082","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-02-02T18:07:16.619192Z","caller":"traceutil/trace.go:171","msg":"trace[1463607397] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"725.816ms","start":"2026-02-02T18:07:15.893367Z","end":"2026-02-02T18:07:16.619183Z","steps":["trace[1463607397] 'process raft request' (duration: 725.735418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.61925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:15.893346Z","time spent":"725.873692ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:07:16.623Z","caller":"traceutil/trace.go:171","msg":"trace[241926724] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"535.680945ms","start":"2026-02-02T18:07:16.08731Z","end":"2026-02-02T18:07:16.622991Z","steps":["trace[241926724] 'read index received' (duration: 531.694255ms)","trace[241926724] 'applied index is now lower than readState.Index' (duration: 3.98624ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:16.623141Z","caller":"traceutil/trace.go:171","msg":"trace[169441145] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"330.740506ms","start":"2026-02-02T18:07:16.292376Z","end":"2026-02-02T18:07:16.623116Z","steps":["trace[169441145] 'process raft request' (duration: 330.554512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.623177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:16.292359Z","time spent":"330.795408ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T18:07:16.623351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.039182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-02-02T18:07:16.623392Z","caller":"traceutil/trace.go:171","msg":"trace[2048217167] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"536.118084ms","start":"2026-02-02T18:07:16.087267Z","end":"2026-02-02T18:07:16.623385Z","steps":["trace[2048217167] 'agreement among raft nodes before linearized reading' (duration: 535.985221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:16.623408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:16.087249Z","time spent":"536.155285ms","remote":"127.0.0.1:38854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "} {"level":"warn","ts":"2026-02-02T18:07:16.623549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.038969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-02T18:07:16.623568Z","caller":"traceutil/trace.go:171","msg":"trace[85735272] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:531; }","duration":"136.10199ms","start":"2026-02-02T18:07:16.487461Z","end":"2026-02-02T18:07:16.623563Z","steps":["trace[85735272] 'agreement among raft nodes before linearized reading' (duration: 136.030058ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:07:23.711915Z","caller":"traceutil/trace.go:171","msg":"trace[1907863201] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"117.966155ms","start":"2026-02-02T18:07:23.593931Z","end":"2026-02-02T18:07:23.711897Z","steps":["trace[1907863201] 'read index received' (duration: 117.786341ms)","trace[1907863201] 'applied index is now lower than readState.Index' (duration: 179.163µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:23.712111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.160739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:23.712134Z","caller":"traceutil/trace.go:171","msg":"trace[1895870384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"118.243051ms","start":"2026-02-02T18:07:23.593884Z","end":"2026-02-02T18:07:23.712127Z","steps":["trace[1895870384] 'agreement among raft nodes before linearized reading' (duration: 118.169199ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:07:23.712355Z","caller":"traceutil/trace.go:171","msg":"trace[1725547320] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"130.982025ms","start":"2026-02-02T18:07:23.581347Z","end":"2026-02-02T18:07:23.712329Z","steps":["trace[1725547320] 'process raft request' (duration: 130.420743ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:07:23.884789Z","caller":"traceutil/trace.go:171","msg":"trace[926724911] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"127.880698ms","start":"2026-02-02T18:07:23.756888Z","end":"2026-02-02T18:07:23.884769Z","steps":["trace[926724911] 'read index received' (duration: 107.85745ms)","trace[926724911] 'applied index is now lower than readState.Index' (duration: 20.022448ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:23.884964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.060812ms","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-02-02T18:07:23.885213Z","caller":"traceutil/trace.go:171","msg":"trace[1348079671] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"158.477072ms","start":"2026-02-02T18:07:23.726688Z","end":"2026-02-02T18:07:23.885165Z","steps":["trace[1348079671] 'process raft request' (duration: 138.124517ms)","trace[1348079671] 'compare' (duration: 19.872384ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:23.886171Z","caller":"traceutil/trace.go:171","msg":"trace[640784462] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:567; }","duration":"128.129643ms","start":"2026-02-02T18:07:23.756849Z","end":"2026-02-02T18:07:23.884979Z","steps":["trace[640784462] 'agreement among raft nodes before linearized reading' (duration: 127.99316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:25.093019Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:25.593373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:26.09438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233021936,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T18:07:26.12043Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.88132444s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T18:07:26.120837Z","caller":"traceutil/trace.go:171","msg":"trace[1356881468] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"1.528488851s","start":"2026-02-02T18:07:24.592328Z","end":"2026-02-02T18:07:26.120817Z","steps":["trace[1356881468] 'read index received' (duration: 1.528304117s)","trace[1356881468] 'applied index is now lower than readState.Index' (duration: 184.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:26.120929Z","caller":"traceutil/trace.go:171","msg":"trace[1322706939] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.881945343s","start":"2026-02-02T18:07:24.238975Z","end":"2026-02-02T18:07:26.12092Z","steps":["trace[1322706939] 'process raft request' (duration: 1.881717948s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.121038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:24.238887Z","time spent":"1.882073076s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:26.121286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.528955971s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:26.121325Z","caller":"traceutil/trace.go:171","msg":"trace[1910799368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"1.529023822s","start":"2026-02-02T18:07:24.59229Z","end":"2026-02-02T18:07:26.121314Z","steps":["trace[1910799368] 'agreement among raft nodes before linearized reading' (duration: 1.528969331s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.121352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:24.592273Z","time spent":"1.529073594s","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:07:26.121559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447015071s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:07:26.121595Z","caller":"traceutil/trace.go:171","msg":"trace[1610517401] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"1.447087382s","start":"2026-02-02T18:07:24.674498Z","end":"2026-02-02T18:07:26.121585Z","steps":["trace[1610517401] 'agreement among raft nodes before linearized reading' (duration: 1.446967559s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.12162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:24.674464Z","time spent":"1.447150524s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:07:26.121812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443279888s","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-02-02T18:07:26.121845Z","caller":"traceutil/trace.go:171","msg":"trace[317891883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"1.443330979s","start":"2026-02-02T18:07:24.678506Z","end":"2026-02-02T18:07:26.121837Z","steps":["trace[317891883] 'agreement among raft nodes before linearized reading' (duration: 1.443252717s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.12187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:24.678497Z","time spent":"1.4433673s","remote":"127.0.0.1:39082","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-02-02T18:07:26.122016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445180469s","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-02-02T18:07:26.122057Z","caller":"traceutil/trace.go:171","msg":"trace[301132331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"1.445241601s","start":"2026-02-02T18:07:24.676806Z","end":"2026-02-02T18:07:26.122048Z","steps":["trace[301132331] 'agreement among raft nodes before linearized reading' (duration: 1.445158519s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.122083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:24.676795Z","time spent":"1.445282122s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:07:26.122009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.094759ms","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-02-02T18:07:26.122236Z","caller":"traceutil/trace.go:171","msg":"trace[1251376802] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"234.357916ms","start":"2026-02-02T18:07:25.88786Z","end":"2026-02-02T18:07:26.122218Z","steps":["trace[1251376802] 'agreement among raft nodes before linearized reading' (duration: 233.843704ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.122559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.739909ms","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-02-02T18:07:26.122598Z","caller":"traceutil/trace.go:171","msg":"trace[2040883771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:569; }","duration":"210.81473ms","start":"2026-02-02T18:07:25.911775Z","end":"2026-02-02T18:07:26.122589Z","steps":["trace[2040883771] 'agreement among raft nodes before linearized reading' (duration: 210.726868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.875231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.40521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:26.875304Z","caller":"traceutil/trace.go:171","msg":"trace[49889365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"750.508273ms","start":"2026-02-02T18:07:26.124777Z","end":"2026-02-02T18:07:26.875285Z","steps":["trace[49889365] 'range keys from in-memory index tree' (duration: 750.338428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.875345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.124736Z","time spent":"750.599285ms","remote":"127.0.0.1:38798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:07:26.892009Z","caller":"traceutil/trace.go:171","msg":"trace[264356117] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"761.385245ms","start":"2026-02-02T18:07:26.130612Z","end":"2026-02-02T18:07:26.891997Z","steps":["trace[264356117] 'process raft request' (duration: 761.304573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.892092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.130598Z","time spent":"761.449347ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:26.894926Z","caller":"traceutil/trace.go:171","msg":"trace[998480479] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"762.823287ms","start":"2026-02-02T18:07:26.132092Z","end":"2026-02-02T18:07:26.894916Z","steps":["trace[998480479] 'process raft request' (duration: 762.613212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.894985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.132087Z","time spent":"762.864038ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:26.895046Z","caller":"traceutil/trace.go:171","msg":"trace[700499978] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"762.902249ms","start":"2026-02-02T18:07:26.132134Z","end":"2026-02-02T18:07:26.895036Z","steps":["trace[700499978] 'process raft request' (duration: 762.594572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.895112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.132129Z","time spent":"762.95315ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:26.895116Z","caller":"traceutil/trace.go:171","msg":"trace[421663509] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"763.111763ms","start":"2026-02-02T18:07:26.131994Z","end":"2026-02-02T18:07:26.895106Z","steps":["trace[421663509] 'process raft request' (duration: 762.667363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.895176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.131978Z","time spent":"763.178095ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:26.895222Z","caller":"traceutil/trace.go:171","msg":"trace[1944356339] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"762.01177ms","start":"2026-02-02T18:07:26.133204Z","end":"2026-02-02T18:07:26.895215Z","steps":["trace[1944356339] 'process raft request' (duration: 761.542119ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:26.895254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:26.133198Z","time spent":"762.03819ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:26.90025Z","caller":"traceutil/trace.go:171","msg":"trace[1382121845] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:590; }","duration":"288.289909ms","start":"2026-02-02T18:07:26.61195Z","end":"2026-02-02T18:07:26.900239Z","steps":["trace[1382121845] 'read index received' (duration: 279.932472ms)","trace[1382121845] 'applied index is now lower than readState.Index' (duration: 8.356637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:26.900299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.3451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:26.900319Z","caller":"traceutil/trace.go:171","msg":"trace[682350522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:574; }","duration":"288.39241ms","start":"2026-02-02T18:07:26.611919Z","end":"2026-02-02T18:07:26.900312Z","steps":["trace[682350522] 'agreement among raft nodes before linearized reading' (duration: 288.35638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.419341Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233022009,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T18:07:33.483633Z","caller":"traceutil/trace.go:171","msg":"trace[2102491989] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"565.264514ms","start":"2026-02-02T18:07:32.918353Z","end":"2026-02-02T18:07:33.483617Z","steps":["trace[2102491989] 'read index received' (duration: 565.109401ms)","trace[2102491989] 'applied index is now lower than readState.Index' (duration: 154.273µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:33.483885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.873011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-02T18:07:33.483885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.52132ms","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-02-02T18:07:33.483931Z","caller":"traceutil/trace.go:171","msg":"trace[1984150029] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"565.604783ms","start":"2026-02-02T18:07:32.918314Z","end":"2026-02-02T18:07:33.483919Z","steps":["trace[1984150029] 'agreement among raft nodes before linearized reading' (duration: 565.47097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.483965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.918297Z","time spent":"565.660924ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T18:07:33.484034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.750451ms","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-02-02T18:07:33.48405Z","caller":"traceutil/trace.go:171","msg":"trace[1761738047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:607; }","duration":"546.823282ms","start":"2026-02-02T18:07:32.937223Z","end":"2026-02-02T18:07:33.484046Z","steps":["trace[1761738047] 'agreement among raft nodes before linearized reading' (duration: 546.751981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.484063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.937215Z","time spent":"546.844774ms","remote":"127.0.0.1:39082","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-02-02T18:07:33.484127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.048807ms","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-02-02T18:07:33.484137Z","caller":"traceutil/trace.go:171","msg":"trace[1045454767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"555.059297ms","start":"2026-02-02T18:07:32.929075Z","end":"2026-02-02T18:07:33.484134Z","steps":["trace[1045454767] 'agreement among raft nodes before linearized reading' (duration: 555.022327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.484148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.929054Z","time spent":"555.091738ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:07:33.484219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.73597ms","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-02-02T18:07:33.48424Z","caller":"traceutil/trace.go:171","msg":"trace[1704452472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"558.768191ms","start":"2026-02-02T18:07:32.925468Z","end":"2026-02-02T18:07:33.484236Z","steps":["trace[1704452472] 'agreement among raft nodes before linearized reading' (duration: 558.710488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.484253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.925462Z","time spent":"558.787911ms","remote":"127.0.0.1:39082","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-02-02T18:07:33.48428Z","caller":"traceutil/trace.go:171","msg":"trace[1252140407] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"849.122325ms","start":"2026-02-02T18:07:32.635143Z","end":"2026-02-02T18:07:33.484265Z","steps":["trace[1252140407] 'process raft request' (duration: 848.374588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.484365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.635128Z","time spent":"849.189487ms","remote":"127.0.0.1:38992","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18061,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:33.483905Z","caller":"traceutil/trace.go:171","msg":"trace[848664091] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"558.930303ms","start":"2026-02-02T18:07:32.924967Z","end":"2026-02-02T18:07:33.483897Z","steps":["trace[848664091] 'agreement among raft nodes before linearized reading' (duration: 558.837121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:33.484489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:32.924958Z","time spent":"559.523477ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-02T18:07:34.91079Z","caller":"traceutil/trace.go:171","msg":"trace[289220383] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"177.357531ms","start":"2026-02-02T18:07:34.733417Z","end":"2026-02-02T18:07:34.910774Z","steps":["trace[289220383] 'process raft request' (duration: 148.846295ms)","trace[289220383] 'compare' (duration: 28.384304ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:34.958547Z","caller":"traceutil/trace.go:171","msg":"trace[933341855] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"225.116588ms","start":"2026-02-02T18:07:34.733413Z","end":"2026-02-02T18:07:34.95853Z","steps":["trace[933341855] 'process raft request' (duration: 225.034826ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:07:41.111797Z","caller":"traceutil/trace.go:171","msg":"trace[1476462162] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"243.350605ms","start":"2026-02-02T18:07:40.868426Z","end":"2026-02-02T18:07:41.111777Z","steps":["trace[1476462162] 'process raft request' (duration: 243.201682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.178502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.773352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T18:07:42.178563Z","caller":"traceutil/trace.go:171","msg":"trace[866735686] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:660; }","duration":"730.877114ms","start":"2026-02-02T18:07:41.447669Z","end":"2026-02-02T18:07:42.178546Z","steps":["trace[866735686] 'range keys from in-memory index tree' (duration: 730.64526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.178592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.447656Z","time spent":"730.928616ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-02T18:07:42.178758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.446246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:07:42.178781Z","caller":"traceutil/trace.go:171","msg":"trace[1622091439] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"641.491428ms","start":"2026-02-02T18:07:41.537283Z","end":"2026-02-02T18:07:42.178775Z","steps":["trace[1622091439] 'range keys from in-memory index tree' (duration: 641.353485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.178797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.537245Z","time spent":"641.548559ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:07:42.178915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.289907ms","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-02-02T18:07:42.17894Z","caller":"traceutil/trace.go:171","msg":"trace[150143860] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:660; }","duration":"621.352158ms","start":"2026-02-02T18:07:41.55758Z","end":"2026-02-02T18:07:42.178932Z","steps":["trace[150143860] 'count revisions from in-memory index tree' (duration: 621.250006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.178958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.557571Z","time spent":"621.381499ms","remote":"127.0.0.1:39172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-02T18:07:42.179087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.344422ms","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-02-02T18:07:42.179125Z","caller":"traceutil/trace.go:171","msg":"trace[1372838963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"614.399682ms","start":"2026-02-02T18:07:41.56472Z","end":"2026-02-02T18:07:42.179119Z","steps":["trace[1372838963] 'range keys from in-memory index tree' (duration: 614.28964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.179146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.564713Z","time spent":"614.429103ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T18:07:42.179245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.973371ms","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-02-02T18:07:42.179266Z","caller":"traceutil/trace.go:171","msg":"trace[1382159360] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:660; }","duration":"609.005072ms","start":"2026-02-02T18:07:41.570256Z","end":"2026-02-02T18:07:42.179261Z","steps":["trace[1382159360] 'range keys from in-memory index tree' (duration: 608.90426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.179282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.57025Z","time spent":"609.028502ms","remote":"127.0.0.1:39082","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-02-02T18:07:42.179399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.533788ms","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-02-02T18:07:42.179421Z","caller":"traceutil/trace.go:171","msg":"trace[1329548777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:660; }","duration":"607.567479ms","start":"2026-02-02T18:07:41.571848Z","end":"2026-02-02T18:07:42.179416Z","steps":["trace[1329548777] 'range keys from in-memory index tree' (duration: 607.464347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.179442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.571841Z","time spent":"607.59651ms","remote":"127.0.0.1:39082","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-02-02T18:07:42.179544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.555799ms","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-02-02T18:07:42.179565Z","caller":"traceutil/trace.go:171","msg":"trace[755832234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:660; }","duration":"607.6082ms","start":"2026-02-02T18:07:41.571951Z","end":"2026-02-02T18:07:42.17956Z","steps":["trace[755832234] 'range keys from in-memory index tree' (duration: 607.513698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.179582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.571947Z","time spent":"607.630161ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T18:07:42.179658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.377681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:42.179678Z","caller":"traceutil/trace.go:171","msg":"trace[949772211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"588.422244ms","start":"2026-02-02T18:07:41.591251Z","end":"2026-02-02T18:07:42.179673Z","steps":["trace[949772211] 'range keys from in-memory index tree' (duration: 588.188336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.179694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.591195Z","time spent":"588.495415ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:07:42.190943Z","caller":"traceutil/trace.go:171","msg":"trace[907134088] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"439.246411ms","start":"2026-02-02T18:07:41.751685Z","end":"2026-02-02T18:07:42.190932Z","steps":["trace[907134088] 'process raft request' (duration: 439.158018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:42.191051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:41.75166Z","time spent":"439.331233ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:42.191346Z","caller":"traceutil/trace.go:171","msg":"trace[1887285163] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:686; }","duration":"218.802617ms","start":"2026-02-02T18:07:41.972506Z","end":"2026-02-02T18:07:42.191308Z","steps":["trace[1887285163] 'read index received' (duration: 218.800027ms)","trace[1887285163] 'applied index is now lower than readState.Index' (duration: 2.13µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:42.191448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.954321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T18:07:42.191475Z","caller":"traceutil/trace.go:171","msg":"trace[648861775] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:661; }","duration":"219.012022ms","start":"2026-02-02T18:07:41.972457Z","end":"2026-02-02T18:07:42.191469Z","steps":["trace[648861775] 'agreement among raft nodes before linearized reading' (duration: 218.91606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:50.746697Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16594528911233022239,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T18:07:51.080539Z","caller":"traceutil/trace.go:171","msg":"trace[579436540] linearizableReadLoop","detail":"{readStateIndex:758; appliedIndex:757; }","duration":"833.998886ms","start":"2026-02-02T18:07:50.246526Z","end":"2026-02-02T18:07:51.080525Z","steps":["trace[579436540] 'read index received' (duration: 833.826432ms)","trace[579436540] 'applied index is now lower than readState.Index' (duration: 171.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:51.080623Z","caller":"traceutil/trace.go:171","msg":"trace[414576581] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"853.623735ms","start":"2026-02-02T18:07:50.226994Z","end":"2026-02-02T18:07:51.080618Z","steps":["trace[414576581] 'process raft request' (duration: 853.420021ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.080699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.226986Z","time spent":"853.652285ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:51.080843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.322953ms","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-02-02T18:07:51.080859Z","caller":"traceutil/trace.go:171","msg":"trace[277483664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:730; }","duration":"834.384784ms","start":"2026-02-02T18:07:50.246469Z","end":"2026-02-02T18:07:51.080854Z","steps":["trace[277483664] 'agreement among raft nodes before linearized reading' (duration: 834.308293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.080872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.24646Z","time spent":"834.409546ms","remote":"127.0.0.1:39082","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-02-02T18:07:51.080952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.828547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T18:07:51.080978Z","caller":"traceutil/trace.go:171","msg":"trace[702224970] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:730; }","duration":"831.855519ms","start":"2026-02-02T18:07:50.249105Z","end":"2026-02-02T18:07:51.080961Z","steps":["trace[702224970] 'agreement among raft nodes before linearized reading' (duration: 831.818858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.080992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.249097Z","time spent":"831.889329ms","remote":"127.0.0.1:39082","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-02-02T18:07:51.081105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.836299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-02T18:07:51.081077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.235606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T18:07:51.08112Z","caller":"traceutil/trace.go:171","msg":"trace[1471295629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:730; }","duration":"810.90372ms","start":"2026-02-02T18:07:50.270212Z","end":"2026-02-02T18:07:51.081116Z","steps":["trace[1471295629] 'agreement among raft nodes before linearized reading' (duration: 810.864199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.081147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.270195Z","time spent":"810.949081ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-02T18:07:51.081149Z","caller":"traceutil/trace.go:171","msg":"trace[1956668611] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:730; }","duration":"639.335968ms","start":"2026-02-02T18:07:50.441794Z","end":"2026-02-02T18:07:51.08113Z","steps":["trace[1956668611] 'agreement among raft nodes before linearized reading' (duration: 639.240326ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.081182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.441778Z","time spent":"639.394909ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-02-02T18:07:51.081237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.179499ms","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-02-02T18:07:51.08125Z","caller":"traceutil/trace.go:171","msg":"trace[881639284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:730; }","duration":"829.207099ms","start":"2026-02-02T18:07:50.252039Z","end":"2026-02-02T18:07:51.081246Z","steps":["trace[881639284] 'agreement among raft nodes before linearized reading' (duration: 829.154028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.08126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.252032Z","time spent":"829.225919ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T18:07:51.08137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.952064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:51.081397Z","caller":"traceutil/trace.go:171","msg":"trace[1252364588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:730; }","duration":"491.004125ms","start":"2026-02-02T18:07:50.590384Z","end":"2026-02-02T18:07:51.081388Z","steps":["trace[1252364588] 'agreement among raft nodes before linearized reading' (duration: 490.964824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:51.081423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:50.590372Z","time spent":"491.045617ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:07:52.158648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"898.17578ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:07:52.158883Z","caller":"traceutil/trace.go:171","msg":"trace[1009117317] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:758; }","duration":"710.431987ms","start":"2026-02-02T18:07:51.448438Z","end":"2026-02-02T18:07:52.15887Z","steps":["trace[1009117317] 'read index received' (duration: 32.811µs)","trace[1009117317] 'applied index is now lower than readState.Index' (duration: 710.398506ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:52.159076Z","caller":"traceutil/trace.go:171","msg":"trace[1416477084] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"1.073709538s","start":"2026-02-02T18:07:51.085353Z","end":"2026-02-02T18:07:52.159063Z","steps":["trace[1416477084] 'process raft request' (duration: 175.057878ms)","trace[1416477084] 'compare' (duration: 898.028187ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:07:52.159127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.085345Z","time spent":"1.073754359s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:52.159278Z","caller":"traceutil/trace.go:171","msg":"trace[1166009863] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"1.072733927s","start":"2026-02-02T18:07:51.086535Z","end":"2026-02-02T18:07:52.159269Z","steps":["trace[1166009863] 'process raft request' (duration: 1.072238846s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:52.159317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.086507Z","time spent":"1.072787208s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:52.159409Z","caller":"traceutil/trace.go:171","msg":"trace[133435205] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"1.071116701s","start":"2026-02-02T18:07:51.088284Z","end":"2026-02-02T18:07:52.159401Z","steps":["trace[133435205] 'process raft request' (duration: 1.070536938s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:52.159448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.088278Z","time spent":"1.071147851s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:07:52.159548Z","caller":"traceutil/trace.go:171","msg":"trace[1817492787] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"1.069948095s","start":"2026-02-02T18:07:51.089594Z","end":"2026-02-02T18:07:52.159542Z","steps":["trace[1817492787] 'process raft request' (duration: 1.06925419s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:52.159578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.089589Z","time spent":"1.069973095s","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:52.159709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"711.273495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.52\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T18:07:52.159731Z","caller":"traceutil/trace.go:171","msg":"trace[1047228269] range","detail":"{range_begin:/registry/masterleases/162.253.55.52; range_end:; response_count:1; response_revision:734; }","duration":"711.326006ms","start":"2026-02-02T18:07:51.448397Z","end":"2026-02-02T18:07:52.159723Z","steps":["trace[1047228269] 'agreement among raft nodes before linearized reading' (duration: 711.249024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:52.159751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.448382Z","time spent":"711.363307ms","remote":"127.0.0.1:38840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.52\" "} {"level":"warn","ts":"2026-02-02T18:07:52.159856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.549555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:52.159874Z","caller":"traceutil/trace.go:171","msg":"trace[722745052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"567.599487ms","start":"2026-02-02T18:07:51.592268Z","end":"2026-02-02T18:07:52.159868Z","steps":["trace[722745052] 'agreement among raft nodes before linearized reading' (duration: 567.574516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:52.159888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:51.59225Z","time spent":"567.634827ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:07:53.976935Z","caller":"traceutil/trace.go:171","msg":"trace[288679777] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"375.119928ms","start":"2026-02-02T18:07:53.601795Z","end":"2026-02-02T18:07:53.976915Z","steps":["trace[288679777] 'read index received' (duration: 374.982425ms)","trace[288679777] 'applied index is now lower than readState.Index' (duration: 136.903µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:07:53.97702Z","caller":"traceutil/trace.go:171","msg":"trace[803741628] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"889.971955ms","start":"2026-02-02T18:07:53.087042Z","end":"2026-02-02T18:07:53.977014Z","steps":["trace[803741628] 'process raft request' (duration: 889.77111ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:53.977092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:53.087031Z","time spent":"890.007446ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:07:53.977186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.389293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:53.977204Z","caller":"traceutil/trace.go:171","msg":"trace[31700284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"375.406374ms","start":"2026-02-02T18:07:53.601792Z","end":"2026-02-02T18:07:53.977198Z","steps":["trace[31700284] 'agreement among raft nodes before linearized reading' (duration: 375.376813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:53.977221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:07:53.601755Z","time spent":"375.462925ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T18:07:53.977364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.261955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:07:53.977415Z","caller":"traceutil/trace.go:171","msg":"trace[1479701255] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:737; }","duration":"261.350387ms","start":"2026-02-02T18:07:53.716051Z","end":"2026-02-02T18:07:53.977402Z","steps":["trace[1479701255] 'agreement among raft nodes before linearized reading' (duration: 261.269475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:07:53.977434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.358957ms","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-02-02T18:07:53.977458Z","caller":"traceutil/trace.go:171","msg":"trace[1640009699] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:737; }","duration":"124.412448ms","start":"2026-02-02T18:07:53.853037Z","end":"2026-02-02T18:07:53.97745Z","steps":["trace[1640009699] 'agreement among raft nodes before linearized reading' (duration: 124.371887ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:04.256648Z","caller":"traceutil/trace.go:171","msg":"trace[2029042021] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"241.915292ms","start":"2026-02-02T18:08:04.014699Z","end":"2026-02-02T18:08:04.256614Z","steps":["trace[2029042021] 'process raft request' (duration: 241.737158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.056376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.391781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:08:05.05802Z","caller":"traceutil/trace.go:171","msg":"trace[302191547] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:801; }","duration":"465.349493ms","start":"2026-02-02T18:08:04.592611Z","end":"2026-02-02T18:08:05.057961Z","steps":["trace[302191547] 'read index received' (duration: 55.263245ms)","trace[302191547] 'applied index is now lower than readState.Index' (duration: 410.085178ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:05.058462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.835443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:05.058681Z","caller":"traceutil/trace.go:171","msg":"trace[99765612] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:774; }","duration":"466.10329ms","start":"2026-02-02T18:08:04.59256Z","end":"2026-02-02T18:08:05.058664Z","steps":["trace[99765612] 'agreement among raft nodes before linearized reading' (duration: 465.778972ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.058873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:04.59251Z","time spent":"466.307674ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:08:05.059883Z","caller":"traceutil/trace.go:171","msg":"trace[191405790] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"790.013964ms","start":"2026-02-02T18:08:04.269851Z","end":"2026-02-02T18:08:05.059865Z","steps":["trace[191405790] 'process raft request' (duration: 378.013404ms)","trace[191405790] 'compare' (duration: 407.94188ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:05.060277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:04.269838Z","time spent":"790.197248ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:05.061876Z","caller":"traceutil/trace.go:171","msg":"trace[1211677745] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"791.718462ms","start":"2026-02-02T18:08:04.270142Z","end":"2026-02-02T18:08:05.061861Z","steps":["trace[1211677745] 'process raft request' (duration: 787.123669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.062244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:04.270124Z","time spent":"792.06674ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:05.062716Z","caller":"traceutil/trace.go:171","msg":"trace[1061437292] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"791.17021ms","start":"2026-02-02T18:08:04.271532Z","end":"2026-02-02T18:08:05.062702Z","steps":["trace[1061437292] 'process raft request' (duration: 786.079756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.063065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:04.271526Z","time spent":"791.495827ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:05.063498Z","caller":"traceutil/trace.go:171","msg":"trace[1427272932] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"790.521245ms","start":"2026-02-02T18:08:04.27296Z","end":"2026-02-02T18:08:05.063481Z","steps":["trace[1427272932] 'process raft request' (duration: 784.745046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:05.063693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:04.272952Z","time spent":"790.656537ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:17.080522Z","caller":"traceutil/trace.go:171","msg":"trace[296398671] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"301.077373ms","start":"2026-02-02T18:08:16.779405Z","end":"2026-02-02T18:08:17.080482Z","steps":["trace[296398671] 'process raft request' (duration: 300.628343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:17.080692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:16.779389Z","time spent":"301.213607ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:17.081368Z","caller":"traceutil/trace.go:171","msg":"trace[317195580] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"204.258176ms","start":"2026-02-02T18:08:16.877095Z","end":"2026-02-02T18:08:17.081353Z","steps":["trace[317195580] 'read index received' (duration: 202.896387ms)","trace[317195580] 'applied index is now lower than readState.Index' (duration: 1.361149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:17.081465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.351408ms","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":"info","ts":"2026-02-02T18:08:17.081482Z","caller":"traceutil/trace.go:171","msg":"trace[675683904] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:855; }","duration":"204.478331ms","start":"2026-02-02T18:08:16.876998Z","end":"2026-02-02T18:08:17.081476Z","steps":["trace[675683904] 'agreement among raft nodes before linearized reading' (duration: 204.410869ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:18.942205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.985763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:18.942249Z","caller":"traceutil/trace.go:171","msg":"trace[13218114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"353.063404ms","start":"2026-02-02T18:08:18.589176Z","end":"2026-02-02T18:08:18.942239Z","steps":["trace[13218114] 'range keys from in-memory index tree' (duration: 352.946022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:18.942272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:18.589164Z","time spent":"353.103595ms","remote":"127.0.0.1:38794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:08:23.479225Z","caller":"traceutil/trace.go:171","msg":"trace[1811717068] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:908; }","duration":"320.616408ms","start":"2026-02-02T18:08:23.158587Z","end":"2026-02-02T18:08:23.479203Z","steps":["trace[1811717068] 'read index received' (duration: 320.387323ms)","trace[1811717068] 'applied index is now lower than readState.Index' (duration: 228.105µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:08:23.479499Z","caller":"traceutil/trace.go:171","msg":"trace[962629755] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"321.61554ms","start":"2026-02-02T18:08:23.15787Z","end":"2026-02-02T18:08:23.479486Z","steps":["trace[962629755] 'process raft request' (duration: 321.15405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:23.479596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:23.157859Z","time spent":"321.669111ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:08:23.479824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.232691ms","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-02-02T18:08:23.47986Z","caller":"traceutil/trace.go:171","msg":"trace[942113529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:873; }","duration":"321.288782ms","start":"2026-02-02T18:08:23.158561Z","end":"2026-02-02T18:08:23.479849Z","steps":["trace[942113529] 'agreement among raft nodes before linearized reading' (duration: 321.178909ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:23.479891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:23.15855Z","time spent":"321.333983ms","remote":"127.0.0.1:39082","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-02-02T18:08:23.48009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.90165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-02T18:08:23.480122Z","caller":"traceutil/trace.go:171","msg":"trace[463436169] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:873; }","duration":"312.94455ms","start":"2026-02-02T18:08:23.167167Z","end":"2026-02-02T18:08:23.480112Z","steps":["trace[463436169] 'agreement among raft nodes before linearized reading' (duration: 312.857428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:23.480152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:23.16716Z","time spent":"312.984951ms","remote":"127.0.0.1:39082","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-02-02T18:08:23.480575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.179127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T18:08:23.480609Z","caller":"traceutil/trace.go:171","msg":"trace[727435644] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:873; }","duration":"302.236728ms","start":"2026-02-02T18:08:23.178363Z","end":"2026-02-02T18:08:23.4806Z","steps":["trace[727435644] 'agreement among raft nodes before linearized reading' (duration: 302.151746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:23.480638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:23.178352Z","time spent":"302.278869ms","remote":"127.0.0.1:39082","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-02-02T18:08:31.798868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.713573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-02T18:08:31.798944Z","caller":"traceutil/trace.go:171","msg":"trace[491033464] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:897; }","duration":"163.825155ms","start":"2026-02-02T18:08:31.635102Z","end":"2026-02-02T18:08:31.798927Z","steps":["trace[491033464] 'range keys from in-memory index tree' (duration: 163.680172ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:31.79921Z","caller":"traceutil/trace.go:171","msg":"trace[1515333250] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"155.691068ms","start":"2026-02-02T18:08:31.643506Z","end":"2026-02-02T18:08:31.799197Z","steps":["trace[1515333250] 'process raft request' (duration: 152.515319ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:32.876566Z","caller":"traceutil/trace.go:171","msg":"trace[827042908] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"472.340112ms","start":"2026-02-02T18:08:32.404204Z","end":"2026-02-02T18:08:32.876544Z","steps":["trace[827042908] 'process raft request' (duration: 472.219169ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:32.876721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:32.404195Z","time spent":"472.441724ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:08:33.242879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.485078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:08:33.242947Z","caller":"traceutil/trace.go:171","msg":"trace[1533215165] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"273.941762ms","start":"2026-02-02T18:08:32.968993Z","end":"2026-02-02T18:08:33.242935Z","steps":["trace[1533215165] 'process raft request' (duration: 122.346273ms)","trace[1533215165] 'compare' (duration: 151.393775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:33.868777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.430968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:08:33.868838Z","caller":"traceutil/trace.go:171","msg":"trace[875867581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"231.50541ms","start":"2026-02-02T18:08:33.637316Z","end":"2026-02-02T18:08:33.868822Z","steps":["trace[875867581] 'range keys from in-memory index tree' (duration: 231.306716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:33.868912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.425559ms","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-02-02T18:08:33.868955Z","caller":"traceutil/trace.go:171","msg":"trace[927825649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:900; }","duration":"226.48503ms","start":"2026-02-02T18:08:33.642459Z","end":"2026-02-02T18:08:33.868944Z","steps":["trace[927825649] 'range keys from in-memory index tree' (duration: 226.367138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:33.869017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.265933ms","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-02-02T18:08:33.869028Z","caller":"traceutil/trace.go:171","msg":"trace[883513043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:900; }","duration":"225.311644ms","start":"2026-02-02T18:08:33.643712Z","end":"2026-02-02T18:08:33.869024Z","steps":["trace[883513043] 'range keys from in-memory index tree' (duration: 225.192652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:33.869097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.356115ms","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-02-02T18:08:33.869137Z","caller":"traceutil/trace.go:171","msg":"trace[80675956] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:900; }","duration":"446.398966ms","start":"2026-02-02T18:08:33.422733Z","end":"2026-02-02T18:08:33.869132Z","steps":["trace[80675956] 'range keys from in-memory index tree' (duration: 446.350385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:33.869284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.949894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:33.8693Z","caller":"traceutil/trace.go:171","msg":"trace[2117938196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"279.992864ms","start":"2026-02-02T18:08:33.589303Z","end":"2026-02-02T18:08:33.869296Z","steps":["trace[2117938196] 'range keys from in-memory index tree' (duration: 279.894383ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:43.321907Z","caller":"traceutil/trace.go:171","msg":"trace[2063456568] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"391.411699ms","start":"2026-02-02T18:08:42.930467Z","end":"2026-02-02T18:08:43.321879Z","steps":["trace[2063456568] 'process raft request' (duration: 391.295366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:43.322218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:42.930455Z","time spent":"391.526051ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T18:08:43.7206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.859929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:43.720654Z","caller":"traceutil/trace.go:171","msg":"trace[633535912] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:948; }","duration":"317.960871ms","start":"2026-02-02T18:08:43.402681Z","end":"2026-02-02T18:08:43.720642Z","steps":["trace[633535912] 'count revisions from in-memory index tree' (duration: 317.789678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:43.720697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:43.40266Z","time spent":"318.030942ms","remote":"127.0.0.1:39226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-02T18:08:43.720883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.388892ms","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-02-02T18:08:43.720918Z","caller":"traceutil/trace.go:171","msg":"trace[2079141215] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:948; }","duration":"297.423313ms","start":"2026-02-02T18:08:43.42349Z","end":"2026-02-02T18:08:43.720913Z","steps":["trace[2079141215] 'range keys from in-memory index tree' (duration: 297.383632ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:43.721154Z","caller":"traceutil/trace.go:171","msg":"trace[765280250] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"180.417607ms","start":"2026-02-02T18:08:43.54073Z","end":"2026-02-02T18:08:43.721147Z","steps":["trace[765280250] 'process raft request' (duration: 127.766981ms)","trace[765280250] 'compare' (duration: 52.025123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:44.347515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.697279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:08:44.347726Z","caller":"traceutil/trace.go:171","msg":"trace[1679602649] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:992; }","duration":"212.27419ms","start":"2026-02-02T18:08:44.135436Z","end":"2026-02-02T18:08:44.34771Z","steps":["trace[1679602649] 'read index received' (duration: 202.671061ms)","trace[1679602649] 'applied index is now lower than readState.Index' (duration: 9.602299ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:08:44.347736Z","caller":"traceutil/trace.go:171","msg":"trace[1583739686] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"271.882839ms","start":"2026-02-02T18:08:44.075828Z","end":"2026-02-02T18:08:44.347711Z","steps":["trace[1583739686] 'process raft request' (duration: 47.928504ms)","trace[1583739686] 'compare' (duration: 223.539056ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:44.347807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.363263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:44.347836Z","caller":"traceutil/trace.go:171","msg":"trace[1492250699] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:954; }","duration":"212.423294ms","start":"2026-02-02T18:08:44.135404Z","end":"2026-02-02T18:08:44.347827Z","steps":["trace[1492250699] 'agreement among raft nodes before linearized reading' (duration: 212.351822ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:44.347985Z","caller":"traceutil/trace.go:171","msg":"trace[774915591] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"270.175721ms","start":"2026-02-02T18:08:44.077801Z","end":"2026-02-02T18:08:44.347977Z","steps":["trace[774915591] 'process raft request' (duration: 269.818683ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:44.348099Z","caller":"traceutil/trace.go:171","msg":"trace[1033880444] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"228.208468ms","start":"2026-02-02T18:08:44.119883Z","end":"2026-02-02T18:08:44.348092Z","steps":["trace[1033880444] 'process raft request' (duration: 227.794428ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:44.602081Z","caller":"traceutil/trace.go:171","msg":"trace[525798606] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"249.802477ms","start":"2026-02-02T18:08:44.352263Z","end":"2026-02-02T18:08:44.602065Z","steps":["trace[525798606] 'process raft request' (duration: 183.003133ms)","trace[525798606] 'compare' (duration: 66.711022ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:08:44.691191Z","caller":"traceutil/trace.go:171","msg":"trace[2140661341] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"335.968052ms","start":"2026-02-02T18:08:44.355204Z","end":"2026-02-02T18:08:44.691172Z","steps":["trace[2140661341] 'process raft request' (duration: 335.86255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:44.691303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:44.355186Z","time spent":"336.056055ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:08:44.691571Z","caller":"traceutil/trace.go:171","msg":"trace[1253151112] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:997; }","duration":"100.264053ms","start":"2026-02-02T18:08:44.5913Z","end":"2026-02-02T18:08:44.691564Z","steps":["trace[1253151112] 'read index received' (duration: 100.260933ms)","trace[1253151112] 'applied index is now lower than readState.Index' (duration: 2.65µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:44.691645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.341234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:44.691664Z","caller":"traceutil/trace.go:171","msg":"trace[915380643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"100.402736ms","start":"2026-02-02T18:08:44.591256Z","end":"2026-02-02T18:08:44.691658Z","steps":["trace[915380643] 'agreement among raft nodes before linearized reading' (duration: 100.347875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:45.004564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.014112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:08:45.004709Z","caller":"traceutil/trace.go:171","msg":"trace[2023663840] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"310.56183ms","start":"2026-02-02T18:08:44.694132Z","end":"2026-02-02T18:08:45.004694Z","steps":["trace[2023663840] 'process raft request' (duration: 310.517399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:45.004776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:44.694122Z","time spent":"310.618261ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:08:45.004929Z","caller":"traceutil/trace.go:171","msg":"trace[485747321] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"392.640207ms","start":"2026-02-02T18:08:44.612278Z","end":"2026-02-02T18:08:45.004918Z","steps":["trace[485747321] 'process raft request' (duration: 272.257037ms)","trace[485747321] 'compare' (duration: 119.798777ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:45.004972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:44.612267Z","time spent":"392.683867ms","remote":"127.0.0.1:38892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T18:08:45.005071Z","caller":"traceutil/trace.go:171","msg":"trace[1611366897] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:997; }","duration":"313.471844ms","start":"2026-02-02T18:08:44.691587Z","end":"2026-02-02T18:08:45.005059Z","steps":["trace[1611366897] 'read index received' (duration: 193.153685ms)","trace[1611366897] 'applied index is now lower than readState.Index' (duration: 120.317249ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:45.005368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.993779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-02T18:08:45.005404Z","caller":"traceutil/trace.go:171","msg":"trace[2103704285] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:958; }","duration":"373.04728ms","start":"2026-02-02T18:08:44.632346Z","end":"2026-02-02T18:08:45.005394Z","steps":["trace[2103704285] 'agreement among raft nodes before linearized reading' (duration: 372.870036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:45.005436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:44.632341Z","time spent":"373.088001ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-02T18:08:45.005583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.754037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:45.00561Z","caller":"traceutil/trace.go:171","msg":"trace[90489859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"312.799708ms","start":"2026-02-02T18:08:44.692802Z","end":"2026-02-02T18:08:45.005602Z","steps":["trace[90489859] 'agreement among raft nodes before linearized reading' (duration: 312.757287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:45.005634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:44.692792Z","time spent":"312.836839ms","remote":"127.0.0.1:38798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T18:08:50.170898Z","caller":"traceutil/trace.go:171","msg":"trace[174644261] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"117.536879ms","start":"2026-02-02T18:08:50.053157Z","end":"2026-02-02T18:08:50.170694Z","steps":["trace[174644261] 'process raft request' (duration: 116.078588ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:53.712748Z","caller":"traceutil/trace.go:171","msg":"trace[2002466362] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"345.574853ms","start":"2026-02-02T18:08:53.367156Z","end":"2026-02-02T18:08:53.712731Z","steps":["trace[2002466362] 'process raft request' (duration: 345.47193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:53.712851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T18:08:53.367141Z","time spent":"345.658295ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T18:08:54.261064Z","caller":"traceutil/trace.go:171","msg":"trace[553318803] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"158.262084ms","start":"2026-02-02T18:08:54.102786Z","end":"2026-02-02T18:08:54.261048Z","steps":["trace[553318803] 'read index received' (duration: 158.115621ms)","trace[553318803] 'applied index is now lower than readState.Index' (duration: 145.673µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:54.261234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.429257ms","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-02-02T18:08:54.26127Z","caller":"traceutil/trace.go:171","msg":"trace[1492661210] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1000; }","duration":"158.504199ms","start":"2026-02-02T18:08:54.102752Z","end":"2026-02-02T18:08:54.261256Z","steps":["trace[1492661210] 'agreement among raft nodes before linearized reading' (duration: 158.363656ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:54.261315Z","caller":"traceutil/trace.go:171","msg":"trace[251839583] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"259.819844ms","start":"2026-02-02T18:08:54.001461Z","end":"2026-02-02T18:08:54.261281Z","steps":["trace[251839583] 'process raft request' (duration: 259.463736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:54.564879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.24215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T18:08:54.564975Z","caller":"traceutil/trace.go:171","msg":"trace[265194237] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"298.023607ms","start":"2026-02-02T18:08:54.266936Z","end":"2026-02-02T18:08:54.564959Z","steps":["trace[265194237] 'read index received' (duration: 176.579993ms)","trace[265194237] 'applied index is now lower than readState.Index' (duration: 121.442384ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:08:54.565024Z","caller":"traceutil/trace.go:171","msg":"trace[1889882826] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"299.925728ms","start":"2026-02-02T18:08:54.265085Z","end":"2026-02-02T18:08:54.565011Z","steps":["trace[1889882826] 'process raft request' (duration: 178.477394ms)","trace[1889882826] 'compare' (duration: 121.152658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:54.565194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.209881ms","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-02-02T18:08:54.565227Z","caller":"traceutil/trace.go:171","msg":"trace[1887707257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"298.284693ms","start":"2026-02-02T18:08:54.266931Z","end":"2026-02-02T18:08:54.565216Z","steps":["trace[1887707257] 'agreement among raft nodes before linearized reading' (duration: 298.109689ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:54.565268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.441339ms","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-02-02T18:08:54.565292Z","caller":"traceutil/trace.go:171","msg":"trace[263391562] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"158.48967ms","start":"2026-02-02T18:08:54.406794Z","end":"2026-02-02T18:08:54.565283Z","steps":["trace[263391562] 'agreement among raft nodes before linearized reading' (duration: 158.425229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:08:54.56537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.759034ms","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-02-02T18:08:54.565387Z","caller":"traceutil/trace.go:171","msg":"trace[1527901580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"157.801544ms","start":"2026-02-02T18:08:54.407581Z","end":"2026-02-02T18:08:54.565382Z","steps":["trace[1527901580] 'agreement among raft nodes before linearized reading' (duration: 157.752253ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:54.80887Z","caller":"traceutil/trace.go:171","msg":"trace[1137541085] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"239.033514ms","start":"2026-02-02T18:08:54.569821Z","end":"2026-02-02T18:08:54.808855Z","steps":["trace[1137541085] 'process raft request' (duration: 238.929151ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:54.81259Z","caller":"traceutil/trace.go:171","msg":"trace[485579093] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1045; }","duration":"220.524441ms","start":"2026-02-02T18:08:54.592055Z","end":"2026-02-02T18:08:54.812579Z","steps":["trace[485579093] 'read index received' (duration: 216.848471ms)","trace[485579093] 'applied index is now lower than readState.Index' (duration: 3.67555ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:08:54.812669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.606822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:08:54.812689Z","caller":"traceutil/trace.go:171","msg":"trace[733660130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1004; }","duration":"220.666703ms","start":"2026-02-02T18:08:54.592017Z","end":"2026-02-02T18:08:54.812683Z","steps":["trace[733660130] 'agreement among raft nodes before linearized reading' (duration: 220.601601ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:08:54.812797Z","caller":"traceutil/trace.go:171","msg":"trace[1921261459] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"241.735471ms","start":"2026-02-02T18:08:54.571055Z","end":"2026-02-02T18:08:54.81279Z","steps":["trace[1921261459] 'process raft request' (duration: 241.456125ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:09.424906Z","caller":"traceutil/trace.go:171","msg":"trace[2086328849] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1381; }","duration":"118.767439ms","start":"2026-02-02T18:10:09.306119Z","end":"2026-02-02T18:10:09.424886Z","steps":["trace[2086328849] 'read index received' (duration: 21.760337ms)","trace[2086328849] 'applied index is now lower than readState.Index' (duration: 97.006352ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:10:09.425029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.891423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:10:09.425074Z","caller":"traceutil/trace.go:171","msg":"trace[1206845188] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1326; }","duration":"118.975934ms","start":"2026-02-02T18:10:09.30609Z","end":"2026-02-02T18:10:09.425066Z","steps":["trace[1206845188] 'agreement among raft nodes before linearized reading' (duration: 118.875112ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:09.425304Z","caller":"traceutil/trace.go:171","msg":"trace[1283923633] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"210.906813ms","start":"2026-02-02T18:10:09.214388Z","end":"2026-02-02T18:10:09.425295Z","steps":["trace[1283923633] 'process raft request' (duration: 210.423272ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:09.425455Z","caller":"traceutil/trace.go:171","msg":"trace[705754995] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"216.677055ms","start":"2026-02-02T18:10:09.208769Z","end":"2026-02-02T18:10:09.425446Z","steps":["trace[705754995] 'process raft request' (duration: 119.137269ms)","trace[705754995] 'compare' (duration: 96.797558ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:10:09.425347Z","caller":"traceutil/trace.go:171","msg":"trace[700278218] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"126.855545ms","start":"2026-02-02T18:10:09.298482Z","end":"2026-02-02T18:10:09.425337Z","steps":["trace[700278218] 'process raft request' (duration: 126.374364ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:10.549165Z","caller":"traceutil/trace.go:171","msg":"trace[400439383] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"187.379756ms","start":"2026-02-02T18:10:10.361761Z","end":"2026-02-02T18:10:10.549141Z","steps":["trace[400439383] 'process raft request' (duration: 186.919604ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:11.032864Z","caller":"traceutil/trace.go:171","msg":"trace[1777197069] linearizableReadLoop","detail":"{readStateIndex:1388; appliedIndex:1387; }","duration":"120.271655ms","start":"2026-02-02T18:10:10.912573Z","end":"2026-02-02T18:10:11.032845Z","steps":["trace[1777197069] 'read index received' (duration: 120.120411ms)","trace[1777197069] 'applied index is now lower than readState.Index' (duration: 150.574µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:10:11.033255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.660973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-02T18:10:11.033894Z","caller":"traceutil/trace.go:171","msg":"trace[245445083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1330; }","duration":"121.331839ms","start":"2026-02-02T18:10:10.912547Z","end":"2026-02-02T18:10:11.033879Z","steps":["trace[245445083] 'agreement among raft nodes before linearized reading' (duration: 120.608442ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:11.033579Z","caller":"traceutil/trace.go:171","msg":"trace[1044207376] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"143.150247ms","start":"2026-02-02T18:10:10.890403Z","end":"2026-02-02T18:10:11.033553Z","steps":["trace[1044207376] 'process raft request' (duration: 142.332967ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:23.183072Z","caller":"traceutil/trace.go:171","msg":"trace[1377477928] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"115.417561ms","start":"2026-02-02T18:10:23.067634Z","end":"2026-02-02T18:10:23.183051Z","steps":["trace[1377477928] 'process raft request' (duration: 115.139534ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:23.316911Z","caller":"traceutil/trace.go:171","msg":"trace[2011555485] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"129.451186ms","start":"2026-02-02T18:10:23.187435Z","end":"2026-02-02T18:10:23.316886Z","steps":["trace[2011555485] 'process raft request' (duration: 129.330593ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:10:25.70015Z","caller":"traceutil/trace.go:171","msg":"trace[845254893] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"156.322139ms","start":"2026-02-02T18:10:25.54382Z","end":"2026-02-02T18:10:25.700143Z","steps":["trace[845254893] 'process raft request' (duration: 138.495189ms)","trace[845254893] 'compare' (duration: 17.422101ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:10:25.700061Z","caller":"traceutil/trace.go:171","msg":"trace[767476132] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"130.423808ms","start":"2026-02-02T18:10:25.56962Z","end":"2026-02-02T18:10:25.700044Z","steps":["trace[767476132] 'read index received' (duration: 112.617058ms)","trace[767476132] 'applied index is now lower than readState.Index' (duration: 17.80623ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:10:25.700441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.228781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:10:25.700457Z","caller":"traceutil/trace.go:171","msg":"trace[1937957483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1389; }","duration":"109.282713ms","start":"2026-02-02T18:10:25.591169Z","end":"2026-02-02T18:10:25.700452Z","steps":["trace[1937957483] 'agreement among raft nodes before linearized reading' (duration: 109.245962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:10:25.700766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.150353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-02T18:10:25.700792Z","caller":"traceutil/trace.go:171","msg":"trace[1279001424] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1389; }","duration":"131.203355ms","start":"2026-02-02T18:10:25.569582Z","end":"2026-02-02T18:10:25.700785Z","steps":["trace[1279001424] 'agreement among raft nodes before linearized reading' (duration: 130.810876ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:12:02.722262Z","caller":"traceutil/trace.go:171","msg":"trace[598844258] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"138.383803ms","start":"2026-02-02T18:12:02.583853Z","end":"2026-02-02T18:12:02.722237Z","steps":["trace[598844258] 'process raft request' (duration: 138.23622ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:12:10.049828Z","caller":"traceutil/trace.go:171","msg":"trace[37051820] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"207.051447ms","start":"2026-02-02T18:12:09.84276Z","end":"2026-02-02T18:12:10.049811Z","steps":["trace[37051820] 'process raft request' (duration: 206.75903ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T18:12:51.707147Z","caller":"traceutil/trace.go:171","msg":"trace[155095575] linearizableReadLoop","detail":"{readStateIndex:2117; appliedIndex:2116; }","duration":"165.212647ms","start":"2026-02-02T18:12:51.541857Z","end":"2026-02-02T18:12:51.70707Z","steps":["trace[155095575] 'read index received' (duration: 134.27433ms)","trace[155095575] 'applied index is now lower than readState.Index' (duration: 30.936697ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T18:12:51.707271Z","caller":"traceutil/trace.go:171","msg":"trace[502796555] transaction","detail":"{read_only:false; response_revision:2025; number_of_response:1; }","duration":"165.468603ms","start":"2026-02-02T18:12:51.541787Z","end":"2026-02-02T18:12:51.707256Z","steps":["trace[502796555] 'process raft request' (duration: 134.441624ms)","trace[502796555] 'compare' (duration: 30.589959ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T18:12:51.707617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.742459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:6133"} {"level":"info","ts":"2026-02-02T18:12:51.707649Z","caller":"traceutil/trace.go:171","msg":"trace[2109560153] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:2025; }","duration":"165.80819ms","start":"2026-02-02T18:12:51.541833Z","end":"2026-02-02T18:12:51.707641Z","steps":["trace[2109560153] 'agreement among raft nodes before linearized reading' (duration: 165.624556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T18:12:51.707827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.966616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T18:12:51.70785Z","caller":"traceutil/trace.go:171","msg":"trace[109781830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2025; }","duration":"117.022568ms","start":"2026-02-02T18:12:51.590823Z","end":"2026-02-02T18:12:51.707846Z","steps":["trace[109781830] 'agreement among raft nodes before linearized reading' (duration: 116.982157ms)"],"step_count":1}