{"level":"warn","ts":"2026-03-09T01:38:07.255266Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-09T01:38:07.25536Z","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-03-09T01:38:07.255428Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-09T01:38:07.255436Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]}
{"level":"info","ts":"2026-03-09T01:38:07.255452Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-03-09T01:38:07.256153Z","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-03-09T01:38:07.256243Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.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-03-09T01:38:07.289684Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"33.226339ms"}
{"level":"info","ts":"2026-03-09T01:38:07.297432Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"}
{"level":"info","ts":"2026-03-09T01:38:07.297516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-09T01:38:07.297542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"}
{"level":"info","ts":"2026-03-09T01:38:07.297553Z","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-03-09T01:38:07.297568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"}
{"level":"info","ts":"2026-03-09T01:38:07.297605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"}
{"level":"warn","ts":"2026-03-09T01:38:07.30795Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-09T01:38:07.314652Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-09T01:38:07.319252Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-03-09T01:38:07.324883Z","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-03-09T01:38:07.325089Z","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-03-09T01:38:07.325294Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-09T01:38:07.325376Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-09T01:38:07.325391Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-09T01:38:07.327976Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"}
{"level":"info","ts":"2026-03-09T01:38:07.328146Z","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-03-09T01:38:07.331575Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-03-09T01:38:07.331743Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"}
{"level":"info","ts":"2026-03-09T01:38:07.331775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"}
{"level":"info","ts":"2026-03-09T01:38:07.331937Z","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-03-09T01:38:07.331963Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-09T01:38:08.197975Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-09T01:38:08.198081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-09T01:38:08.198109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"}
{"level":"info","ts":"2026-03-09T01:38:08.19813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"}
{"level":"info","ts":"2026-03-09T01:38:08.198142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"}
{"level":"info","ts":"2026-03-09T01:38:08.198168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"}
{"level":"info","ts":"2026-03-09T01:38:08.198184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"}
{"level":"info","ts":"2026-03-09T01:38:08.199195Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:38:08.200156Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T01:38:08.200222Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-09T01:38:08.200463Z","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-03-09T01:38:08.200585Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:38:08.200621Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-09T01:38:08.203361Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-09T01:38:08.203408Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-09T01:38:08.203621Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"}
{"level":"info","ts":"2026-03-09T01:38:08.200114Z","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-03-09T01:38:08.204139Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"warn","ts":"2026-03-09T01:38:13.275578Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.449848285s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-09T01:38:13.276096Z","caller":"traceutil/trace.go:171","msg":"trace[1001308384] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"1.450417728s","start":"2026-03-09T01:38:11.825638Z","end":"2026-03-09T01:38:13.276055Z","steps":["trace[1001308384] 'process raft request'  (duration: 1.450158562s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:13.276748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:11.825626Z","time spent":"1.450574802s","remote":"127.0.0.1:59396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:28 > success:<request_put:<key:\"/registry/minions/instance\" value_size:3661 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-03-09T01:38:13.847596Z","caller":"traceutil/trace.go:171","msg":"trace[2060185881] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"566.163976ms","start":"2026-03-09T01:38:13.281402Z","end":"2026-03-09T01:38:13.847566Z","steps":["trace[2060185881] 'process raft request'  (duration: 560.519316ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:13.84772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:13.28138Z","time spent":"566.284548ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:0 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<>"}
{"level":"info","ts":"2026-03-09T01:38:13.849283Z","caller":"traceutil/trace.go:171","msg":"trace[773618340] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"564.161567ms","start":"2026-03-09T01:38:13.285098Z","end":"2026-03-09T01:38:13.84926Z","steps":["trace[773618340] 'process raft request'  (duration: 564.009604ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:13.849372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:13.28508Z","time spent":"564.253959ms","remote":"127.0.0.1:59298","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":525,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/secrets/kube-system/bootstrap-token-5nmctn\" mod_revision:0 > success:<request_put:<key:\"/registry/secrets/kube-system/bootstrap-token-5nmctn\" value_size:465 >> failure:<>"}
{"level":"info","ts":"2026-03-09T01:38:13.849341Z","caller":"traceutil/trace.go:171","msg":"trace[358259889] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:215; }","duration":"299.393812ms","start":"2026-03-09T01:38:13.549923Z","end":"2026-03-09T01:38:13.849317Z","steps":["trace[358259889] 'read index received'  (duration: 292.153822ms)","trace[358259889] 'applied index is now lower than readState.Index'  (duration: 7.23717ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:38:13.849452Z","caller":"traceutil/trace.go:171","msg":"trace[13639000] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"149.778974ms","start":"2026-03-09T01:38:13.699667Z","end":"2026-03-09T01:38:13.849446Z","steps":["trace[13639000] 'process raft request'  (duration: 149.602399ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:13.849629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.640148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-03-09T01:38:13.849717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.35352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-09T01:38:13.84972Z","caller":"traceutil/trace.go:171","msg":"trace[1425649222] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:213; }","duration":"299.821572ms","start":"2026-03-09T01:38:13.549884Z","end":"2026-03-09T01:38:13.849706Z","steps":["trace[1425649222] 'agreement among raft nodes before linearized reading'  (duration: 299.615537ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:38:13.849776Z","caller":"traceutil/trace.go:171","msg":"trace[1941466068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:213; }","duration":"280.476562ms","start":"2026-03-09T01:38:13.569279Z","end":"2026-03-09T01:38:13.849755Z","steps":["trace[1941466068] 'agreement among raft nodes before linearized reading'  (duration: 280.351429ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:13.849841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.550774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:38:13.849941Z","caller":"traceutil/trace.go:171","msg":"trace[1697674482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:213; }","duration":"280.708438ms","start":"2026-03-09T01:38:13.569207Z","end":"2026-03-09T01:38:13.849915Z","steps":["trace[1697674482] 'agreement among raft nodes before linearized reading'  (duration: 280.410211ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:38:39.726557Z","caller":"traceutil/trace.go:171","msg":"trace[189488039] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"430.264517ms","start":"2026-03-09T01:38:39.296265Z","end":"2026-03-09T01:38:39.726529Z","steps":["trace[189488039] 'process raft request'  (duration: 429.991611ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:39.726722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:39.296249Z","time spent":"430.39253ms","remote":"127.0.0.1:59396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4567,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:375 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4533 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-03-09T01:38:46.092052Z","caller":"traceutil/trace.go:171","msg":"trace[683915545] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"109.893465ms","start":"2026-03-09T01:38:45.982127Z","end":"2026-03-09T01:38:46.09202Z","steps":["trace[683915545] 'process raft request'  (duration: 109.676059ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:38:49.396552Z","caller":"traceutil/trace.go:171","msg":"trace[2032495145] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"308.706423ms","start":"2026-03-09T01:38:49.08777Z","end":"2026-03-09T01:38:49.396476Z","steps":["trace[2032495145] 'process raft request'  (duration: 308.326873ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:38:49.396713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:49.087761Z","time spent":"308.871647ms","remote":"127.0.0.1:59246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5571,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" mod_revision:519 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" value_size:5473 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" > >"}
{"level":"info","ts":"2026-03-09T01:38:49.398138Z","caller":"traceutil/trace.go:171","msg":"trace[1417671326] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"139.324004ms","start":"2026-03-09T01:38:49.258786Z","end":"2026-03-09T01:38:49.39811Z","steps":["trace[1417671326] 'process raft request'  (duration: 139.207421ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:06.433288Z","caller":"traceutil/trace.go:171","msg":"trace[798488605] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"100.237895ms","start":"2026-03-09T01:39:06.333013Z","end":"2026-03-09T01:39:06.433251Z","steps":["trace[798488605] 'process raft request'  (duration: 100.129322ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:06.43376Z","caller":"traceutil/trace.go:171","msg":"trace[900212989] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"101.732594ms","start":"2026-03-09T01:39:06.332003Z","end":"2026-03-09T01:39:06.433736Z","steps":["trace[900212989] 'process raft request'  (duration: 34.357873ms)","trace[900212989] 'compare'  (duration: 66.690703ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:39:06.499125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.355856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-09T01:39:06.499192Z","caller":"traceutil/trace.go:171","msg":"trace[1188250085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:685; }","duration":"164.463518ms","start":"2026-03-09T01:39:06.334711Z","end":"2026-03-09T01:39:06.499174Z","steps":["trace[1188250085] 'agreement among raft nodes before linearized reading'  (duration: 98.603584ms)","trace[1188250085] 'range keys from in-memory index tree'  (duration: 65.675489ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:39:08.00969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353036084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:39:08.009778Z","caller":"traceutil/trace.go:171","msg":"trace[409882365] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:686; }","duration":"1.353160887s","start":"2026-03-09T01:39:06.656594Z","end":"2026-03-09T01:39:08.009754Z","steps":["trace[409882365] 'range keys from in-memory index tree'  (duration: 1.352975292s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:08.009852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:06.656577Z","time spent":"1.353253039s","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-09T01:39:08.009992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"867.614979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"}
{"level":"info","ts":"2026-03-09T01:39:08.010075Z","caller":"traceutil/trace.go:171","msg":"trace[643523484] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:686; }","duration":"867.759032ms","start":"2026-03-09T01:39:07.142297Z","end":"2026-03-09T01:39:08.010056Z","steps":["trace[643523484] 'range keys from in-memory index tree'  (duration: 867.038635ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:08.010127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.142272Z","time spent":"867.838055ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-03-09T01:39:08.565574Z","caller":"traceutil/trace.go:171","msg":"trace[1594907491] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"128.380654ms","start":"2026-03-09T01:39:08.437166Z","end":"2026-03-09T01:39:08.565547Z","steps":["trace[1594907491] 'read index received'  (duration: 128.209709ms)","trace[1594907491] 'applied index is now lower than readState.Index'  (duration: 169.815µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:39:08.565776Z","caller":"traceutil/trace.go:171","msg":"trace[1798903585] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"212.012897ms","start":"2026-03-09T01:39:08.353748Z","end":"2026-03-09T01:39:08.565761Z","steps":["trace[1798903585] 'process raft request'  (duration: 211.649228ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:08.565993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.288821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:39:08.566058Z","caller":"traceutil/trace.go:171","msg":"trace[1785791231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:688; }","duration":"128.391913ms","start":"2026-03-09T01:39:08.437641Z","end":"2026-03-09T01:39:08.566033Z","steps":["trace[1785791231] 'agreement among raft nodes before linearized reading'  (duration: 128.221149ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:08.566147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.954977ms","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-03-09T01:39:08.566225Z","caller":"traceutil/trace.go:171","msg":"trace[159469798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"129.078701ms","start":"2026-03-09T01:39:08.437123Z","end":"2026-03-09T01:39:08.566202Z","steps":["trace[159469798] 'agreement among raft nodes before linearized reading'  (duration: 128.858135ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:08.672907Z","caller":"traceutil/trace.go:171","msg":"trace[1266697126] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"101.958038ms","start":"2026-03-09T01:39:08.570933Z","end":"2026-03-09T01:39:08.672891Z","steps":["trace[1266697126] 'process raft request'  (duration: 101.933928ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:08.673046Z","caller":"traceutil/trace.go:171","msg":"trace[1038040083] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"102.549573ms","start":"2026-03-09T01:39:08.570449Z","end":"2026-03-09T01:39:08.672999Z","steps":["trace[1038040083] 'process raft request'  (duration: 71.093943ms)","trace[1038040083] 'compare'  (duration: 31.212374ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:39:08.673113Z","caller":"traceutil/trace.go:171","msg":"trace[1420628772] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"102.332238ms","start":"2026-03-09T01:39:08.570774Z","end":"2026-03-09T01:39:08.673107Z","steps":["trace[1420628772] 'process raft request'  (duration: 102.065751ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:32.952971Z","caller":"traceutil/trace.go:171","msg":"trace[2036932926] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"102.809924ms","start":"2026-03-09T01:39:32.850139Z","end":"2026-03-09T01:39:32.952949Z","steps":["trace[2036932926] 'read index received'  (duration: 102.64631ms)","trace[2036932926] 'applied index is now lower than readState.Index'  (duration: 162.564µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:39:32.953099Z","caller":"traceutil/trace.go:171","msg":"trace[655955017] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"105.425069ms","start":"2026-03-09T01:39:32.847664Z","end":"2026-03-09T01:39:32.953089Z","steps":["trace[655955017] 'process raft request'  (duration: 105.150003ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:32.953316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.164192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:39:32.953369Z","caller":"traceutil/trace.go:171","msg":"trace[1230003896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"103.266895ms","start":"2026-03-09T01:39:32.850088Z","end":"2026-03-09T01:39:32.953355Z","steps":["trace[1230003896] 'agreement among raft nodes before linearized reading'  (duration: 103.126091ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:32.953659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.121101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-09T01:39:32.953742Z","caller":"traceutil/trace.go:171","msg":"trace[1828021105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"101.238994ms","start":"2026-03-09T01:39:32.852482Z","end":"2026-03-09T01:39:32.953721Z","steps":["trace[1828021105] 'agreement among raft nodes before linearized reading'  (duration: 101.002198ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:33.908311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.585675ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747810850395473839 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:758 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:39:33.908554Z","caller":"traceutil/trace.go:171","msg":"trace[213766617] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"951.276007ms","start":"2026-03-09T01:39:32.957252Z","end":"2026-03-09T01:39:33.908528Z","steps":["trace[213766617] 'process raft request'  (duration: 372.852961ms)","trace[213766617] 'compare'  (duration: 577.293248ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:39:33.908672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:32.957226Z","time spent":"951.38696ms","remote":"127.0.0.1:59496","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:758 > 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-03-09T01:39:33.908772Z","caller":"traceutil/trace.go:171","msg":"trace[52715479] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:793; }","duration":"251.972956ms","start":"2026-03-09T01:39:33.656781Z","end":"2026-03-09T01:39:33.908753Z","steps":["trace[52715479] 'read index received'  (duration: 56.071µs)","trace[52715479] 'applied index is now lower than readState.Index'  (duration: 251.915525ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:39:33.908864Z","caller":"traceutil/trace.go:171","msg":"trace[75260844] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"951.106712ms","start":"2026-03-09T01:39:32.957724Z","end":"2026-03-09T01:39:33.908831Z","steps":["trace[75260844] 'process raft request'  (duration: 950.794164ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:33.909039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:32.957701Z","time spent":"951.248526ms","remote":"127.0.0.1:59496","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:759 > 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-03-09T01:39:33.90903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.244783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:39:33.909142Z","caller":"traceutil/trace.go:171","msg":"trace[316323012] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"252.386806ms","start":"2026-03-09T01:39:33.656744Z","end":"2026-03-09T01:39:33.90913Z","steps":["trace[316323012] 'agreement among raft nodes before linearized reading'  (duration: 252.12071ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:35.191265Z","caller":"traceutil/trace.go:171","msg":"trace[863317248] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"476.096735ms","start":"2026-03-09T01:39:34.715143Z","end":"2026-03-09T01:39:35.19124Z","steps":["trace[863317248] 'process raft request'  (duration: 475.951142ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:35.19146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:34.715124Z","time spent":"476.25261ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:762 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-09T01:39:35.191511Z","caller":"traceutil/trace.go:171","msg":"trace[2049701598] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:797; }","duration":"231.946095ms","start":"2026-03-09T01:39:34.959537Z","end":"2026-03-09T01:39:35.191483Z","steps":["trace[2049701598] 'read index received'  (duration: 231.938045ms)","trace[2049701598] 'applied index is now lower than readState.Index'  (duration: 6.51µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:39:35.191791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.226183ms","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-03-09T01:39:35.191877Z","caller":"traceutil/trace.go:171","msg":"trace[2117960583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:767; }","duration":"232.372546ms","start":"2026-03-09T01:39:34.959485Z","end":"2026-03-09T01:39:35.191858Z","steps":["trace[2117960583] 'agreement among raft nodes before linearized reading'  (duration: 232.090759ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:35.309871Z","caller":"traceutil/trace.go:171","msg":"trace[1777657775] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"112.100335ms","start":"2026-03-09T01:39:35.197753Z","end":"2026-03-09T01:39:35.309853Z","steps":["trace[1777657775] 'process raft request'  (duration: 105.855389ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:36.144866Z","caller":"traceutil/trace.go:171","msg":"trace[915417319] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"226.762584ms","start":"2026-03-09T01:39:35.918088Z","end":"2026-03-09T01:39:36.14485Z","steps":["trace[915417319] 'process raft request'  (duration: 226.645201ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:36.150505Z","caller":"traceutil/trace.go:171","msg":"trace[1454563115] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"231.182495ms","start":"2026-03-09T01:39:35.919306Z","end":"2026-03-09T01:39:36.150489Z","steps":["trace[1454563115] 'process raft request'  (duration: 231.088423ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:39:41.389753Z","caller":"traceutil/trace.go:171","msg":"trace[1954343161] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"142.549218ms","start":"2026-03-09T01:39:41.247002Z","end":"2026-03-09T01:39:41.389551Z","steps":["trace[1954343161] 'process raft request'  (duration: 113.109331ms)","trace[1954343161] 'compare'  (duration: 29.298384ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:39:58.009058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810850395474119,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-09T01:39:58.509651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810850395474119,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-03-09T01:39:58.882659Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.420454329s","expected-duration":"1s"}
{"level":"info","ts":"2026-03-09T01:39:58.882969Z","caller":"traceutil/trace.go:171","msg":"trace[1316242362] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:916; }","duration":"1.374796658s","start":"2026-03-09T01:39:57.508132Z","end":"2026-03-09T01:39:58.882929Z","steps":["trace[1316242362] 'read index received'  (duration: 1.374632864s)","trace[1316242362] 'applied index is now lower than readState.Index'  (duration: 163.044µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:39:58.883263Z","caller":"traceutil/trace.go:171","msg":"trace[959746944] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"1.421201248s","start":"2026-03-09T01:39:57.462052Z","end":"2026-03-09T01:39:58.883254Z","steps":["trace[959746944] 'process raft request'  (duration: 1.420780217s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.883353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:57.462022Z","time spent":"1.42127059s","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:874 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-03-09T01:39:58.883547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375419203s","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-03-09T01:39:58.883573Z","caller":"traceutil/trace.go:171","msg":"trace[1951530213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"1.375481434s","start":"2026-03-09T01:39:57.508084Z","end":"2026-03-09T01:39:58.883566Z","steps":["trace[1951530213] 'agreement among raft nodes before linearized reading'  (duration: 1.375393582s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.88362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:57.508065Z","time spent":"1.375551437s","remote":"127.0.0.1:59496","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-03-09T01:39:58.88371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.228335121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:39:58.883771Z","caller":"traceutil/trace.go:171","msg":"trace[18672640] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"1.228442375s","start":"2026-03-09T01:39:57.655314Z","end":"2026-03-09T01:39:58.883756Z","steps":["trace[18672640] 'agreement among raft nodes before linearized reading'  (duration: 1.228317911s)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.883815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:57.655298Z","time spent":"1.228507816s","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-03-09T01:39:58.883964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.987827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:39:58.883997Z","caller":"traceutil/trace.go:171","msg":"trace[311676587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"604.058839ms","start":"2026-03-09T01:39:58.279931Z","end":"2026-03-09T01:39:58.883989Z","steps":["trace[311676587] 'agreement among raft nodes before linearized reading'  (duration: 603.985507ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.884016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:58.27991Z","time spent":"604.102179ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-09T01:39:58.884065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.593901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-09T01:39:58.884113Z","caller":"traceutil/trace.go:171","msg":"trace[502557435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"610.670683ms","start":"2026-03-09T01:39:58.273423Z","end":"2026-03-09T01:39:58.884093Z","steps":["trace[502557435] 'agreement among raft nodes before linearized reading'  (duration: 610.561491ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.884132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.677668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-03-09T01:39:58.884139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:58.273403Z","time spent":"610.729235ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-03-09T01:39:58.884162Z","caller":"traceutil/trace.go:171","msg":"trace[1995654627] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"410.7458ms","start":"2026-03-09T01:39:58.473409Z","end":"2026-03-09T01:39:58.884155Z","steps":["trace[1995654627] 'agreement among raft nodes before linearized reading'  (duration: 410.681648ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:39:58.884185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:58.473387Z","time spent":"410.792671ms","remote":"127.0.0.1:59496","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-03-09T01:40:11.167783Z","caller":"traceutil/trace.go:171","msg":"trace[2096822173] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"100.916086ms","start":"2026-03-09T01:40:11.06684Z","end":"2026-03-09T01:40:11.167756Z","steps":["trace[2096822173] 'process raft request'  (duration: 100.69246ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:11.16895Z","caller":"traceutil/trace.go:171","msg":"trace[18289038] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"101.556581ms","start":"2026-03-09T01:40:11.067357Z","end":"2026-03-09T01:40:11.168914Z","steps":["trace[18289038] 'process raft request'  (duration: 101.268314ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:14.521705Z","caller":"traceutil/trace.go:171","msg":"trace[1752056834] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"209.060453ms","start":"2026-03-09T01:40:14.31262Z","end":"2026-03-09T01:40:14.521681Z","steps":["trace[1752056834] 'process raft request'  (duration: 208.920299ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:14.704859Z","caller":"traceutil/trace.go:171","msg":"trace[1085574632] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"176.780334ms","start":"2026-03-09T01:40:14.528049Z","end":"2026-03-09T01:40:14.704829Z","steps":["trace[1085574632] 'process raft request'  (duration: 116.715591ms)","trace[1085574632] 'compare'  (duration: 59.919669ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:14.70684Z","caller":"traceutil/trace.go:171","msg":"trace[1859029910] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"178.317223ms","start":"2026-03-09T01:40:14.528499Z","end":"2026-03-09T01:40:14.706816Z","steps":["trace[1859029910] 'process raft request'  (duration: 178.066347ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:14.70731Z","caller":"traceutil/trace.go:171","msg":"trace[891278511] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"172.214769ms","start":"2026-03-09T01:40:14.535067Z","end":"2026-03-09T01:40:14.707281Z","steps":["trace[891278511] 'process raft request'  (duration: 171.666516ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:15.257445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.955703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:15.257513Z","caller":"traceutil/trace.go:171","msg":"trace[191757845] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:944; }","duration":"140.068345ms","start":"2026-03-09T01:40:15.117428Z","end":"2026-03-09T01:40:15.257496Z","steps":["trace[191757845] 'count revisions from in-memory index tree'  (duration: 139.882541ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:15.257935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.823516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"}
{"level":"info","ts":"2026-03-09T01:40:15.25796Z","caller":"traceutil/trace.go:171","msg":"trace[1937846973] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"180.881757ms","start":"2026-03-09T01:40:15.07707Z","end":"2026-03-09T01:40:15.257952Z","steps":["trace[1937846973] 'range keys from in-memory index tree'  (duration: 180.709733ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:19.415534Z","caller":"traceutil/trace.go:171","msg":"trace[501506969] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"105.4672ms","start":"2026-03-09T01:40:19.310044Z","end":"2026-03-09T01:40:19.415511Z","steps":["trace[501506969] 'process raft request'  (duration: 105.129181ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:19.835613Z","caller":"traceutil/trace.go:171","msg":"trace[140592266] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"523.629205ms","start":"2026-03-09T01:40:19.311958Z","end":"2026-03-09T01:40:19.835588Z","steps":["trace[140592266] 'process raft request'  (duration: 523.41963ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:19.835777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:19.311945Z","time spent":"523.749438ms","remote":"127.0.0.1:59496","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:969 > 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-03-09T01:40:19.836082Z","caller":"traceutil/trace.go:171","msg":"trace[1465777649] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"523.42582ms","start":"2026-03-09T01:40:19.312616Z","end":"2026-03-09T01:40:19.836042Z","steps":["trace[1465777649] 'process raft request'  (duration: 522.926468ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:19.836352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:19.31261Z","time spent":"523.624895ms","remote":"127.0.0.1:59496","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:966 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-03-09T01:40:19.836732Z","caller":"traceutil/trace.go:171","msg":"trace[460650669] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"521.591754ms","start":"2026-03-09T01:40:19.315128Z","end":"2026-03-09T01:40:19.83672Z","steps":["trace[460650669] 'process raft request'  (duration: 520.809365ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:19.83685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:19.315112Z","time spent":"521.698326ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:968 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-09T01:40:19.837011Z","caller":"traceutil/trace.go:171","msg":"trace[905962230] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1019; }","duration":"212.738404ms","start":"2026-03-09T01:40:19.624252Z","end":"2026-03-09T01:40:19.836991Z","steps":["trace[905962230] 'read index received'  (duration: 211.075563ms)","trace[905962230] 'applied index is now lower than readState.Index'  (duration: 1.659111ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:19.837148Z","caller":"traceutil/trace.go:171","msg":"trace[173602341] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"521.736407ms","start":"2026-03-09T01:40:19.315401Z","end":"2026-03-09T01:40:19.837137Z","steps":["trace[173602341] 'process raft request'  (duration: 521.284386ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:19.83723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:19.31539Z","time spent":"521.792688ms","remote":"127.0.0.1:59496","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:967 > 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-03-09T01:40:19.837357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.111073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:19.837415Z","caller":"traceutil/trace.go:171","msg":"trace[1508876315] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:982; }","duration":"213.220176ms","start":"2026-03-09T01:40:19.624183Z","end":"2026-03-09T01:40:19.837403Z","steps":["trace[1508876315] 'agreement among raft nodes before linearized reading'  (duration: 213.136604ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:19.83754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.100962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:19.837601Z","caller":"traceutil/trace.go:171","msg":"trace[178191819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:982; }","duration":"183.212115ms","start":"2026-03-09T01:40:19.654373Z","end":"2026-03-09T01:40:19.837585Z","steps":["trace[178191819] 'agreement among raft nodes before linearized reading'  (duration: 183.114023ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:20.852152Z","caller":"traceutil/trace.go:171","msg":"trace[1276027201] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"195.706127ms","start":"2026-03-09T01:40:20.656419Z","end":"2026-03-09T01:40:20.852125Z","steps":["trace[1276027201] 'read index received'  (duration: 195.48237ms)","trace[1276027201] 'applied index is now lower than readState.Index'  (duration: 222.617µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:20.852281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.85337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:20.852193Z","caller":"traceutil/trace.go:171","msg":"trace[464833226] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"438.056152ms","start":"2026-03-09T01:40:20.414108Z","end":"2026-03-09T01:40:20.852164Z","steps":["trace[464833226] 'process raft request'  (duration: 437.855036ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:20.85233Z","caller":"traceutil/trace.go:171","msg":"trace[1066593278] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"195.943492ms","start":"2026-03-09T01:40:20.656373Z","end":"2026-03-09T01:40:20.852317Z","steps":["trace[1066593278] 'agreement among raft nodes before linearized reading'  (duration: 195.85893ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:20.852395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:20.414085Z","time spent":"438.245406ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" mod_revision:956 > success:<request_put:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" value_size:406 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" > >"}
{"level":"warn","ts":"2026-03-09T01:40:21.65636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.453987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-03-09T01:40:21.656441Z","caller":"traceutil/trace.go:171","msg":"trace[791902941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"236.532798ms","start":"2026-03-09T01:40:21.419875Z","end":"2026-03-09T01:40:21.656408Z","steps":["trace[791902941] 'range keys from in-memory index tree'  (duration: 236.241881ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.656492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.488983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"}
{"level":"warn","ts":"2026-03-09T01:40:21.65655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.764017ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:21.656599Z","caller":"traceutil/trace.go:171","msg":"trace[1956350341] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:984; }","duration":"528.689138ms","start":"2026-03-09T01:40:21.127884Z","end":"2026-03-09T01:40:21.656573Z","steps":["trace[1956350341] 'range keys from in-memory index tree'  (duration: 528.233806ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:21.656633Z","caller":"traceutil/trace.go:171","msg":"trace[26441354] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:984; }","duration":"426.85821ms","start":"2026-03-09T01:40:21.229762Z","end":"2026-03-09T01:40:21.65662Z","steps":["trace[26441354] 'range keys from in-memory index tree'  (duration: 426.752647ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.656647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:21.127866Z","time spent":"528.770659ms","remote":"127.0.0.1:59242","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":"info","ts":"2026-03-09T01:40:21.798408Z","caller":"traceutil/trace.go:171","msg":"trace[1825396965] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"142.887854ms","start":"2026-03-09T01:40:21.655491Z","end":"2026-03-09T01:40:21.798379Z","steps":["trace[1825396965] 'process raft request'  (duration: 142.428203ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.798765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.7227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-03-09T01:40:21.79884Z","caller":"traceutil/trace.go:171","msg":"trace[1682096915] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:985; }","duration":"142.829743ms","start":"2026-03-09T01:40:21.655992Z","end":"2026-03-09T01:40:21.798822Z","steps":["trace[1682096915] 'agreement among raft nodes before linearized reading'  (duration: 142.645229ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:21.798593Z","caller":"traceutil/trace.go:171","msg":"trace[1490921304] linearizableReadLoop","detail":"{readStateIndex:1026; appliedIndex:1025; }","duration":"142.278949ms","start":"2026-03-09T01:40:21.656017Z","end":"2026-03-09T01:40:21.798296Z","steps":["trace[1490921304] 'read index received'  (duration: 141.989942ms)","trace[1490921304] 'applied index is now lower than readState.Index'  (duration: 288.037µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:21.835486Z","caller":"traceutil/trace.go:171","msg":"trace[1386071011] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"175.201824ms","start":"2026-03-09T01:40:21.66026Z","end":"2026-03-09T01:40:21.835462Z","steps":["trace[1386071011] 'process raft request'  (duration: 175.07879ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.996902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.807707ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747810850395474351 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" mod_revision:963 > success:<request_put:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" value_size:424 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:40:21.99717Z","caller":"traceutil/trace.go:171","msg":"trace[1189171483] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"193.706546ms","start":"2026-03-09T01:40:21.80343Z","end":"2026-03-09T01:40:21.997136Z","steps":["trace[1189171483] 'process raft request'  (duration: 69.500449ms)","trace[1189171483] 'compare'  (duration: 123.679394ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:21.997457Z","caller":"traceutil/trace.go:171","msg":"trace[193265518] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1028; }","duration":"157.537431ms","start":"2026-03-09T01:40:21.8399Z","end":"2026-03-09T01:40:21.997438Z","steps":["trace[193265518] 'read index received'  (duration: 33.120359ms)","trace[193265518] 'applied index is now lower than readState.Index'  (duration: 124.415262ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:21.997599Z","caller":"traceutil/trace.go:171","msg":"trace[323226625] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"160.763881ms","start":"2026-03-09T01:40:21.83679Z","end":"2026-03-09T01:40:21.997554Z","steps":["trace[323226625] 'process raft request'  (duration: 160.226808ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.997692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.759656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-03-09T01:40:21.997692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.948796ms","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-03-09T01:40:21.997727Z","caller":"traceutil/trace.go:171","msg":"trace[1740425205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:989; }","duration":"155.834038ms","start":"2026-03-09T01:40:21.841882Z","end":"2026-03-09T01:40:21.997716Z","steps":["trace[1740425205] 'agreement among raft nodes before linearized reading'  (duration: 155.718215ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:21.997731Z","caller":"traceutil/trace.go:171","msg":"trace[2077137099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:989; }","duration":"157.012438ms","start":"2026-03-09T01:40:21.840709Z","end":"2026-03-09T01:40:21.997722Z","steps":["trace[2077137099] 'agreement among raft nodes before linearized reading'  (duration: 156.851764ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.997757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.469559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-09T01:40:21.997811Z","caller":"traceutil/trace.go:171","msg":"trace[1115356486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:989; }","duration":"155.543321ms","start":"2026-03-09T01:40:21.842251Z","end":"2026-03-09T01:40:21.997795Z","steps":["trace[1115356486] 'agreement among raft nodes before linearized reading'  (duration: 155.341196ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:21.997841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.929821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:40:21.998111Z","caller":"traceutil/trace.go:171","msg":"trace[264391414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:989; }","duration":"158.25071ms","start":"2026-03-09T01:40:21.839842Z","end":"2026-03-09T01:40:21.998093Z","steps":["trace[264391414] 'agreement among raft nodes before linearized reading'  (duration: 157.694725ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:22.292113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.010539ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747810850395474364 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-ss465.189b08ae157cea61\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-ss465.189b08ae157cea61\" value_size:677 lease:4747810850395473992 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:40:22.292238Z","caller":"traceutil/trace.go:171","msg":"trace[1780752000] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"210.919577ms","start":"2026-03-09T01:40:22.081307Z","end":"2026-03-09T01:40:22.292227Z","steps":["trace[1780752000] 'process raft request'  (duration: 210.884676ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:22.292356Z","caller":"traceutil/trace.go:171","msg":"trace[1339227980] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"211.225795ms","start":"2026-03-09T01:40:22.081084Z","end":"2026-03-09T01:40:22.29231Z","steps":["trace[1339227980] 'process raft request'  (duration: 21.96625ms)","trace[1339227980] 'compare'  (duration: 188.847054ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:23.009825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.598341ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747810850395474368 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/external-attacher-leader-rbd-csi-ceph-com.189b08ae2256942e\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/external-attacher-leader-rbd-csi-ceph-com.189b08ae2256942e\" value_size:805 lease:4747810850395473992 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:40:23.010071Z","caller":"traceutil/trace.go:171","msg":"trace[275712533] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"353.862084ms","start":"2026-03-09T01:40:22.656192Z","end":"2026-03-09T01:40:23.010054Z","steps":["trace[275712533] 'read index received'  (duration: 60.003µs)","trace[275712533] 'applied index is now lower than readState.Index'  (duration: 353.801141ms)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:23.01017Z","caller":"traceutil/trace.go:171","msg":"trace[204468460] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"713.704926ms","start":"2026-03-09T01:40:22.29645Z","end":"2026-03-09T01:40:23.010155Z","steps":["trace[204468460] 'process raft request'  (duration: 97.703865ms)","trace[204468460] 'compare'  (duration: 615.314054ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:23.01023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:22.29637Z","time spent":"713.831558ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":910,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/external-attacher-leader-rbd-csi-ceph-com.189b08ae2256942e\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/external-attacher-leader-rbd-csi-ceph-com.189b08ae2256942e\" value_size:805 lease:4747810850395473992 >> failure:<>"}
{"level":"info","ts":"2026-03-09T01:40:23.010284Z","caller":"traceutil/trace.go:171","msg":"trace[694722056] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"710.385433ms","start":"2026-03-09T01:40:22.299881Z","end":"2026-03-09T01:40:23.010267Z","steps":["trace[694722056] 'process raft request'  (duration: 710.050215ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:23.010265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.058318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:23.01035Z","caller":"traceutil/trace.go:171","msg":"trace[976354911] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:998; }","duration":"354.187161ms","start":"2026-03-09T01:40:22.656143Z","end":"2026-03-09T01:40:23.01033Z","steps":["trace[976354911] 'agreement among raft nodes before linearized reading'  (duration: 354.031188ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:23.010365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:22.299866Z","time spent":"710.456134ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" mod_revision:995 > success:<request_put:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" value_size:433 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" > >"}
{"level":"warn","ts":"2026-03-09T01:40:23.010406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:22.656124Z","time spent":"354.274054ms","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-09T01:40:23.700214Z","caller":"traceutil/trace.go:171","msg":"trace[1234895705] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"182.967657ms","start":"2026-03-09T01:40:23.517222Z","end":"2026-03-09T01:40:23.700189Z","steps":["trace[1234895705] 'read index received'  (duration: 182.846074ms)","trace[1234895705] 'applied index is now lower than readState.Index'  (duration: 120.413µs)"],"step_count":2}
{"level":"info","ts":"2026-03-09T01:40:23.700244Z","caller":"traceutil/trace.go:171","msg":"trace[1314741646] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"304.093478ms","start":"2026-03-09T01:40:23.396119Z","end":"2026-03-09T01:40:23.700213Z","steps":["trace[1314741646] 'process raft request'  (duration: 303.890703ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:23.700372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:23.3961Z","time spent":"304.21107ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":805,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-ss465.189b08ae63dbe298\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-ss465.189b08ae63dbe298\" value_size:701 lease:4747810850395473992 >> failure:<>"}
{"level":"warn","ts":"2026-03-09T01:40:23.700465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.189152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:23.700549Z","caller":"traceutil/trace.go:171","msg":"trace[2143492988] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:0; response_revision:1000; }","duration":"183.342196ms","start":"2026-03-09T01:40:23.517186Z","end":"2026-03-09T01:40:23.700528Z","steps":["trace[2143492988] 'agreement among raft nodes before linearized reading'  (duration: 183.130191ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.340354Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810850395474376,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-09T01:40:24.409729Z","caller":"traceutil/trace.go:171","msg":"trace[1424028536] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1042; }","duration":"570.113413ms","start":"2026-03-09T01:40:23.83959Z","end":"2026-03-09T01:40:24.409703Z","steps":["trace[1424028536] 'read index received'  (duration: 568.113043ms)","trace[1424028536] 'applied index is now lower than readState.Index'  (duration: 1.99902ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:24.410262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.229407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-03-09T01:40:24.41035Z","caller":"traceutil/trace.go:171","msg":"trace[307755290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1001; }","duration":"341.3606ms","start":"2026-03-09T01:40:24.068963Z","end":"2026-03-09T01:40:24.410323Z","steps":["trace[307755290] 'agreement among raft nodes before linearized reading'  (duration: 340.93922ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.410403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.068943Z","time spent":"341.448213ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-09T01:40:24.410452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.068833ms","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-03-09T01:40:24.4105Z","caller":"traceutil/trace.go:171","msg":"trace[1389799119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1001; }","duration":"329.155895ms","start":"2026-03-09T01:40:24.081331Z","end":"2026-03-09T01:40:24.410487Z","steps":["trace[1389799119] 'agreement among raft nodes before linearized reading'  (duration: 328.96115ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.410534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.081318Z","time spent":"329.208537ms","remote":"127.0.0.1:59496","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-03-09T01:40:24.410633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.35754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-03-09T01:40:24.410686Z","caller":"traceutil/trace.go:171","msg":"trace[809418085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1001; }","duration":"327.466133ms","start":"2026-03-09T01:40:24.083208Z","end":"2026-03-09T01:40:24.410674Z","steps":["trace[809418085] 'agreement among raft nodes before linearized reading'  (duration: 327.295198ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.410713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.083185Z","time spent":"327.521884ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-03-09T01:40:24.410829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.140724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-09T01:40:24.410891Z","caller":"traceutil/trace.go:171","msg":"trace[2072718470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1001; }","duration":"331.241726ms","start":"2026-03-09T01:40:24.079632Z","end":"2026-03-09T01:40:24.410874Z","steps":["trace[2072718470] 'agreement among raft nodes before linearized reading'  (duration: 331.061272ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:24.411048Z","caller":"traceutil/trace.go:171","msg":"trace[281450899] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"704.244568ms","start":"2026-03-09T01:40:23.705558Z","end":"2026-03-09T01:40:24.409803Z","steps":["trace[281450899] 'process raft request'  (duration: 702.154527ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.411169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.582639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-03-09T01:40:24.411177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:23.705526Z","time spent":"705.596193ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:0 > success:<request_put:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" value_size:452 >> failure:<>"}
{"level":"info","ts":"2026-03-09T01:40:24.411215Z","caller":"traceutil/trace.go:171","msg":"trace[46821749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1001; }","duration":"571.63151ms","start":"2026-03-09T01:40:23.839571Z","end":"2026-03-09T01:40:24.411203Z","steps":["trace[46821749] 'agreement among raft nodes before linearized reading'  (duration: 570.268406ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:24.411247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:23.839467Z","time spent":"571.772174ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-09T01:40:24.411289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.079612Z","time spent":"331.655957ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-09T01:40:25.324093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.044976ms","expected-duration":"100ms","prefix":"","request":"header:<ID:4747810850395474381 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:992 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-09T01:40:25.324364Z","caller":"traceutil/trace.go:171","msg":"trace[480407120] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"908.653653ms","start":"2026-03-09T01:40:24.415687Z","end":"2026-03-09T01:40:25.324341Z","steps":["trace[480407120] 'process raft request'  (duration: 908.52244ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:25.324496Z","caller":"traceutil/trace.go:171","msg":"trace[681509513] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"908.839078ms","start":"2026-03-09T01:40:24.41554Z","end":"2026-03-09T01:40:25.324379Z","steps":["trace[681509513] 'process raft request'  (duration: 688.401192ms)","trace[681509513] 'compare'  (duration: 219.903363ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:25.324516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.415682Z","time spent":"908.783476ms","remote":"127.0.0.1:59496","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:986 > 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-03-09T01:40:25.324672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.415523Z","time spent":"909.039172ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:992 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:409 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-03-09T01:40:25.324749Z","caller":"traceutil/trace.go:171","msg":"trace[275515049] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"908.662743ms","start":"2026-03-09T01:40:24.416065Z","end":"2026-03-09T01:40:25.324728Z","steps":["trace[275515049] 'process raft request'  (duration: 908.573781ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:25.324871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.416059Z","time spent":"908.750865ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:990 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-03-09T01:40:25.324866Z","caller":"traceutil/trace.go:171","msg":"trace[2059616248] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"908.95256ms","start":"2026-03-09T01:40:24.415837Z","end":"2026-03-09T01:40:25.32479Z","steps":["trace[2059616248] 'process raft request'  (duration: 908.465938ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:25.325059Z","caller":"traceutil/trace.go:171","msg":"trace[569097340] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"908.747685ms","start":"2026-03-09T01:40:24.416298Z","end":"2026-03-09T01:40:25.325045Z","steps":["trace[569097340] 'process raft request'  (duration: 908.395367ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:25.325019Z","caller":"traceutil/trace.go:171","msg":"trace[1823888069] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"909.34272ms","start":"2026-03-09T01:40:24.415651Z","end":"2026-03-09T01:40:25.324994Z","steps":["trace[1823888069] 'read index received'  (duration: 38.424631ms)","trace[1823888069] 'applied index is now lower than readState.Index'  (duration: 870.913759ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-09T01:40:25.32511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.416292Z","time spent":"908.796426ms","remote":"127.0.0.1:59280","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/rbd.csi.ceph.com-kube-system.189b08aea0b18f0a\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/rbd.csi.ceph.com-kube-system.189b08aea0b18f0a\" value_size:801 lease:4747810850395473992 >> failure:<>"}
{"level":"warn","ts":"2026-03-09T01:40:25.325209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.415829Z","time spent":"909.236317ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:991 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:429 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-03-09T01:40:25.325312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.649837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-03-09T01:40:25.325372Z","caller":"traceutil/trace.go:171","msg":"trace[568808410] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1007; }","duration":"909.76415ms","start":"2026-03-09T01:40:24.415592Z","end":"2026-03-09T01:40:25.325356Z","steps":["trace[568808410] 'agreement among raft nodes before linearized reading'  (duration: 909.576845ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:40:25.325408Z","caller":"traceutil/trace.go:171","msg":"trace[1802168050] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"277.085843ms","start":"2026-03-09T01:40:25.048304Z","end":"2026-03-09T01:40:25.32539Z","steps":["trace[1802168050] 'process raft request'  (duration: 277.046852ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:25.325423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.415586Z","time spent":"909.825252ms","remote":"127.0.0.1:59496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"warn","ts":"2026-03-09T01:40:25.326469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.796188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-09T01:40:25.326525Z","caller":"traceutil/trace.go:171","msg":"trace[73455110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"665.891729ms","start":"2026-03-09T01:40:24.660621Z","end":"2026-03-09T01:40:25.326513Z","steps":["trace[73455110] 'agreement among raft nodes before linearized reading'  (duration: 665.766506ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-09T01:40:25.326983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:40:24.660592Z","time spent":"666.372001ms","remote":"127.0.0.1:59210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-03-09T01:41:39.898889Z","caller":"traceutil/trace.go:171","msg":"trace[1005216308] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"144.279533ms","start":"2026-03-09T01:41:39.754582Z","end":"2026-03-09T01:41:39.898862Z","steps":["trace[1005216308] 'process raft request'  (duration: 144.039846ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:41:58.118062Z","caller":"traceutil/trace.go:171","msg":"trace[1827042706] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"106.871611ms","start":"2026-03-09T01:41:58.011161Z","end":"2026-03-09T01:41:58.118032Z","steps":["trace[1827042706] 'process raft request'  (duration: 106.527333ms)"],"step_count":1}
{"level":"info","ts":"2026-03-09T01:41:58.118228Z","caller":"traceutil/trace.go:171","msg":"trace[996540402] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"106.778279ms","start":"2026-03-09T01:41:58.011425Z","end":"2026-03-09T01:41:58.118203Z","steps":["trace[996540402] 'process raft request'  (duration: 106.511193ms)"],"step_count":1}