{"level":"warn","ts":"2026-01-23T00:49:09.568487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-23T00:49:09.571836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-01-23T00:49:09.571996Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-23T00:49:09.572075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]}
{"level":"info","ts":"2026-01-23T00:49:09.572196Z","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-01-23T00:49:09.572845Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]}
{"level":"info","ts":"2026-01-23T00:49:09.573524Z","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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-01-23T00:49:09.579935Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.643895ms"}
{"level":"info","ts":"2026-01-23T00:49:09.626066Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"}
{"level":"info","ts":"2026-01-23T00:49:09.626124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"}
{"level":"info","ts":"2026-01-23T00:49:09.62622Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"}
{"level":"info","ts":"2026-01-23T00:49:09.626229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-01-23T00:49:09.626289Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"}
{"level":"info","ts":"2026-01-23T00:49:09.626362Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"}
{"level":"warn","ts":"2026-01-23T00:49:09.637755Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-01-23T00:49:09.644009Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-01-23T00:49:09.648294Z","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-01-23T00:49:09.652347Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-01-23T00:49:09.654623Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-01-23T00:49:09.654826Z","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-01-23T00:49:09.654892Z","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-01-23T00:49:09.654952Z","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-01-23T00:49:09.655169Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"}
{"level":"info","ts":"2026-01-23T00:49:09.664459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]}
{"level":"info","ts":"2026-01-23T00:49:09.665212Z","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-01-23T00:49:09.665332Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-01-23T00:49:09.665351Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-01-23T00:49:09.665431Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"}
{"level":"info","ts":"2026-01-23T00:49:09.665443Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"}
{"level":"info","ts":"2026-01-23T00:49:10.52796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"}
{"level":"info","ts":"2026-01-23T00:49:10.528051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"}
{"level":"info","ts":"2026-01-23T00:49:10.528082Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"}
{"level":"info","ts":"2026-01-23T00:49:10.528094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"}
{"level":"info","ts":"2026-01-23T00:49:10.528099Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"}
{"level":"info","ts":"2026-01-23T00:49:10.528107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"}
{"level":"info","ts":"2026-01-23T00:49:10.528114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"}
{"level":"info","ts":"2026-01-23T00:49:10.530196Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"}
{"level":"info","ts":"2026-01-23T00:49:10.530469Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-23T00:49:10.530861Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T00:49:10.530984Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-23T00:49:10.531291Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-01-23T00:49:10.531329Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-01-23T00:49:10.53309Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-01-23T00:49:10.534173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"}
{"level":"info","ts":"2026-01-23T00:49:10.535187Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T00:49:10.536895Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T00:49:10.537022Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"warn","ts":"2026-01-23T00:49:14.567262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.587919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:14.567353Z","caller":"traceutil/trace.go:171","msg":"trace[304145496] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:229; }","duration":"108.724436ms","start":"2026-01-23T00:49:14.45861Z","end":"2026-01-23T00:49:14.567334Z","steps":["trace[304145496] 'range keys from in-memory index tree'  (duration: 108.516776ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:49:20.74012Z","caller":"traceutil/trace.go:171","msg":"trace[676806157] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"110.843121ms","start":"2026-01-23T00:49:20.629259Z","end":"2026-01-23T00:49:20.740102Z","steps":["trace[676806157] 'process raft request'  (duration: 110.733477ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:26.573172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747809854315429456,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T00:49:26.593769Z","caller":"traceutil/trace.go:171","msg":"trace[1850075259] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"596.904816ms","start":"2026-01-23T00:49:25.996852Z","end":"2026-01-23T00:49:26.593757Z","steps":["trace[1850075259] 'process raft request'  (duration: 596.824632ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:26.594185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:25.996838Z","time spent":"596.99095ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:363 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:28.14413Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.550494892s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-23T00:49:28.216344Z","caller":"traceutil/trace.go:171","msg":"trace[1673921566] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"2.143370028s","start":"2026-01-23T00:49:26.072958Z","end":"2026-01-23T00:49:28.216328Z","steps":["trace[1673921566] 'read index received'  (duration: 520.904873ms)","trace[1673921566] 'applied index is now lower than readState.Index'  (duration: 1.622464425s)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:28.216469Z","caller":"traceutil/trace.go:171","msg":"trace[1808425168] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"2.21912252s","start":"2026-01-23T00:49:25.997341Z","end":"2026-01-23T00:49:28.216463Z","steps":["trace[1808425168] 'process raft request'  (duration: 2.146934927s)","trace[1808425168] 'compare'  (duration: 71.372949ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:28.216529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:25.997334Z","time spent":"2.219149192s","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:364 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:28.216637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.143671841s","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-01-23T00:49:28.216652Z","caller":"traceutil/trace.go:171","msg":"trace[760093887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"2.143720364s","start":"2026-01-23T00:49:26.072928Z","end":"2026-01-23T00:49:28.216648Z","steps":["trace[760093887] 'agreement among raft nodes before linearized reading'  (duration: 2.143683362s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:28.216665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:26.072917Z","time spent":"2.143745924s","remote":"127.0.0.1:37272","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-01-23T00:49:28.216729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135620413s","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-01-23T00:49:28.21674Z","caller":"traceutil/trace.go:171","msg":"trace[4697020] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"2.135652904s","start":"2026-01-23T00:49:26.081084Z","end":"2026-01-23T00:49:28.216737Z","steps":["trace[4697020] 'agreement among raft nodes before linearized reading'  (duration: 2.135632624s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:28.216751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:26.081074Z","time spent":"2.135674266s","remote":"127.0.0.1:37272","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-01-23T00:49:28.216831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521956334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:28.216857Z","caller":"traceutil/trace.go:171","msg":"trace[997883505] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:370; }","duration":"1.522000146s","start":"2026-01-23T00:49:26.694852Z","end":"2026-01-23T00:49:28.216852Z","steps":["trace[997883505] 'agreement among raft nodes before linearized reading'  (duration: 1.521970165s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:28.216869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:26.694841Z","time spent":"1.522024357s","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T00:49:28.216934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915945469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"}
{"level":"info","ts":"2026-01-23T00:49:28.216945Z","caller":"traceutil/trace.go:171","msg":"trace[823823017] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"1.91599068s","start":"2026-01-23T00:49:26.300951Z","end":"2026-01-23T00:49:28.216941Z","steps":["trace[823823017] 'agreement among raft nodes before linearized reading'  (duration: 1.915968409s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:28.216955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:26.300934Z","time spent":"1.916018702s","remote":"127.0.0.1:37190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4042,"request content":"key:\"/registry/minions/instance\" "}
{"level":"info","ts":"2026-01-23T00:49:42.802496Z","caller":"traceutil/trace.go:171","msg":"trace[1379639708] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"143.767962ms","start":"2026-01-23T00:49:42.658702Z","end":"2026-01-23T00:49:42.80247Z","steps":["trace[1379639708] 'process raft request'  (duration: 143.629878ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:43.860609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.943912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:43.860679Z","caller":"traceutil/trace.go:171","msg":"trace[369252497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"420.020338ms","start":"2026-01-23T00:49:43.440642Z","end":"2026-01-23T00:49:43.860662Z","steps":["trace[369252497] 'range keys from in-memory index tree'  (duration: 419.882024ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:43.860716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:43.440599Z","time spent":"420.108114ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T00:49:43.861062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.031399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-01-23T00:49:43.861134Z","caller":"traceutil/trace.go:171","msg":"trace[239731148] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:476; }","duration":"566.109865ms","start":"2026-01-23T00:49:43.295009Z","end":"2026-01-23T00:49:43.861119Z","steps":["trace[239731148] 'range keys from in-memory index tree'  (duration: 565.962682ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:43.861174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:43.294945Z","time spent":"566.22285ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "}
{"level":"warn","ts":"2026-01-23T00:49:44.633987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:43.864137Z","time spent":"769.84348ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-23T00:49:44.633958Z","caller":"traceutil/trace.go:171","msg":"trace[836392974] linearizableReadLoop","detail":"{readStateIndex:493; appliedIndex:492; }","duration":"319.151777ms","start":"2026-01-23T00:49:44.314781Z","end":"2026-01-23T00:49:44.633933Z","steps":["trace[836392974] 'read index received'  (duration: 319.08678ms)","trace[836392974] 'applied index is now lower than readState.Index'  (duration: 63.347µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:44.634538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.749529ms","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-01-23T00:49:44.634652Z","caller":"traceutil/trace.go:171","msg":"trace[1778836772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"319.915651ms","start":"2026-01-23T00:49:44.314724Z","end":"2026-01-23T00:49:44.634639Z","steps":["trace[1778836772] 'agreement among raft nodes before linearized reading'  (duration: 319.7121ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:44.634728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.314704Z","time spent":"320.010886ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T00:49:44.635119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.054064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:44.635571Z","caller":"traceutil/trace.go:171","msg":"trace[324961792] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:476; }","duration":"192.525283ms","start":"2026-01-23T00:49:44.443029Z","end":"2026-01-23T00:49:44.635554Z","steps":["trace[324961792] 'agreement among raft nodes before linearized reading'  (duration: 192.061444ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:44.635356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.63696ms","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-01-23T00:49:44.638029Z","caller":"traceutil/trace.go:171","msg":"trace[2133785794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:476; }","duration":"261.344255ms","start":"2026-01-23T00:49:44.376667Z","end":"2026-01-23T00:49:44.638011Z","steps":["trace[2133785794] 'agreement among raft nodes before linearized reading'  (duration: 258.590002ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:44.635434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.535605ms","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-01-23T00:49:44.638232Z","caller":"traceutil/trace.go:171","msg":"trace[857287461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"261.629871ms","start":"2026-01-23T00:49:44.376588Z","end":"2026-01-23T00:49:44.638218Z","steps":["trace[857287461] 'agreement among raft nodes before linearized reading'  (duration: 258.4053ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.430336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.96688ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747809854315429676 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/162.253.55.227\" mod_revision:418 > success:<request_put:<key:\"/registry/masterleases/162.253.55.227\" value_size:67 lease:4747809854315429672 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.227\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T00:49:45.430722Z","caller":"traceutil/trace.go:171","msg":"trace[1485910314] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:493; }","duration":"624.434312ms","start":"2026-01-23T00:49:44.806271Z","end":"2026-01-23T00:49:45.430705Z","steps":["trace[1485910314] 'read index received'  (duration: 16.839931ms)","trace[1485910314] 'applied index is now lower than readState.Index'  (duration: 607.593541ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:45.430826Z","caller":"traceutil/trace.go:171","msg":"trace[1577658372] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"790.507819ms","start":"2026-01-23T00:49:44.640308Z","end":"2026-01-23T00:49:45.430816Z","steps":["trace[1577658372] 'process raft request'  (duration: 790.256231ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.431678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.640291Z","time spent":"791.351731ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:472 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-01-23T00:49:45.430878Z","caller":"traceutil/trace.go:171","msg":"trace[1788500317] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"793.817466ms","start":"2026-01-23T00:49:44.637035Z","end":"2026-01-23T00:49:45.430852Z","steps":["trace[1788500317] 'process raft request'  (duration: 186.08595ms)","trace[1788500317] 'compare'  (duration: 606.859015ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:45.432365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.636972Z","time spent":"795.355956ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.227\" mod_revision:418 > success:<request_put:<key:\"/registry/masterleases/162.253.55.227\" value_size:67 lease:4747809854315429672 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.227\" > >"}
{"level":"info","ts":"2026-01-23T00:49:45.431198Z","caller":"traceutil/trace.go:171","msg":"trace[1411083224] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"787.838904ms","start":"2026-01-23T00:49:44.643349Z","end":"2026-01-23T00:49:45.431188Z","steps":["trace[1411083224] 'process raft request'  (duration: 787.296218ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.432686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.643335Z","time spent":"789.312787ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:473 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-23T00:49:45.431239Z","caller":"traceutil/trace.go:171","msg":"trace[1249138381] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"785.817757ms","start":"2026-01-23T00:49:44.645413Z","end":"2026-01-23T00:49:45.431231Z","steps":["trace[1249138381] 'process raft request'  (duration: 785.261442ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.432947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.645399Z","time spent":"787.51704ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:474 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:45.431351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.075133ms","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-01-23T00:49:45.433194Z","caller":"traceutil/trace.go:171","msg":"trace[268720600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:480; }","duration":"626.915509ms","start":"2026-01-23T00:49:44.806265Z","end":"2026-01-23T00:49:45.43318Z","steps":["trace[268720600] 'agreement among raft nodes before linearized reading'  (duration: 625.000576ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.433256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:44.806246Z","time spent":"626.998466ms","remote":"127.0.0.1:37272","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-01-23T00:49:45.431568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.347253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:45.433519Z","caller":"traceutil/trace.go:171","msg":"trace[1359788662] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:480; }","duration":"312.342612ms","start":"2026-01-23T00:49:45.121165Z","end":"2026-01-23T00:49:45.433507Z","steps":["trace[1359788662] 'agreement among raft nodes before linearized reading'  (duration: 310.372741ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:45.433581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:45.12115Z","time spent":"312.418169ms","remote":"127.0.0.1:37082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "}
{"level":"info","ts":"2026-01-23T00:49:51.932761Z","caller":"traceutil/trace.go:171","msg":"trace[525478179] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:533; }","duration":"439.130628ms","start":"2026-01-23T00:49:51.493609Z","end":"2026-01-23T00:49:51.932739Z","steps":["trace[525478179] 'read index received'  (duration: 439.007803ms)","trace[525478179] 'applied index is now lower than readState.Index'  (duration: 122.325µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:51.932951Z","caller":"traceutil/trace.go:171","msg":"trace[1158285209] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"444.103607ms","start":"2026-01-23T00:49:51.488842Z","end":"2026-01-23T00:49:51.932946Z","steps":["trace[1158285209] 'process raft request'  (duration: 443.818068ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:51.933011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.488833Z","time spent":"444.132786ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:509 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:51.935114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.059034ms","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-01-23T00:49:51.935141Z","caller":"traceutil/trace.go:171","msg":"trace[2114800397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"441.537896ms","start":"2026-01-23T00:49:51.493597Z","end":"2026-01-23T00:49:51.935135Z","steps":["trace[2114800397] 'agreement among raft nodes before linearized reading'  (duration: 440.487846ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:51.935157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.493592Z","time spent":"441.560625ms","remote":"127.0.0.1:37272","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-01-23T00:49:51.935245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.844315ms","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-01-23T00:49:51.935262Z","caller":"traceutil/trace.go:171","msg":"trace[1322038416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"429.872724ms","start":"2026-01-23T00:49:51.505385Z","end":"2026-01-23T00:49:51.935258Z","steps":["trace[1322038416] 'agreement among raft nodes before linearized reading'  (duration: 429.827576ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:51.935273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.505379Z","time spent":"429.891684ms","remote":"127.0.0.1:37272","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-01-23T00:49:51.935376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.516469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8w5h6\" ","response":"range_response_count:1 size:16656"}
{"level":"info","ts":"2026-01-23T00:49:51.935393Z","caller":"traceutil/trace.go:171","msg":"trace[1438970324] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8w5h6; range_end:; response_count:1; response_revision:516; }","duration":"218.569167ms","start":"2026-01-23T00:49:51.716819Z","end":"2026-01-23T00:49:51.935388Z","steps":["trace[1438970324] 'agreement among raft nodes before linearized reading'  (duration: 218.526279ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:51.93549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.00176ms","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-01-23T00:49:51.935515Z","caller":"traceutil/trace.go:171","msg":"trace[668932382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"430.055608ms","start":"2026-01-23T00:49:51.505455Z","end":"2026-01-23T00:49:51.93551Z","steps":["trace[668932382] 'agreement among raft nodes before linearized reading'  (duration: 430.00119ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:51.935538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.505436Z","time spent":"430.096776ms","remote":"127.0.0.1:37272","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-01-23T00:49:52.213213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.209433ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747809854315429756 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:510 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T00:49:52.213357Z","caller":"traceutil/trace.go:171","msg":"trace[652771030] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"259.253557ms","start":"2026-01-23T00:49:51.954062Z","end":"2026-01-23T00:49:52.213315Z","steps":["trace[652771030] 'process raft request'  (duration: 96.86393ms)","trace[652771030] 'compare'  (duration: 162.0462ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:52.790215Z","caller":"traceutil/trace.go:171","msg":"trace[1360560637] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"347.94154ms","start":"2026-01-23T00:49:52.442256Z","end":"2026-01-23T00:49:52.790198Z","steps":["trace[1360560637] 'read index received'  (duration: 347.67256ms)","trace[1360560637] 'applied index is now lower than readState.Index'  (duration: 268.21µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:52.790247Z","caller":"traceutil/trace.go:171","msg":"trace[101901] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"830.356592ms","start":"2026-01-23T00:49:51.959858Z","end":"2026-01-23T00:49:52.790215Z","steps":["trace[101901] 'process raft request'  (duration: 830.229826ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:52.79035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.076395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:52.790374Z","caller":"traceutil/trace.go:171","msg":"trace[1049366497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"348.142873ms","start":"2026-01-23T00:49:52.442224Z","end":"2026-01-23T00:49:52.790367Z","steps":["trace[1049366497] 'agreement among raft nodes before linearized reading'  (duration: 348.052636ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:52.790415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:52.442185Z","time spent":"348.223989ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T00:49:52.790413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.959851Z","time spent":"830.473938ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:512 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-23T00:49:52.790627Z","caller":"traceutil/trace.go:171","msg":"trace[802627112] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"835.473046ms","start":"2026-01-23T00:49:51.955148Z","end":"2026-01-23T00:49:52.790621Z","steps":["trace[802627112] 'process raft request'  (duration: 834.82065ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:52.790669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.955142Z","time spent":"835.509644ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:511 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-23T00:49:52.790717Z","caller":"traceutil/trace.go:171","msg":"trace[1671299687] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"830.281585ms","start":"2026-01-23T00:49:51.960426Z","end":"2026-01-23T00:49:52.790708Z","steps":["trace[1671299687] 'process raft request'  (duration: 829.734356ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:52.790796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:51.96042Z","time spent":"830.317914ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-8w5h6\" mod_revision:458 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-8w5h6\" value_size:16754 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-8w5h6\" > >"}
{"level":"info","ts":"2026-01-23T00:49:52.840211Z","caller":"traceutil/trace.go:171","msg":"trace[861760770] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"280.826766ms","start":"2026-01-23T00:49:52.559364Z","end":"2026-01-23T00:49:52.840191Z","steps":["trace[861760770] 'process raft request'  (duration: 280.562046ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:54.198883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.103664ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747809854315429766 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/masterleases/162.253.55.227\" mod_revision:477 > success:<request_put:<key:\"/registry/masterleases/162.253.55.227\" value_size:67 lease:4747809854315429764 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.227\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T00:49:54.198971Z","caller":"traceutil/trace.go:171","msg":"trace[1072986724] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"245.761772ms","start":"2026-01-23T00:49:53.953194Z","end":"2026-01-23T00:49:54.198956Z","steps":["trace[1072986724] 'read index received'  (duration: 42.329µs)","trace[1072986724] 'applied index is now lower than readState.Index'  (duration: 245.718393ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:54.199129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.930457ms","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-01-23T00:49:54.199165Z","caller":"traceutil/trace.go:171","msg":"trace[563044944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"246.002683ms","start":"2026-01-23T00:49:53.953153Z","end":"2026-01-23T00:49:54.199156Z","steps":["trace[563044944] 'agreement among raft nodes before linearized reading'  (duration: 245.843849ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:49:54.199176Z","caller":"traceutil/trace.go:171","msg":"trace[843632525] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"682.312761ms","start":"2026-01-23T00:49:53.516832Z","end":"2026-01-23T00:49:54.199145Z","steps":["trace[843632525] 'process raft request'  (duration: 54.881038ms)","trace[843632525] 'compare'  (duration: 626.593122ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:54.199297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:53.516814Z","time spent":"682.413707ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/masterleases/162.253.55.227\" mod_revision:477 > success:<request_put:<key:\"/registry/masterleases/162.253.55.227\" value_size:67 lease:4747809854315429764 >> failure:<request_range:<key:\"/registry/masterleases/162.253.55.227\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:54.717145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747809854315429770,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T00:49:55.218156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747809854315429770,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T00:49:55.275994Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.069836212s","expected-duration":"1s"}
{"level":"warn","ts":"2026-01-23T00:49:56.188345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:55.483071Z","time spent":"705.267411ms","remote":"127.0.0.1:33740","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-23T00:49:56.188753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.133679ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747809854315429771 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:516 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T00:49:56.188878Z","caller":"traceutil/trace.go:171","msg":"trace[1105806869] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"1.971967564s","start":"2026-01-23T00:49:54.216875Z","end":"2026-01-23T00:49:56.188842Z","steps":["trace[1105806869] 'read index received'  (duration: 1.078705689s)","trace[1105806869] 'applied index is now lower than readState.Index'  (duration: 893.258105ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:56.18916Z","caller":"traceutil/trace.go:171","msg":"trace[1945499142] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.983038173s","start":"2026-01-23T00:49:54.206108Z","end":"2026-01-23T00:49:56.189146Z","steps":["trace[1945499142] 'process raft request'  (duration: 1.089418661s)","trace[1945499142] 'compare'  (duration: 892.738242ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:56.189245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:54.206099Z","time spent":"1.983090842s","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:516 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T00:49:56.189666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972753028s","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-01-23T00:49:56.189698Z","caller":"traceutil/trace.go:171","msg":"trace[713218744] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:523; }","duration":"1.972830585s","start":"2026-01-23T00:49:54.216858Z","end":"2026-01-23T00:49:56.189688Z","steps":["trace[713218744] 'agreement among raft nodes before linearized reading'  (duration: 1.972665511s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:56.190673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.737545353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:56.190721Z","caller":"traceutil/trace.go:171","msg":"trace[261025254] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.737623271s","start":"2026-01-23T00:49:54.45308Z","end":"2026-01-23T00:49:56.190703Z","steps":["trace[261025254] 'agreement among raft nodes before linearized reading'  (duration: 1.737365379s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:56.190754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:54.453065Z","time spent":"1.737683168s","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T00:49:56.190978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.392370774s","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-01-23T00:49:56.191007Z","caller":"traceutil/trace.go:171","msg":"trace[1311723321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.392428582s","start":"2026-01-23T00:49:54.79857Z","end":"2026-01-23T00:49:56.190998Z","steps":["trace[1311723321] 'agreement among raft nodes before linearized reading'  (duration: 1.392313836s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:56.191033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:54.798555Z","time spent":"1.392472091s","remote":"127.0.0.1:37272","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-01-23T00:49:56.191193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39278549s","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-01-23T00:49:56.19122Z","caller":"traceutil/trace.go:171","msg":"trace[362299411] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:523; }","duration":"1.392850678s","start":"2026-01-23T00:49:54.798362Z","end":"2026-01-23T00:49:56.191212Z","steps":["trace[362299411] 'agreement among raft nodes before linearized reading'  (duration: 1.39277778s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:56.191244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:54.798343Z","time spent":"1.392895056s","remote":"127.0.0.1:37272","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-01-23T00:49:56.196526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:54.216851Z","time spent":"1.979653178s","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-23T00:49:56.360533Z","caller":"traceutil/trace.go:171","msg":"trace[1190083408] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"160.768175ms","start":"2026-01-23T00:49:56.199739Z","end":"2026-01-23T00:49:56.360507Z","steps":["trace[1190083408] 'process raft request'  (duration: 118.949375ms)","trace[1190083408] 'compare'  (duration: 41.595717ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:49:56.360854Z","caller":"traceutil/trace.go:171","msg":"trace[237283805] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"157.944408ms","start":"2026-01-23T00:49:56.202885Z","end":"2026-01-23T00:49:56.360829Z","steps":["trace[237283805] 'process raft request'  (duration: 157.564941ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:49:56.361085Z","caller":"traceutil/trace.go:171","msg":"trace[782522069] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:542; }","duration":"153.443918ms","start":"2026-01-23T00:49:56.207626Z","end":"2026-01-23T00:49:56.36107Z","steps":["trace[782522069] 'read index received'  (duration: 111.070997ms)","trace[782522069] 'applied index is now lower than readState.Index'  (duration: 42.371711ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:49:56.361241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.570754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:49:56.361274Z","caller":"traceutil/trace.go:171","msg":"trace[950632946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"153.650222ms","start":"2026-01-23T00:49:56.207615Z","end":"2026-01-23T00:49:56.361265Z","steps":["trace[950632946] 'agreement among raft nodes before linearized reading'  (duration: 153.526386ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:49:56.361298Z","caller":"traceutil/trace.go:171","msg":"trace[1933999220] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"156.853355ms","start":"2026-01-23T00:49:56.204433Z","end":"2026-01-23T00:49:56.361287Z","steps":["trace[1933999220] 'process raft request'  (duration: 156.329702ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:58.500485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.001348ms","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-01-23T00:49:58.500537Z","caller":"traceutil/trace.go:171","msg":"trace[142773332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:535; }","duration":"301.076276ms","start":"2026-01-23T00:49:58.199443Z","end":"2026-01-23T00:49:58.500519Z","steps":["trace[142773332] 'range keys from in-memory index tree'  (duration: 300.894282ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:58.50057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:49:58.199428Z","time spent":"301.137124ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T00:49:58.500561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.671267ms","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-01-23T00:49:58.500622Z","caller":"traceutil/trace.go:171","msg":"trace[336774681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"132.804403ms","start":"2026-01-23T00:49:58.367802Z","end":"2026-01-23T00:49:58.500606Z","steps":["trace[336774681] 'range keys from in-memory index tree'  (duration: 132.468413ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:58.50076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.6866ms","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-01-23T00:49:58.500776Z","caller":"traceutil/trace.go:171","msg":"trace[107611265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"130.721818ms","start":"2026-01-23T00:49:58.370049Z","end":"2026-01-23T00:49:58.500771Z","steps":["trace[107611265] 'range keys from in-memory index tree'  (duration: 130.613461ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:49:58.500852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.467695ms","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-01-23T00:49:58.500863Z","caller":"traceutil/trace.go:171","msg":"trace[60674234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"131.514633ms","start":"2026-01-23T00:49:58.369344Z","end":"2026-01-23T00:49:58.500859Z","steps":["trace[60674234] 'range keys from in-memory index tree'  (duration: 131.386418ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:16.555118Z","caller":"traceutil/trace.go:171","msg":"trace[1580722089] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:750; }","duration":"112.516288ms","start":"2026-01-23T00:50:16.442583Z","end":"2026-01-23T00:50:16.5551Z","steps":["trace[1580722089] 'read index received'  (duration: 112.351321ms)","trace[1580722089] 'applied index is now lower than readState.Index'  (duration: 164.257µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:16.555198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.598637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:16.555214Z","caller":"traceutil/trace.go:171","msg":"trace[311486048] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:726; }","duration":"112.653686ms","start":"2026-01-23T00:50:16.442555Z","end":"2026-01-23T00:50:16.555209Z","steps":["trace[311486048] 'agreement among raft nodes before linearized reading'  (duration: 112.608497ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:16.555396Z","caller":"traceutil/trace.go:171","msg":"trace[1759030544] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"222.449549ms","start":"2026-01-23T00:50:16.332941Z","end":"2026-01-23T00:50:16.555391Z","steps":["trace[1759030544] 'process raft request'  (duration: 222.014396ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:17.572372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747809854315430161,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T00:50:17.954401Z","caller":"traceutil/trace.go:171","msg":"trace[1503119110] linearizableReadLoop","detail":"{readStateIndex:756; appliedIndex:755; }","duration":"882.377826ms","start":"2026-01-23T00:50:17.072003Z","end":"2026-01-23T00:50:17.954381Z","steps":["trace[1503119110] 'read index received'  (duration: 882.179429ms)","trace[1503119110] 'applied index is now lower than readState.Index'  (duration: 197.557µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:17.954859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.835139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T00:50:17.955136Z","caller":"traceutil/trace.go:171","msg":"trace[172861044] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:731; }","duration":"883.163913ms","start":"2026-01-23T00:50:17.07196Z","end":"2026-01-23T00:50:17.955124Z","steps":["trace[172861044] 'agreement among raft nodes before linearized reading'  (duration: 882.783369ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:17.956178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:17.07195Z","time spent":"884.210057ms","remote":"127.0.0.1:37362","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T00:50:17.955362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.385679ms","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-01-23T00:50:17.956589Z","caller":"traceutil/trace.go:171","msg":"trace[53606487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:731; }","duration":"425.671029ms","start":"2026-01-23T00:50:17.530877Z","end":"2026-01-23T00:50:17.956548Z","steps":["trace[53606487] 'agreement among raft nodes before linearized reading'  (duration: 424.238261ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:17.954905Z","caller":"traceutil/trace.go:171","msg":"trace[1122297648] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"899.749606ms","start":"2026-01-23T00:50:17.055144Z","end":"2026-01-23T00:50:17.954894Z","steps":["trace[1122297648] 'process raft request'  (duration: 899.106776ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:17.957026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:17.055127Z","time spent":"901.665406ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:645 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-23T00:50:17.955686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.23912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"}
{"level":"info","ts":"2026-01-23T00:50:17.957304Z","caller":"traceutil/trace.go:171","msg":"trace[1725398928] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:731; }","duration":"247.901974ms","start":"2026-01-23T00:50:17.709389Z","end":"2026-01-23T00:50:17.957291Z","steps":["trace[1725398928] 'agreement among raft nodes before linearized reading'  (duration: 246.26224ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:17.95672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:17.530821Z","time spent":"425.882795ms","remote":"127.0.0.1:37272","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-01-23T00:50:18.29526Z","caller":"traceutil/trace.go:171","msg":"trace[1531199917] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"332.576033ms","start":"2026-01-23T00:50:17.962664Z","end":"2026-01-23T00:50:18.29524Z","steps":["trace[1531199917] 'process raft request'  (duration: 320.922936ms)","trace[1531199917] 'compare'  (duration: 11.567929ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:18.295368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:17.962649Z","time spent":"332.666282ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:725 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-23T00:50:18.577701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.998221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.577766Z","caller":"traceutil/trace.go:171","msg":"trace[2113068130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:732; }","duration":"136.106369ms","start":"2026-01-23T00:50:18.441644Z","end":"2026-01-23T00:50:18.577751Z","steps":["trace[2113068130] 'range keys from in-memory index tree'  (duration: 135.947562ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:18.758254Z","caller":"traceutil/trace.go:171","msg":"trace[1406032129] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"121.415017ms","start":"2026-01-23T00:50:18.636822Z","end":"2026-01-23T00:50:18.758238Z","steps":["trace[1406032129] 'process raft request'  (duration: 121.19254ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.984856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.919475ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747809854315430175 > lease_revoke:<id:41e39be853920034>","response":"size:28"}
{"level":"info","ts":"2026-01-23T00:50:18.985069Z","caller":"traceutil/trace.go:171","msg":"trace[1149777869] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"226.875631ms","start":"2026-01-23T00:50:18.758178Z","end":"2026-01-23T00:50:18.985054Z","steps":["trace[1149777869] 'read index received'  (duration: 64.764408ms)","trace[1149777869] 'applied index is now lower than readState.Index'  (duration: 162.110223ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:18.985148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.205533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.985164Z","caller":"traceutil/trace.go:171","msg":"trace[627829819] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"236.230853ms","start":"2026-01-23T00:50:18.748928Z","end":"2026-01-23T00:50:18.985159Z","steps":["trace[627829819] 'agreement among raft nodes before linearized reading'  (duration: 236.189943ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.43546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.98536Z","caller":"traceutil/trace.go:171","msg":"trace[144337920] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"236.46558ms","start":"2026-01-23T00:50:18.748889Z","end":"2026-01-23T00:50:18.985355Z","steps":["trace[144337920] 'agreement among raft nodes before linearized reading'  (duration: 236.43758ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.544618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.985437Z","caller":"traceutil/trace.go:171","msg":"trace[1778881769] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:733; }","duration":"236.566008ms","start":"2026-01-23T00:50:18.748867Z","end":"2026-01-23T00:50:18.985433Z","steps":["trace[1778881769] 'agreement among raft nodes before linearized reading'  (duration: 236.543908ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.336912ms","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-01-23T00:50:18.985561Z","caller":"traceutil/trace.go:171","msg":"trace[1152137893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:733; }","duration":"238.357491ms","start":"2026-01-23T00:50:18.747199Z","end":"2026-01-23T00:50:18.985556Z","steps":["trace[1152137893] 'agreement among raft nodes before linearized reading'  (duration: 238.294242ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.474095ms","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-01-23T00:50:18.98568Z","caller":"traceutil/trace.go:171","msg":"trace[249428226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:733; }","duration":"241.510424ms","start":"2026-01-23T00:50:18.744166Z","end":"2026-01-23T00:50:18.985676Z","steps":["trace[249428226] 'agreement among raft nodes before linearized reading'  (duration: 241.468395ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.469251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.985762Z","caller":"traceutil/trace.go:171","msg":"trace[2075398249] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"248.490591ms","start":"2026-01-23T00:50:18.737267Z","end":"2026-01-23T00:50:18.985758Z","steps":["trace[2075398249] 'agreement among raft nodes before linearized reading'  (duration: 248.465891ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.57648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.985858Z","caller":"traceutil/trace.go:171","msg":"trace[798461866] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"248.599839ms","start":"2026-01-23T00:50:18.737254Z","end":"2026-01-23T00:50:18.985854Z","steps":["trace[798461866] 'agreement among raft nodes before linearized reading'  (duration: 248.57506ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.677007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.985931Z","caller":"traceutil/trace.go:171","msg":"trace[725186859] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:733; }","duration":"248.690167ms","start":"2026-01-23T00:50:18.737236Z","end":"2026-01-23T00:50:18.985926Z","steps":["trace[725186859] 'agreement among raft nodes before linearized reading'  (duration: 248.670528ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.985991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.886406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.986003Z","caller":"traceutil/trace.go:171","msg":"trace[788660256] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"258.907536ms","start":"2026-01-23T00:50:18.727091Z","end":"2026-01-23T00:50:18.985999Z","steps":["trace[788660256] 'agreement among raft nodes before linearized reading'  (duration: 258.887286ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.98608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.703604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.986094Z","caller":"traceutil/trace.go:171","msg":"trace[1128622229] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"259.726944ms","start":"2026-01-23T00:50:18.726364Z","end":"2026-01-23T00:50:18.986091Z","steps":["trace[1128622229] 'agreement among raft nodes before linearized reading'  (duration: 259.703694ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.986155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.799713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:18.986167Z","caller":"traceutil/trace.go:171","msg":"trace[1067708411] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:733; }","duration":"259.832222ms","start":"2026-01-23T00:50:18.726332Z","end":"2026-01-23T00:50:18.986164Z","steps":["trace[1067708411] 'agreement among raft nodes before linearized reading'  (duration: 259.811872ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:18.98687Z","caller":"traceutil/trace.go:171","msg":"trace[1064167557] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"221.720898ms","start":"2026-01-23T00:50:18.765141Z","end":"2026-01-23T00:50:18.986862Z","steps":["trace[1064167557] 'process raft request'  (duration: 219.803927ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.987295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.651051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T00:50:18.987312Z","caller":"traceutil/trace.go:171","msg":"trace[1396419169] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:734; }","duration":"101.671471ms","start":"2026-01-23T00:50:18.885636Z","end":"2026-01-23T00:50:18.987307Z","steps":["trace[1396419169] 'agreement among raft nodes before linearized reading'  (duration: 101.624681ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:18.987486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.958931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-5dd5756b68-6rs6n\" ","response":"range_response_count:1 size:2277"}
{"level":"info","ts":"2026-01-23T00:50:18.987504Z","caller":"traceutil/trace.go:171","msg":"trace[22607931] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/kube-system/coredns-5dd5756b68-6rs6n; range_end:; response_count:1; response_revision:734; }","duration":"132.00337ms","start":"2026-01-23T00:50:18.855494Z","end":"2026-01-23T00:50:18.987497Z","steps":["trace[22607931] 'agreement among raft nodes before linearized reading'  (duration: 131.31508ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:19.318326Z","caller":"traceutil/trace.go:171","msg":"trace[1883578425] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"321.468945ms","start":"2026-01-23T00:50:18.996836Z","end":"2026-01-23T00:50:19.318305Z","steps":["trace[1883578425] 'process raft request'  (duration: 321.256398ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:19.318458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:18.996828Z","time spent":"321.564534ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:730 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-23T00:50:19.318521Z","caller":"traceutil/trace.go:171","msg":"trace[805336189] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"315.73755ms","start":"2026-01-23T00:50:19.002765Z","end":"2026-01-23T00:50:19.318503Z","steps":["trace[805336189] 'process raft request'  (duration: 315.487154ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:19.318616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:19.002752Z","time spent":"315.80887ms","remote":"127.0.0.1:37202","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4994,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/coredns-5dd5756b68-6rs6n\" mod_revision:688 > success:<request_put:<key:\"/registry/pods/kube-system/coredns-5dd5756b68-6rs6n\" value_size:4935 >> failure:<request_range:<key:\"/registry/pods/kube-system/coredns-5dd5756b68-6rs6n\" > >"}
{"level":"info","ts":"2026-01-23T00:50:19.318695Z","caller":"traceutil/trace.go:171","msg":"trace[1275675160] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"321.789401ms","start":"2026-01-23T00:50:18.996895Z","end":"2026-01-23T00:50:19.318684Z","steps":["trace[1275675160] 'process raft request'  (duration: 321.325257ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:19.318768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:50:18.996888Z","time spent":"321.83174ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:729 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-23T00:50:20.066981Z","caller":"traceutil/trace.go:171","msg":"trace[120015489] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"177.321912ms","start":"2026-01-23T00:50:19.889638Z","end":"2026-01-23T00:50:20.06696Z","steps":["trace[120015489] 'process raft request'  (duration: 177.174184ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:33.605766Z","caller":"traceutil/trace.go:171","msg":"trace[781631975] linearizableReadLoop","detail":"{readStateIndex:806; appliedIndex:805; }","duration":"165.989228ms","start":"2026-01-23T00:50:33.439762Z","end":"2026-01-23T00:50:33.605751Z","steps":["trace[781631975] 'read index received'  (duration: 165.894158ms)","trace[781631975] 'applied index is now lower than readState.Index'  (duration: 94.36µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:33.605868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.088857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:50:33.605885Z","caller":"traceutil/trace.go:171","msg":"trace[811762731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"166.144216ms","start":"2026-01-23T00:50:33.439736Z","end":"2026-01-23T00:50:33.60588Z","steps":["trace[811762731] 'agreement among raft nodes before linearized reading'  (duration: 166.092446ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:33.606079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.010361ms","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-01-23T00:50:33.606092Z","caller":"traceutil/trace.go:171","msg":"trace[1698458733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"149.050101ms","start":"2026-01-23T00:50:33.457038Z","end":"2026-01-23T00:50:33.606088Z","steps":["trace[1698458733] 'agreement among raft nodes before linearized reading'  (duration: 148.983831ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:50:33.606186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.728086ms","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-01-23T00:50:33.606198Z","caller":"traceutil/trace.go:171","msg":"trace[892561799] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"120.761066ms","start":"2026-01-23T00:50:33.485434Z","end":"2026-01-23T00:50:33.606195Z","steps":["trace[892561799] 'agreement among raft nodes before linearized reading'  (duration: 120.725226ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:35.254983Z","caller":"traceutil/trace.go:171","msg":"trace[1791887739] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"130.394355ms","start":"2026-01-23T00:50:35.124567Z","end":"2026-01-23T00:50:35.254961Z","steps":["trace[1791887739] 'process raft request'  (duration: 130.238856ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:35.847268Z","caller":"traceutil/trace.go:171","msg":"trace[805263069] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"109.877685ms","start":"2026-01-23T00:50:35.737356Z","end":"2026-01-23T00:50:35.847234Z","steps":["trace[805263069] 'read index received'  (duration: 37.395785ms)","trace[805263069] 'applied index is now lower than readState.Index'  (duration: 72.48093ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T00:50:35.847385Z","caller":"traceutil/trace.go:171","msg":"trace[705843084] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"136.966686ms","start":"2026-01-23T00:50:35.710398Z","end":"2026-01-23T00:50:35.847365Z","steps":["trace[705843084] 'process raft request'  (duration: 64.399476ms)","trace[705843084] 'compare'  (duration: 72.345461ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:50:35.847397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.010655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T00:50:35.847436Z","caller":"traceutil/trace.go:171","msg":"trace[157333449] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:786; }","duration":"110.094385ms","start":"2026-01-23T00:50:35.737332Z","end":"2026-01-23T00:50:35.847426Z","steps":["trace[157333449] 'agreement among raft nodes before linearized reading'  (duration: 109.995565ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:37.363531Z","caller":"traceutil/trace.go:171","msg":"trace[1563841061] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"103.115364ms","start":"2026-01-23T00:50:37.260394Z","end":"2026-01-23T00:50:37.363509Z","steps":["trace[1563841061] 'process raft request'  (duration: 102.982835ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:39.125612Z","caller":"traceutil/trace.go:171","msg":"trace[1380351442] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"107.970248ms","start":"2026-01-23T00:50:39.01762Z","end":"2026-01-23T00:50:39.12559Z","steps":["trace[1380351442] 'process raft request'  (duration: 107.831628ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T00:50:48.075644Z","caller":"traceutil/trace.go:171","msg":"trace[1344151006] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"101.873158ms","start":"2026-01-23T00:50:47.973133Z","end":"2026-01-23T00:50:48.075007Z","steps":["trace[1344151006] 'process raft request'  (duration: 101.755238ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:51:15.83384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.665805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T00:51:15.833911Z","caller":"traceutil/trace.go:171","msg":"trace[1479647576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:927; }","duration":"389.782427ms","start":"2026-01-23T00:51:15.44411Z","end":"2026-01-23T00:51:15.833893Z","steps":["trace[1479647576] 'range keys from in-memory index tree'  (duration: 389.566774ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:51:15.833946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:51:15.44409Z","time spent":"389.847417ms","remote":"127.0.0.1:36988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T00:51:15.833992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.20746ms","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-01-23T00:51:15.834027Z","caller":"traceutil/trace.go:171","msg":"trace[325877696] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:927; }","duration":"227.27653ms","start":"2026-01-23T00:51:15.60674Z","end":"2026-01-23T00:51:15.834016Z","steps":["trace[325877696] 'range keys from in-memory index tree'  (duration: 226.930416ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:51:22.168183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.121159ms","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-01-23T00:51:22.168231Z","caller":"traceutil/trace.go:171","msg":"trace[1937984306] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"103.217521ms","start":"2026-01-23T00:51:22.065003Z","end":"2026-01-23T00:51:22.168221Z","steps":["trace[1937984306] 'range keys from in-memory index tree'  (duration: 102.985557ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:51:23.77546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:51:23.311384Z","time spent":"464.069664ms","remote":"127.0.0.1:37010","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-23T00:51:23.775453Z","caller":"traceutil/trace.go:171","msg":"trace[47332860] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"408.940713ms","start":"2026-01-23T00:51:23.366489Z","end":"2026-01-23T00:51:23.77543Z","steps":["trace[47332860] 'read index received'  (duration: 408.790621ms)","trace[47332860] 'applied index is now lower than readState.Index'  (duration: 148.952µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T00:51:23.776523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.021968ms","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-01-23T00:51:23.776659Z","caller":"traceutil/trace.go:171","msg":"trace[2126557075] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:954; }","duration":"410.1915ms","start":"2026-01-23T00:51:23.366453Z","end":"2026-01-23T00:51:23.776645Z","steps":["trace[2126557075] 'agreement among raft nodes before linearized reading'  (duration: 409.070295ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T00:51:23.77686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:51:23.366443Z","time spent":"410.399473ms","remote":"127.0.0.1:37272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}