{"level":"warn","ts":"2026-01-29T18:45:56.471641Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-29T18:45:56.475959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.33: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://199.204.45.33:2380","--initial-cluster=instance=https://199.204.45.33:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.33:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.33:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-01-29T18:45:56.477752Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-01-29T18:45:56.478746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.33:2380"]}
{"level":"info","ts":"2026-01-29T18:45:56.478802Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-01-29T18:45:56.483318Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"]}
{"level":"info","ts":"2026-01-29T18:45:56.485312Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.33:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-01-29T18:45:56.502532Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.367356ms"}
{"level":"info","ts":"2026-01-29T18:45:56.51865Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f1e5729930cf7e1f","cluster-id":"14aeb8c3ec66b749"}
{"level":"info","ts":"2026-01-29T18:45:56.518789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=()"}
{"level":"info","ts":"2026-01-29T18:45:56.518836Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 0"}
{"level":"info","ts":"2026-01-29T18:45:56.518872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f1e5729930cf7e1f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-01-29T18:45:56.518899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became follower at term 1"}
{"level":"info","ts":"2026-01-29T18:45:56.518969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"}
{"level":"warn","ts":"2026-01-29T18:45:56.528528Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-01-29T18:45:56.534646Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-01-29T18:45:56.53669Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-01-29T18:45:56.538945Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f1e5729930cf7e1f","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-01-29T18:45:56.541489Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-01-29T18:45:56.541736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f1e5729930cf7e1f","initial-advertise-peer-urls":["https://199.204.45.33:2380"],"listen-peer-urls":["https://199.204.45.33:2380"],"advertise-client-urls":["https://199.204.45.33:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.33:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-01-29T18:45:56.541767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-01-29T18:45:56.541991Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f1e5729930cf7e1f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-01-29T18:45:56.542194Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-01-29T18:45:56.542225Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-01-29T18:45:56.542234Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-01-29T18:45:56.543167Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.33:2380"}
{"level":"info","ts":"2026-01-29T18:45:56.54319Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.33:2380"}
{"level":"info","ts":"2026-01-29T18:45:56.54344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f switched to configuration voters=(17430463935081840159)"}
{"level":"info","ts":"2026-01-29T18:45:56.543558Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","added-peer-id":"f1e5729930cf7e1f","added-peer-peer-urls":["https://199.204.45.33:2380"]}
{"level":"info","ts":"2026-01-29T18:45:57.219558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f is starting a new election at term 1"}
{"level":"info","ts":"2026-01-29T18:45:57.219716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became pre-candidate at term 1"}
{"level":"info","ts":"2026-01-29T18:45:57.219772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgPreVoteResp from f1e5729930cf7e1f at term 1"}
{"level":"info","ts":"2026-01-29T18:45:57.219809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became candidate at term 2"}
{"level":"info","ts":"2026-01-29T18:45:57.219856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f received MsgVoteResp from f1e5729930cf7e1f at term 2"}
{"level":"info","ts":"2026-01-29T18:45:57.219889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f1e5729930cf7e1f became leader at term 2"}
{"level":"info","ts":"2026-01-29T18:45:57.219932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f1e5729930cf7e1f elected leader f1e5729930cf7e1f at term 2"}
{"level":"info","ts":"2026-01-29T18:45:57.221811Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f1e5729930cf7e1f","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.33:2379]}","request-path":"/0/members/f1e5729930cf7e1f/attributes","cluster-id":"14aeb8c3ec66b749","publish-timeout":"7s"}
{"level":"info","ts":"2026-01-29T18:45:57.229518Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-29T18:45:57.229875Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-29T18:45:57.230051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-29T18:45:57.235174Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-01-29T18:45:57.235191Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-01-29T18:45:57.236381Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-01-29T18:45:57.243204Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.33:2379"}
{"level":"info","ts":"2026-01-29T18:45:57.243264Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"14aeb8c3ec66b749","local-member-id":"f1e5729930cf7e1f","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-29T18:45:57.243329Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-29T18:45:57.243348Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-29T18:46:07.803593Z","caller":"traceutil/trace.go:171","msg":"trace[1085188762] linearizableReadLoop","detail":"{readStateIndex:326; appliedIndex:325; }","duration":"397.491069ms","start":"2026-01-29T18:46:07.40608Z","end":"2026-01-29T18:46:07.803571Z","steps":["trace[1085188762] 'read index received'  (duration: 397.313666ms)","trace[1085188762] 'applied index is now lower than readState.Index'  (duration: 176.423µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:07.80449Z","caller":"traceutil/trace.go:171","msg":"trace[2085969822] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"428.113593ms","start":"2026-01-29T18:46:07.376345Z","end":"2026-01-29T18:46:07.804458Z","steps":["trace[2085969822] 'process raft request'  (duration: 427.113765ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.805028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.761493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:46:07.806411Z","caller":"traceutil/trace.go:171","msg":"trace[1265474329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:317; }","duration":"400.352801ms","start":"2026-01-29T18:46:07.40604Z","end":"2026-01-29T18:46:07.806393Z","steps":["trace[1265474329] 'agreement among raft nodes before linearized reading'  (duration: 398.423055ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.806603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.406023Z","time spent":"400.524825ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:46:07.807034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.565327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"}
{"level":"info","ts":"2026-01-29T18:46:07.807302Z","caller":"traceutil/trace.go:171","msg":"trace[87856148] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:317; }","duration":"392.841703ms","start":"2026-01-29T18:46:07.414447Z","end":"2026-01-29T18:46:07.807289Z","steps":["trace[87856148] 'agreement among raft nodes before linearized reading'  (duration: 392.548148ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.807641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.376315Z","time spent":"428.283146ms","remote":"127.0.0.1:41422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/configmaps/kube-public/cluster-info\" mod_revision:236 > success:<request_put:<key:\"/registry/configmaps/kube-public/cluster-info\" value_size:2109 >> failure:<request_range:<key:\"/registry/configmaps/kube-public/cluster-info\" > >"}
{"level":"warn","ts":"2026-01-29T18:46:07.807673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.414442Z","time spent":"393.216291ms","remote":"127.0.0.1:41528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "}
{"level":"warn","ts":"2026-01-29T18:46:07.808588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.897192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"}
{"level":"info","ts":"2026-01-29T18:46:07.812986Z","caller":"traceutil/trace.go:171","msg":"trace[743240936] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:317; }","duration":"148.350435ms","start":"2026-01-29T18:46:07.664625Z","end":"2026-01-29T18:46:07.812976Z","steps":["trace[743240936] 'agreement among raft nodes before linearized reading'  (duration: 143.894702ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.808753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.462836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"}
{"level":"warn","ts":"2026-01-29T18:46:07.808799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.245055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"}
{"level":"warn","ts":"2026-01-29T18:46:07.808825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.146884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"}
{"level":"warn","ts":"2026-01-29T18:46:07.808845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.846029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"}
{"level":"warn","ts":"2026-01-29T18:46:07.808876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.340812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"}
{"level":"info","ts":"2026-01-29T18:46:07.813669Z","caller":"traceutil/trace.go:171","msg":"trace[1474994103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:317; }","duration":"199.395026ms","start":"2026-01-29T18:46:07.614265Z","end":"2026-01-29T18:46:07.81366Z","steps":["trace[1474994103] 'agreement among raft nodes before linearized reading'  (duration: 194.462145ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:07.814224Z","caller":"traceutil/trace.go:171","msg":"trace[1476785304] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:317; }","duration":"379.692721ms","start":"2026-01-29T18:46:07.434516Z","end":"2026-01-29T18:46:07.814208Z","steps":["trace[1476785304] 'agreement among raft nodes before linearized reading'  (duration: 374.341733ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.814281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.434508Z","time spent":"379.757732ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5283,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "}
{"level":"info","ts":"2026-01-29T18:46:07.813815Z","caller":"traceutil/trace.go:171","msg":"trace[112627576] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:317; }","duration":"248.279587ms","start":"2026-01-29T18:46:07.565507Z","end":"2026-01-29T18:46:07.813787Z","steps":["trace[112627576] 'agreement among raft nodes before linearized reading'  (duration: 243.259665ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:07.813903Z","caller":"traceutil/trace.go:171","msg":"trace[1138213497] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:317; }","duration":"299.238367ms","start":"2026-01-29T18:46:07.514657Z","end":"2026-01-29T18:46:07.813895Z","steps":["trace[1138213497] 'agreement among raft nodes before linearized reading'  (duration: 294.152794ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.816518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.51464Z","time spent":"301.864296ms","remote":"127.0.0.1:41528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "}
{"level":"info","ts":"2026-01-29T18:46:07.814011Z","caller":"traceutil/trace.go:171","msg":"trace[549840893] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:317; }","duration":"349.027115ms","start":"2026-01-29T18:46:07.464973Z","end":"2026-01-29T18:46:07.814001Z","steps":["trace[549840893] 'agreement among raft nodes before linearized reading'  (duration: 343.85957ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:07.816717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:07.464962Z","time spent":"351.746205ms","remote":"127.0.0.1:41528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "}
{"level":"info","ts":"2026-01-29T18:46:12.685038Z","caller":"traceutil/trace.go:171","msg":"trace[80668175] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"150.557745ms","start":"2026-01-29T18:46:12.534462Z","end":"2026-01-29T18:46:12.68502Z","steps":["trace[80668175] 'process raft request'  (duration: 150.445093ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:13.686076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.219448ms","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-01-29T18:46:13.686137Z","caller":"traceutil/trace.go:171","msg":"trace[567380510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:370; }","duration":"471.34067ms","start":"2026-01-29T18:46:13.21478Z","end":"2026-01-29T18:46:13.686121Z","steps":["trace[567380510] 'range keys from in-memory index tree'  (duration: 471.099685ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:13.686165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.214758Z","time spent":"471.400871ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:46:13.686334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.472865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:46:13.686354Z","caller":"traceutil/trace.go:171","msg":"trace[792397426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"450.521546ms","start":"2026-01-29T18:46:13.235825Z","end":"2026-01-29T18:46:13.686347Z","steps":["trace[792397426] 'range keys from in-memory index tree'  (duration: 450.371693ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:13.686387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.235811Z","time spent":"450.566646ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:46:13.686997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.38228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"}
{"level":"info","ts":"2026-01-29T18:46:13.687019Z","caller":"traceutil/trace.go:171","msg":"trace[411049507] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:370; }","duration":"222.403881ms","start":"2026-01-29T18:46:13.464609Z","end":"2026-01-29T18:46:13.687013Z","steps":["trace[411049507] 'range keys from in-memory index tree'  (duration: 222.319809ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:14.417458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509326,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-29T18:46:14.547939Z","caller":"traceutil/trace.go:171","msg":"trace[1579760997] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"848.26184ms","start":"2026-01-29T18:46:13.699661Z","end":"2026-01-29T18:46:14.547922Z","steps":["trace[1579760997] 'process raft request'  (duration: 848.144688ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:14.548074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.699645Z","time spent":"848.349081ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:365 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-01-29T18:46:17.120755Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.572877916s","expected-duration":"1s"}
{"level":"warn","ts":"2026-01-29T18:46:17.147579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284416871s","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-01-29T18:46:17.147683Z","caller":"traceutil/trace.go:171","msg":"trace[1308161906] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:371; }","duration":"1.284530314s","start":"2026-01-29T18:46:15.863138Z","end":"2026-01-29T18:46:17.147668Z","steps":["trace[1308161906] 'range keys from in-memory index tree'  (duration: 1.28440064s)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:17.147926Z","caller":"traceutil/trace.go:171","msg":"trace[1886060402] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:382; }","duration":"3.231186535s","start":"2026-01-29T18:46:13.916725Z","end":"2026-01-29T18:46:17.147911Z","steps":["trace[1886060402] 'read index received'  (duration: 631.638225ms)","trace[1886060402] 'applied index is now lower than readState.Index'  (duration: 2.59954762s)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:17.148007Z","caller":"traceutil/trace.go:171","msg":"trace[1337640906] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"3.447777108s","start":"2026-01-29T18:46:13.70022Z","end":"2026-01-29T18:46:17.147997Z","steps":["trace[1337640906] 'process raft request'  (duration: 3.447538944s)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:17.148073Z","caller":"traceutil/trace.go:171","msg":"trace[145645848] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"3.44787646s","start":"2026-01-29T18:46:13.700182Z","end":"2026-01-29T18:46:17.148058Z","steps":["trace[145645848] 'process raft request'  (duration: 3.420878953s)","trace[145645848] 'compare'  (duration: 26.422366ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:17.148083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.700216Z","time spent":"3.447813599s","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6669,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-controller-manager-instance\" mod_revision:370 > success:<request_put:<key:\"/registry/pods/kube-system/kube-controller-manager-instance\" value_size:6602 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-controller-manager-instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:46:17.148142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.700175Z","time spent":"3.447928231s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:257 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"info","ts":"2026-01-29T18:46:17.148228Z","caller":"traceutil/trace.go:171","msg":"trace[282314629] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"3.445969625s","start":"2026-01-29T18:46:13.70225Z","end":"2026-01-29T18:46:17.148219Z","steps":["trace[282314629] 'process raft request'  (duration: 3.445572818s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:17.148275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.702242Z","time spent":"3.446008236s","remote":"127.0.0.1:41590","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:366 > 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-01-29T18:46:17.148419Z","caller":"traceutil/trace.go:171","msg":"trace[384030550] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"3.323070408s","start":"2026-01-29T18:46:13.825339Z","end":"2026-01-29T18:46:17.148409Z","steps":["trace[384030550] 'process raft request'  (duration: 3.322517009s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:17.148465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.825322Z","time spent":"3.323117149s","remote":"127.0.0.1:41502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4041,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:321 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4007 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:46:17.313705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.718155ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443509332 > lease_revoke:<id:7e1f9c0b138ca8ed>","response":"size:28"}
{"level":"info","ts":"2026-01-29T18:46:17.313784Z","caller":"traceutil/trace.go:171","msg":"trace[1685363985] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"165.812987ms","start":"2026-01-29T18:46:17.147958Z","end":"2026-01-29T18:46:17.313771Z","steps":["trace[1685363985] 'read index received'  (duration: 629.342µs)","trace[1685363985] 'applied index is now lower than readState.Index'  (duration: 165.182415ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:17.313825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.397084553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"warn","ts":"2026-01-29T18:46:17.313852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.624501748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:46:17.31387Z","caller":"traceutil/trace.go:171","msg":"trace[1203610604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:375; }","duration":"2.62456387s","start":"2026-01-29T18:46:14.6893Z","end":"2026-01-29T18:46:17.313864Z","steps":["trace[1203610604] 'agreement among raft nodes before linearized reading'  (duration: 2.624504949s)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:17.313876Z","caller":"traceutil/trace.go:171","msg":"trace[127540904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"3.397177075s","start":"2026-01-29T18:46:13.916684Z","end":"2026-01-29T18:46:17.313861Z","steps":["trace[127540904] 'agreement among raft nodes before linearized reading'  (duration: 3.231892837s)","trace[127540904] 'range keys from in-memory index tree'  (duration: 165.208255ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:17.313896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:14.689281Z","time spent":"2.62460929s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:46:17.3139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:13.916666Z","time spent":"3.397229765s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:46:17.314013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.275415ms","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-01-29T18:46:17.314032Z","caller":"traceutil/trace.go:171","msg":"trace[381885641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"761.330796ms","start":"2026-01-29T18:46:16.552695Z","end":"2026-01-29T18:46:17.314026Z","steps":["trace[381885641] 'agreement among raft nodes before linearized reading'  (duration: 761.297836ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:17.31405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:16.552676Z","time spent":"761.368367ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:46:17.31411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721806245s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:17.314128Z","caller":"traceutil/trace.go:171","msg":"trace[216163099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:375; }","duration":"1.721848305s","start":"2026-01-29T18:46:15.592273Z","end":"2026-01-29T18:46:17.314121Z","steps":["trace[216163099] 'agreement among raft nodes before linearized reading'  (duration: 1.721822415s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:17.31414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:15.592259Z","time spent":"1.721878766s","remote":"127.0.0.1:41332","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-01-29T18:46:17.467264Z","caller":"traceutil/trace.go:171","msg":"trace[2040202167] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"139.470902ms","start":"2026-01-29T18:46:17.32777Z","end":"2026-01-29T18:46:17.467241Z","steps":["trace[2040202167] 'process raft request'  (duration: 116.04121ms)","trace[2040202167] 'compare'  (duration: 23.017034ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:17.467715Z","caller":"traceutil/trace.go:171","msg":"trace[1458350528] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"137.879451ms","start":"2026-01-29T18:46:17.329827Z","end":"2026-01-29T18:46:17.467707Z","steps":["trace[1458350528] 'process raft request'  (duration: 137.348142ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:17.467676Z","caller":"traceutil/trace.go:171","msg":"trace[653249713] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:387; }","duration":"139.645365ms","start":"2026-01-29T18:46:17.328018Z","end":"2026-01-29T18:46:17.467663Z","steps":["trace[653249713] 'read index received'  (duration: 3.586946ms)","trace[653249713] 'applied index is now lower than readState.Index'  (duration: 136.056829ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:17.468016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.986062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:17.468045Z","caller":"traceutil/trace.go:171","msg":"trace[663993320] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:378; }","duration":"140.063832ms","start":"2026-01-29T18:46:17.327972Z","end":"2026-01-29T18:46:17.468036Z","steps":["trace[663993320] 'agreement among raft nodes before linearized reading'  (duration: 140.003881ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:17.468341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.857459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7228"}
{"level":"info","ts":"2026-01-29T18:46:17.468372Z","caller":"traceutil/trace.go:171","msg":"trace[2096168678] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:378; }","duration":"139.89673ms","start":"2026-01-29T18:46:17.328466Z","end":"2026-01-29T18:46:17.468363Z","steps":["trace[2096168678] 'agreement among raft nodes before linearized reading'  (duration: 139.809629ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:18.263873Z","caller":"traceutil/trace.go:171","msg":"trace[1787981163] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"164.452843ms","start":"2026-01-29T18:46:18.099402Z","end":"2026-01-29T18:46:18.263855Z","steps":["trace[1787981163] 'process raft request'  (duration: 164.33837ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:29.670581Z","caller":"traceutil/trace.go:171","msg":"trace[1498631236] linearizableReadLoop","detail":"{readStateIndex:451; appliedIndex:450; }","duration":"103.872796ms","start":"2026-01-29T18:46:29.566691Z","end":"2026-01-29T18:46:29.670563Z","steps":["trace[1498631236] 'read index received'  (duration: 103.731934ms)","trace[1498631236] 'applied index is now lower than readState.Index'  (duration: 139.952µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:29.670694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.984458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:46:29.670715Z","caller":"traceutil/trace.go:171","msg":"trace[1568574435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:436; }","duration":"104.050679ms","start":"2026-01-29T18:46:29.566658Z","end":"2026-01-29T18:46:29.670709Z","steps":["trace[1568574435] 'agreement among raft nodes before linearized reading'  (duration: 103.973857ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:29.670809Z","caller":"traceutil/trace.go:171","msg":"trace[1486402096] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"104.458476ms","start":"2026-01-29T18:46:29.566344Z","end":"2026-01-29T18:46:29.670802Z","steps":["trace[1486402096] 'process raft request'  (duration: 104.12314ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:31.298884Z","caller":"traceutil/trace.go:171","msg":"trace[1557516301] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"129.073651ms","start":"2026-01-29T18:46:31.169791Z","end":"2026-01-29T18:46:31.298865Z","steps":["trace[1557516301] 'process raft request'  (duration: 80.931663ms)","trace[1557516301] 'compare'  (duration: 47.806731ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:31.564093Z","caller":"traceutil/trace.go:171","msg":"trace[68457786] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"156.894512ms","start":"2026-01-29T18:46:31.407178Z","end":"2026-01-29T18:46:31.564072Z","steps":["trace[68457786] 'read index received'  (duration: 132.246077ms)","trace[68457786] 'applied index is now lower than readState.Index'  (duration: 24.647505ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:31.564284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.082275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:46:31.564317Z","caller":"traceutil/trace.go:171","msg":"trace[1307819323] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"157.158067ms","start":"2026-01-29T18:46:31.407148Z","end":"2026-01-29T18:46:31.564306Z","steps":["trace[1307819323] 'agreement among raft nodes before linearized reading'  (duration: 157.053495ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:31.564573Z","caller":"traceutil/trace.go:171","msg":"trace[1271093457] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"260.059524ms","start":"2026-01-29T18:46:31.304502Z","end":"2026-01-29T18:46:31.564561Z","steps":["trace[1271093457] 'process raft request'  (duration: 234.975971ms)","trace[1271093457] 'compare'  (duration: 24.440971ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:31.56466Z","caller":"traceutil/trace.go:171","msg":"trace[2078584637] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"259.722808ms","start":"2026-01-29T18:46:31.304929Z","end":"2026-01-29T18:46:31.564652Z","steps":["trace[2078584637] 'process raft request'  (duration: 259.105867ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:31.860496Z","caller":"traceutil/trace.go:171","msg":"trace[1495620798] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"117.13559ms","start":"2026-01-29T18:46:31.743338Z","end":"2026-01-29T18:46:31.860473Z","steps":["trace[1495620798] 'process raft request'  (duration: 109.095062ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:32.006515Z","caller":"traceutil/trace.go:171","msg":"trace[1439832479] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"102.016321ms","start":"2026-01-29T18:46:31.904475Z","end":"2026-01-29T18:46:32.006491Z","steps":["trace[1439832479] 'process raft request'  (duration: 101.750786ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:34.154319Z","caller":"traceutil/trace.go:171","msg":"trace[982945809] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"180.341885ms","start":"2026-01-29T18:46:33.973962Z","end":"2026-01-29T18:46:34.154304Z","steps":["trace[982945809] 'process raft request'  (duration: 180.248763ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:35.578892Z","caller":"traceutil/trace.go:171","msg":"trace[1436607571] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"163.074616ms","start":"2026-01-29T18:46:35.415431Z","end":"2026-01-29T18:46:35.578506Z","steps":["trace[1436607571] 'process raft request'  (duration: 162.74156ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:37.903046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.362544ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443509562 > lease_revoke:<id:7e1f9c0b138caa9c>","response":"size:28"}
{"level":"info","ts":"2026-01-29T18:46:37.903283Z","caller":"traceutil/trace.go:171","msg":"trace[1627911145] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"317.100667ms","start":"2026-01-29T18:46:37.586168Z","end":"2026-01-29T18:46:37.903269Z","steps":["trace[1627911145] 'read index received'  (duration: 49.061µs)","trace[1627911145] 'applied index is now lower than readState.Index'  (duration: 317.048496ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:37.903417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.24064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:46:37.90344Z","caller":"traceutil/trace.go:171","msg":"trace[456938469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:487; }","duration":"317.27384ms","start":"2026-01-29T18:46:37.586159Z","end":"2026-01-29T18:46:37.903433Z","steps":["trace[456938469] 'agreement among raft nodes before linearized reading'  (duration: 317.179288ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:37.903463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:37.586093Z","time spent":"317.364992ms","remote":"127.0.0.1:41590","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":"info","ts":"2026-01-29T18:46:37.903903Z","caller":"traceutil/trace.go:171","msg":"trace[1326540892] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"111.098548ms","start":"2026-01-29T18:46:37.792798Z","end":"2026-01-29T18:46:37.903897Z","steps":["trace[1326540892] 'process raft request'  (duration: 110.355224ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:37.903825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.385648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:46:37.90406Z","caller":"traceutil/trace.go:171","msg":"trace[1454698320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"249.636562ms","start":"2026-01-29T18:46:37.65441Z","end":"2026-01-29T18:46:37.904047Z","steps":["trace[1454698320] 'agreement among raft nodes before linearized reading'  (duration: 249.383098ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:38.31834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.408288ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443509567 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:484 > 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-01-29T18:46:38.318465Z","caller":"traceutil/trace.go:171","msg":"trace[837467648] linearizableReadLoop","detail":"{readStateIndex:507; appliedIndex:506; }","duration":"357.375088ms","start":"2026-01-29T18:46:37.96107Z","end":"2026-01-29T18:46:38.318445Z","steps":["trace[837467648] 'read index received'  (duration: 43.769307ms)","trace[837467648] 'applied index is now lower than readState.Index'  (duration: 313.604041ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:38.318661Z","caller":"traceutil/trace.go:171","msg":"trace[965692642] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"408.255847ms","start":"2026-01-29T18:46:37.91038Z","end":"2026-01-29T18:46:38.318636Z","steps":["trace[965692642] 'process raft request'  (duration: 94.451841ms)","trace[965692642] 'compare'  (duration: 313.138374ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:38.318749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.269499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:46:38.318782Z","caller":"traceutil/trace.go:171","msg":"trace[708450076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:490; }","duration":"400.334321ms","start":"2026-01-29T18:46:37.918437Z","end":"2026-01-29T18:46:38.318771Z","steps":["trace[708450076] 'agreement among raft nodes before linearized reading'  (duration: 400.152338ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:38.318788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:37.910369Z","time spent":"408.360198ms","remote":"127.0.0.1:41590","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:484 > 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":"warn","ts":"2026-01-29T18:46:38.318823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:37.918426Z","time spent":"400.388792ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-01-29T18:46:38.415008Z","caller":"traceutil/trace.go:171","msg":"trace[361904980] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"449.6471ms","start":"2026-01-29T18:46:37.96533Z","end":"2026-01-29T18:46:38.414977Z","steps":["trace[361904980] 'process raft request'  (duration: 449.507447ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:38.415195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:37.965315Z","time spent":"449.775692ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:486 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-01-29T18:46:38.489458Z","caller":"traceutil/trace.go:171","msg":"trace[258154456] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"162.20618ms","start":"2026-01-29T18:46:38.32722Z","end":"2026-01-29T18:46:38.489426Z","steps":["trace[258154456] 'process raft request'  (duration: 154.07194ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:43.675598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.036037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j58hg\" ","response":"range_response_count:1 size:16652"}
{"level":"info","ts":"2026-01-29T18:46:43.675703Z","caller":"traceutil/trace.go:171","msg":"trace[399311820] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j58hg; range_end:; response_count:1; response_revision:505; }","duration":"137.20357ms","start":"2026-01-29T18:46:43.538474Z","end":"2026-01-29T18:46:43.675678Z","steps":["trace[399311820] 'range keys from in-memory index tree'  (duration: 136.913735ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:44.355745Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509608,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-29T18:46:44.403892Z","caller":"traceutil/trace.go:171","msg":"trace[917699935] linearizableReadLoop","detail":"{readStateIndex:524; appliedIndex:523; }","duration":"550.922578ms","start":"2026-01-29T18:46:43.852945Z","end":"2026-01-29T18:46:44.403867Z","steps":["trace[917699935] 'read index received'  (duration: 550.629012ms)","trace[917699935] 'applied index is now lower than readState.Index'  (duration: 292.576µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:44.404018Z","caller":"traceutil/trace.go:171","msg":"trace[880495665] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"710.828286ms","start":"2026-01-29T18:46:43.693167Z","end":"2026-01-29T18:46:44.403995Z","steps":["trace[880495665] 'process raft request'  (duration: 710.487369ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:44.404117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:43.693106Z","time spent":"710.934829ms","remote":"127.0.0.1:41514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-j58hg\" mod_revision:472 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-j58hg\" value_size:16750 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-j58hg\" > >"}
{"level":"warn","ts":"2026-01-29T18:46:44.40425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.306305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:44.404277Z","caller":"traceutil/trace.go:171","msg":"trace[1041922837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"551.391287ms","start":"2026-01-29T18:46:43.852874Z","end":"2026-01-29T18:46:44.404265Z","steps":["trace[1041922837] 'agreement among raft nodes before linearized reading'  (duration: 551.348576ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:44.404295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:43.852853Z","time spent":"551.438937ms","remote":"127.0.0.1:41340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:46:44.404544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.38293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:46:44.404566Z","caller":"traceutil/trace.go:171","msg":"trace[62945189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:506; }","duration":"420.461292ms","start":"2026-01-29T18:46:43.984099Z","end":"2026-01-29T18:46:44.404561Z","steps":["trace[62945189] 'agreement among raft nodes before linearized reading'  (duration: 420.36323ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:44.404583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:43.984056Z","time spent":"420.523453ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:46:45.495432Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.033440254s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-29T18:46:45.496037Z","caller":"traceutil/trace.go:171","msg":"trace[110186524] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:524; }","duration":"1.060139406s","start":"2026-01-29T18:46:44.435858Z","end":"2026-01-29T18:46:45.495998Z","steps":["trace[110186524] 'read index received'  (duration: 25.572322ms)","trace[110186524] 'applied index is now lower than readState.Index'  (duration: 1.034565864s)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:46:45.496205Z","caller":"traceutil/trace.go:171","msg":"trace[1119772655] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.080307619s","start":"2026-01-29T18:46:44.415873Z","end":"2026-01-29T18:46:45.496181Z","steps":["trace[1119772655] 'process raft request'  (duration: 1.079788259s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:45.496569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.937353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:46:45.496661Z","caller":"traceutil/trace.go:171","msg":"trace[2081443561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:508; }","duration":"983.094125ms","start":"2026-01-29T18:46:44.513543Z","end":"2026-01-29T18:46:45.496637Z","steps":["trace[2081443561] 'agreement among raft nodes before linearized reading'  (duration: 982.919412ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:45.496705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:44.513517Z","time spent":"983.178627ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:46:45.497142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:44.415848Z","time spent":"1.081202944s","remote":"127.0.0.1:41590","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:500 > 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":"warn","ts":"2026-01-29T18:46:45.49738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.061513412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:46:45.497443Z","caller":"traceutil/trace.go:171","msg":"trace[1312063626] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"1.061614583s","start":"2026-01-29T18:46:44.435817Z","end":"2026-01-29T18:46:45.497432Z","steps":["trace[1312063626] 'agreement among raft nodes before linearized reading'  (duration: 1.061086174s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:45.497477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:44.435801Z","time spent":"1.061667984s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:46:45.497638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.37134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:45.497669Z","caller":"traceutil/trace.go:171","msg":"trace[1788995224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"645.44669ms","start":"2026-01-29T18:46:44.852213Z","end":"2026-01-29T18:46:45.49766Z","steps":["trace[1788995224] 'agreement among raft nodes before linearized reading'  (duration: 645.398569ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:45.497701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:44.852189Z","time spent":"645.504502ms","remote":"127.0.0.1:41332","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-01-29T18:46:47.988779Z","caller":"traceutil/trace.go:171","msg":"trace[861508385] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"150.834211ms","start":"2026-01-29T18:46:47.83792Z","end":"2026-01-29T18:46:47.988754Z","steps":["trace[861508385] 'process raft request'  (duration: 150.643628ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:56.040483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.421662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:56.040551Z","caller":"traceutil/trace.go:171","msg":"trace[526959170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"189.546145ms","start":"2026-01-29T18:46:55.850992Z","end":"2026-01-29T18:46:56.040538Z","steps":["trace[526959170] 'range keys from in-memory index tree'  (duration: 189.341541ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:56.040697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.540296ms","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-01-29T18:46:56.040737Z","caller":"traceutil/trace.go:171","msg":"trace[1810669583] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:625; }","duration":"169.586637ms","start":"2026-01-29T18:46:55.871146Z","end":"2026-01-29T18:46:56.040733Z","steps":["trace[1810669583] 'range keys from in-memory index tree'  (duration: 169.535436ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:46:56.239734Z","caller":"traceutil/trace.go:171","msg":"trace[2044708526] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"192.04894ms","start":"2026-01-29T18:46:56.047664Z","end":"2026-01-29T18:46:56.239713Z","steps":["trace[2044708526] 'process raft request'  (duration: 191.923788ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.095069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.522292676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:46:58.095153Z","caller":"traceutil/trace.go:171","msg":"trace[1194440049] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"1.522422479s","start":"2026-01-29T18:46:56.572716Z","end":"2026-01-29T18:46:58.095139Z","steps":["trace[1194440049] 'range keys from in-memory index tree'  (duration: 1.522147114s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.095182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:56.572686Z","time spent":"1.52249082s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:46:58.10231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.532356ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443509869 > lease_revoke:<id:7e1f9c0b138cab52>","response":"size:28"}
{"level":"info","ts":"2026-01-29T18:46:58.102396Z","caller":"traceutil/trace.go:171","msg":"trace[928800107] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"519.021439ms","start":"2026-01-29T18:46:57.58336Z","end":"2026-01-29T18:46:58.102381Z","steps":["trace[928800107] 'read index received'  (duration: 50.701µs)","trace[928800107] 'applied index is now lower than readState.Index'  (duration: 518.969298ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:46:58.102551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.217113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:46:58.102573Z","caller":"traceutil/trace.go:171","msg":"trace[598034564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"519.323075ms","start":"2026-01-29T18:46:57.583243Z","end":"2026-01-29T18:46:58.102567Z","steps":["trace[598034564] 'agreement among raft nodes before linearized reading'  (duration: 519.200703ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.102603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:57.583217Z","time spent":"519.376715ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:46:58.10348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.84026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:46:58.103644Z","caller":"traceutil/trace.go:171","msg":"trace[1235885200] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:636; }","duration":"510.013012ms","start":"2026-01-29T18:46:57.593598Z","end":"2026-01-29T18:46:58.103611Z","steps":["trace[1235885200] 'agreement among raft nodes before linearized reading'  (duration: 509.674516ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.10374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:57.593578Z","time spent":"510.153455ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:46:58.103905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.530205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:46:58.103933Z","caller":"traceutil/trace.go:171","msg":"trace[1023001893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:636; }","duration":"252.609127ms","start":"2026-01-29T18:46:57.851318Z","end":"2026-01-29T18:46:58.103928Z","steps":["trace[1023001893] 'agreement among raft nodes before linearized reading'  (duration: 252.570096ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.104014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.261129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:46:58.104038Z","caller":"traceutil/trace.go:171","msg":"trace[1870467046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:636; }","duration":"387.350641ms","start":"2026-01-29T18:46:57.716679Z","end":"2026-01-29T18:46:58.104029Z","steps":["trace[1870467046] 'agreement among raft nodes before linearized reading'  (duration: 387.28053ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:46:58.10419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:46:57.716655Z","time spent":"387.527604ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:06.674514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509980,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-29T18:47:07.175688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509980,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-29T18:47:07.204337Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.034913571s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-29T18:47:07.204712Z","caller":"traceutil/trace.go:171","msg":"trace[373082540] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"1.035344828s","start":"2026-01-29T18:47:06.169337Z","end":"2026-01-29T18:47:07.204682Z","steps":["trace[373082540] 'process raft request'  (duration: 1.035176245s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.204933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.169327Z","time spent":"1.035463161s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:692 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-01-29T18:47:07.328061Z","caller":"traceutil/trace.go:171","msg":"trace[1893427863] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:721; }","duration":"1.154138665s","start":"2026-01-29T18:47:06.173901Z","end":"2026-01-29T18:47:07.328039Z","steps":["trace[1893427863] 'read index received'  (duration: 1.030727023s)","trace[1893427863] 'applied index is now lower than readState.Index'  (duration: 123.410352ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:07.328299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154371639s","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-01-29T18:47:07.328323Z","caller":"traceutil/trace.go:171","msg":"trace[156093118] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:697; }","duration":"1.154431711s","start":"2026-01-29T18:47:06.173884Z","end":"2026-01-29T18:47:07.328316Z","steps":["trace[156093118] 'agreement among raft nodes before linearized reading'  (duration: 1.154275838s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.328346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.173878Z","time spent":"1.154462801s","remote":"127.0.0.1:41590","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-01-29T18:47:07.328562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147033555s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:47:07.328689Z","caller":"traceutil/trace.go:171","msg":"trace[605040973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:697; }","duration":"1.147170708s","start":"2026-01-29T18:47:06.181513Z","end":"2026-01-29T18:47:07.328683Z","steps":["trace[605040973] 'agreement among raft nodes before linearized reading'  (duration: 1.147013566s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.328706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.181506Z","time spent":"1.147195179s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:07.329239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.513594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.32926Z","caller":"traceutil/trace.go:171","msg":"trace[394170235] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:697; }","duration":"457.571094ms","start":"2026-01-29T18:47:06.87168Z","end":"2026-01-29T18:47:07.329251Z","steps":["trace[394170235] 'agreement among raft nodes before linearized reading'  (duration: 457.537034ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.329275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.871659Z","time spent":"457.610996ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 "}
{"level":"warn","ts":"2026-01-29T18:47:07.329366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.819079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.329381Z","caller":"traceutil/trace.go:171","msg":"trace[1147894364] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:697; }","duration":"457.849489ms","start":"2026-01-29T18:47:06.871525Z","end":"2026-01-29T18:47:07.329374Z","steps":["trace[1147894364] 'agreement among raft nodes before linearized reading'  (duration: 457.823369ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.329395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.87152Z","time spent":"457.87053ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:07.329458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.050853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.329472Z","caller":"traceutil/trace.go:171","msg":"trace[1359220839] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:697; }","duration":"458.091704ms","start":"2026-01-29T18:47:06.871374Z","end":"2026-01-29T18:47:07.329466Z","steps":["trace[1359220839] 'agreement among raft nodes before linearized reading'  (duration: 458.065723ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.329488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.871355Z","time spent":"458.127704ms","remote":"127.0.0.1:35806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 "}
{"level":"warn","ts":"2026-01-29T18:47:07.329551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.281172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.329564Z","caller":"traceutil/trace.go:171","msg":"trace[1633030775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"477.345684ms","start":"2026-01-29T18:47:06.852214Z","end":"2026-01-29T18:47:07.329559Z","steps":["trace[1633030775] 'agreement among raft nodes before linearized reading'  (duration: 477.322683ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.329577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.852188Z","time spent":"477.386105ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:47:07.331637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.039526992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:47:07.331659Z","caller":"traceutil/trace.go:171","msg":"trace[1790817321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"1.039572803s","start":"2026-01-29T18:47:06.292081Z","end":"2026-01-29T18:47:07.331653Z","steps":["trace[1790817321] 'agreement among raft nodes before linearized reading'  (duration: 1.039498132s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.331676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.292069Z","time spent":"1.039601664s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:07.331789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148133205s","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-01-29T18:47:07.331803Z","caller":"traceutil/trace.go:171","msg":"trace[1964176427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:697; }","duration":"1.148157175s","start":"2026-01-29T18:47:06.183641Z","end":"2026-01-29T18:47:07.331798Z","steps":["trace[1964176427] 'agreement among raft nodes before linearized reading'  (duration: 1.148116874s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.331816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:06.183635Z","time spent":"1.148178005s","remote":"127.0.0.1:41590","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-01-29T18:47:07.463663Z","caller":"traceutil/trace.go:171","msg":"trace[559297809] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:723; }","duration":"124.581263ms","start":"2026-01-29T18:47:07.339061Z","end":"2026-01-29T18:47:07.463643Z","steps":["trace[559297809] 'read index received'  (duration: 124.254648ms)","trace[559297809] 'applied index is now lower than readState.Index'  (duration: 325.205µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:07.463777Z","caller":"traceutil/trace.go:171","msg":"trace[133623176] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"125.741745ms","start":"2026-01-29T18:47:07.338026Z","end":"2026-01-29T18:47:07.463768Z","steps":["trace[133623176] 'process raft request'  (duration: 125.376049ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.464063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.962711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.464096Z","caller":"traceutil/trace.go:171","msg":"trace[1158798655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"125.027812ms","start":"2026-01-29T18:47:07.339057Z","end":"2026-01-29T18:47:07.464085Z","steps":["trace[1158798655] 'agreement among raft nodes before linearized reading'  (duration: 124.871729ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.512068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.86384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.512154Z","caller":"traceutil/trace.go:171","msg":"trace[40495071] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"167.984563ms","start":"2026-01-29T18:47:07.344151Z","end":"2026-01-29T18:47:07.512135Z","steps":["trace[40495071] 'agreement among raft nodes before linearized reading'  (duration: 167.81028ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:07.512499Z","caller":"traceutil/trace.go:171","msg":"trace[1881325793] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"170.227233ms","start":"2026-01-29T18:47:07.342264Z","end":"2026-01-29T18:47:07.512491Z","steps":["trace[1881325793] 'process raft request'  (duration: 169.503701ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:07.512588Z","caller":"traceutil/trace.go:171","msg":"trace[474927796] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"168.3908ms","start":"2026-01-29T18:47:07.344191Z","end":"2026-01-29T18:47:07.512582Z","steps":["trace[474927796] 'process raft request'  (duration: 167.712768ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:07.512653Z","caller":"traceutil/trace.go:171","msg":"trace[2011844730] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"165.896914ms","start":"2026-01-29T18:47:07.346751Z","end":"2026-01-29T18:47:07.512648Z","steps":["trace[2011844730] 'process raft request'  (duration: 165.183251ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.512725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.486871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.512745Z","caller":"traceutil/trace.go:171","msg":"trace[394736955] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"168.508992ms","start":"2026-01-29T18:47:07.344226Z","end":"2026-01-29T18:47:07.512735Z","steps":["trace[394736955] 'agreement among raft nodes before linearized reading'  (duration: 168.481361ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:07.512816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.590963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:07.51283Z","caller":"traceutil/trace.go:171","msg":"trace[2019102792] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:701; }","duration":"168.611993ms","start":"2026-01-29T18:47:07.344213Z","end":"2026-01-29T18:47:07.512825Z","steps":["trace[2019102792] 'agreement among raft nodes before linearized reading'  (duration: 168.584703ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:08.244239Z","caller":"traceutil/trace.go:171","msg":"trace[1796251264] linearizableReadLoop","detail":"{readStateIndex:728; appliedIndex:727; }","duration":"128.375183ms","start":"2026-01-29T18:47:08.115834Z","end":"2026-01-29T18:47:08.244209Z","steps":["trace[1796251264] 'read index received'  (duration: 128.163269ms)","trace[1796251264] 'applied index is now lower than readState.Index'  (duration: 211.014µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:08.244397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.535326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-29T18:47:08.244423Z","caller":"traceutil/trace.go:171","msg":"trace[2059905568] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:702; }","duration":"128.666988ms","start":"2026-01-29T18:47:08.115749Z","end":"2026-01-29T18:47:08.244416Z","steps":["trace[2059905568] 'agreement among raft nodes before linearized reading'  (duration: 128.567506ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:08.244671Z","caller":"traceutil/trace.go:171","msg":"trace[1717580219] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"162.95333ms","start":"2026-01-29T18:47:08.081709Z","end":"2026-01-29T18:47:08.244663Z","steps":["trace[1717580219] 'process raft request'  (duration: 162.309178ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:09.354373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509992,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-29T18:47:09.387776Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079903342s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-29T18:47:09.388101Z","caller":"traceutil/trace.go:171","msg":"trace[1228605329] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"534.656746ms","start":"2026-01-29T18:47:08.853421Z","end":"2026-01-29T18:47:09.388078Z","steps":["trace[1228605329] 'read index received'  (duration: 534.468411ms)","trace[1228605329] 'applied index is now lower than readState.Index'  (duration: 187.315µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:09.388371Z","caller":"traceutil/trace.go:171","msg":"trace[1478883885] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.080688168s","start":"2026-01-29T18:47:08.307676Z","end":"2026-01-29T18:47:09.388364Z","steps":["trace[1478883885] 'process raft request'  (duration: 1.08027248s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:09.388461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:08.307642Z","time spent":"1.080746858s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:640 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:09.388627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.214255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:09.388646Z","caller":"traceutil/trace.go:171","msg":"trace[81537300] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:703; }","duration":"535.272986ms","start":"2026-01-29T18:47:08.853367Z","end":"2026-01-29T18:47:09.38864Z","steps":["trace[81537300] 'agreement among raft nodes before linearized reading'  (duration: 535.231816ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:09.388662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:08.853347Z","time spent":"535.310887ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:47:09.388807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.826407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"info","ts":"2026-01-29T18:47:09.388832Z","caller":"traceutil/trace.go:171","msg":"trace[1182748427] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:703; }","duration":"179.878859ms","start":"2026-01-29T18:47:09.208946Z","end":"2026-01-29T18:47:09.388825Z","steps":["trace[1182748427] 'agreement among raft nodes before linearized reading'  (duration: 179.790317ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:09.969426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443509996,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-29T18:47:10.677561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.711977ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443509997 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:697 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:47:10.677628Z","caller":"traceutil/trace.go:171","msg":"trace[622663577] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"1.208458418s","start":"2026-01-29T18:47:09.469159Z","end":"2026-01-29T18:47:10.677617Z","steps":["trace[622663577] 'read index received'  (duration: 952.69114ms)","trace[622663577] 'applied index is now lower than readState.Index'  (duration: 255.766678ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:10.677753Z","caller":"traceutil/trace.go:171","msg":"trace[468805204] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"1.285203492s","start":"2026-01-29T18:47:09.392542Z","end":"2026-01-29T18:47:10.677746Z","steps":["trace[468805204] 'process raft request'  (duration: 1.029248201s)","trace[468805204] 'compare'  (duration: 255.568873ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:10.677794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.392523Z","time spent":"1.285244293s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:697 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:10.677939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208789854s","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-01-29T18:47:10.67799Z","caller":"traceutil/trace.go:171","msg":"trace[1353487773] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:704; }","duration":"1.208839705s","start":"2026-01-29T18:47:09.469143Z","end":"2026-01-29T18:47:10.677983Z","steps":["trace[1353487773] 'agreement among raft nodes before linearized reading'  (duration: 1.208739013s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:10.678004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.469104Z","time spent":"1.208897036s","remote":"127.0.0.1:41590","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-01-29T18:47:10.678091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157176977s","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-01-29T18:47:10.678103Z","caller":"traceutil/trace.go:171","msg":"trace[2117337356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.157243238s","start":"2026-01-29T18:47:09.520856Z","end":"2026-01-29T18:47:10.678099Z","steps":["trace[2117337356] 'agreement among raft nodes before linearized reading'  (duration: 1.157205728s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:10.678114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.52083Z","time spent":"1.157281728s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:10.678244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.619098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:10.678259Z","caller":"traceutil/trace.go:171","msg":"trace[2058260977] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:704; }","duration":"729.635688ms","start":"2026-01-29T18:47:09.948619Z","end":"2026-01-29T18:47:10.678254Z","steps":["trace[2058260977] 'agreement among raft nodes before linearized reading'  (duration: 729.609907ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:10.678271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.948526Z","time spent":"729.742319ms","remote":"127.0.0.1:41722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:10.67837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.155136049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:47:10.678382Z","caller":"traceutil/trace.go:171","msg":"trace[1631976858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.155165399s","start":"2026-01-29T18:47:09.523212Z","end":"2026-01-29T18:47:10.678378Z","steps":["trace[1631976858] 'agreement among raft nodes before linearized reading'  (duration: 1.155127328s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:10.678395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.523205Z","time spent":"1.15518679s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:10.678476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.15573658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:47:10.678488Z","caller":"traceutil/trace.go:171","msg":"trace[1013761691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.15577215s","start":"2026-01-29T18:47:09.522711Z","end":"2026-01-29T18:47:10.678484Z","steps":["trace[1013761691] 'agreement among raft nodes before linearized reading'  (duration: 1.155734899s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:10.6785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:09.522699Z","time spent":"1.15579812s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-01-29T18:47:11.094603Z","caller":"traceutil/trace.go:171","msg":"trace[969198305] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"241.320175ms","start":"2026-01-29T18:47:10.853263Z","end":"2026-01-29T18:47:11.094583Z","steps":["trace[969198305] 'read index received'  (duration: 241.143252ms)","trace[969198305] 'applied index is now lower than readState.Index'  (duration: 175.873µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:11.094898Z","caller":"traceutil/trace.go:171","msg":"trace[1174886265] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"249.147707ms","start":"2026-01-29T18:47:10.845742Z","end":"2026-01-29T18:47:11.09489Z","steps":["trace[1174886265] 'process raft request'  (duration: 248.721739ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:11.095093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.819834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:11.09512Z","caller":"traceutil/trace.go:171","msg":"trace[1938953824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:709; }","duration":"241.909476ms","start":"2026-01-29T18:47:10.853203Z","end":"2026-01-29T18:47:11.095112Z","steps":["trace[1938953824] 'agreement among raft nodes before linearized reading'  (duration: 241.847355ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:11.095253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.435137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-29T18:47:11.095272Z","caller":"traceutil/trace.go:171","msg":"trace[1095468413] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:709; }","duration":"143.500749ms","start":"2026-01-29T18:47:10.951765Z","end":"2026-01-29T18:47:11.095266Z","steps":["trace[1095468413] 'agreement among raft nodes before linearized reading'  (duration: 143.454838ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:29.348716Z","caller":"traceutil/trace.go:171","msg":"trace[1380105580] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"530.132663ms","start":"2026-01-29T18:47:28.818559Z","end":"2026-01-29T18:47:29.348692Z","steps":["trace[1380105580] 'process raft request'  (duration: 529.974181ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:29.348891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.818535Z","time spent":"530.262515ms","remote":"127.0.0.1:41590","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:755 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:30.702067Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.353665106s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-29T18:47:30.702766Z","caller":"traceutil/trace.go:171","msg":"trace[2091692057] linearizableReadLoop","detail":"{readStateIndex:791; appliedIndex:790; }","duration":"1.853064172s","start":"2026-01-29T18:47:28.849663Z","end":"2026-01-29T18:47:30.702728Z","steps":["trace[2091692057] 'read index received'  (duration: 499.778341ms)","trace[2091692057] 'applied index is now lower than readState.Index'  (duration: 1.353284351s)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:30.702887Z","caller":"traceutil/trace.go:171","msg":"trace[1653188470] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"1.882427395s","start":"2026-01-29T18:47:28.820451Z","end":"2026-01-29T18:47:30.702879Z","steps":["trace[1653188470] 'process raft request'  (duration: 1.882076509s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:30.70301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.820443Z","time spent":"1.882466995s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:754 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:30.703173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.853473889s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:47:30.703219Z","caller":"traceutil/trace.go:171","msg":"trace[287361511] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"1.85357997s","start":"2026-01-29T18:47:28.849623Z","end":"2026-01-29T18:47:30.703203Z","steps":["trace[287361511] 'agreement among raft nodes before linearized reading'  (duration: 1.853420348s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:30.703252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.849607Z","time spent":"1.853636722s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:30.85525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005080113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"}
{"level":"info","ts":"2026-01-29T18:47:30.855319Z","caller":"traceutil/trace.go:171","msg":"trace[2020984390] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.005301937s","start":"2026-01-29T18:47:28.850003Z","end":"2026-01-29T18:47:30.855305Z","steps":["trace[2020984390] 'agreement among raft nodes before linearized reading'  (duration: 2.005215536s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:30.855448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.84991Z","time spent":"2.005528171s","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/01/29 18:47:30 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-01-29T18:47:31.019093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.468493ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510158 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:736 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:47:31.019344Z","caller":"traceutil/trace.go:171","msg":"trace[613514115] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"1.179830258s","start":"2026-01-29T18:47:29.839492Z","end":"2026-01-29T18:47:31.019323Z","steps":["trace[613514115] 'process raft request'  (duration: 1.07205276s)","trace[613514115] 'compare'  (duration: 107.352821ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:31.019661Z","caller":"traceutil/trace.go:171","msg":"trace[602489064] linearizableReadLoop","detail":"{readStateIndex:792; appliedIndex:791; }","duration":"316.761805ms","start":"2026-01-29T18:47:30.702839Z","end":"2026-01-29T18:47:31.019601Z","steps":["trace[602489064] 'read index received'  (duration: 208.752533ms)","trace[602489064] 'applied index is now lower than readState.Index'  (duration: 108.006792ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:31.019886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202840906s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:31.019925Z","caller":"traceutil/trace.go:171","msg":"trace[1616367809] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:761; }","duration":"1.202992469s","start":"2026-01-29T18:47:29.816919Z","end":"2026-01-29T18:47:31.019912Z","steps":["trace[1616367809] 'agreement among raft nodes before linearized reading'  (duration: 1.202853356s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.019987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:29.816868Z","time spent":"1.203106321s","remote":"127.0.0.1:41802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:31.021789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:29.839464Z","time spent":"1.17991775s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:736 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:31.022635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.837871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:31.02274Z","caller":"traceutil/trace.go:171","msg":"trace[1526979206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:762; }","duration":"167.015295ms","start":"2026-01-29T18:47:30.855714Z","end":"2026-01-29T18:47:31.02273Z","steps":["trace[1526979206] 'agreement among raft nodes before linearized reading'  (duration: 166.800131ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:31.022977Z","caller":"traceutil/trace.go:171","msg":"trace[291811700] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"310.874389ms","start":"2026-01-29T18:47:30.712095Z","end":"2026-01-29T18:47:31.022969Z","steps":["trace[291811700] 'process raft request'  (duration: 310.370979ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.023043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:30.712059Z","time spent":"310.93768ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:757 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:31.02351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560533126s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-29T18:47:31.023576Z","caller":"traceutil/trace.go:171","msg":"trace[95937329] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:762; }","duration":"1.560627948s","start":"2026-01-29T18:47:29.462899Z","end":"2026-01-29T18:47:31.023527Z","steps":["trace[95937329] 'agreement among raft nodes before linearized reading'  (duration: 1.560578987s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.023607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:29.46287Z","time spent":"1.560730049s","remote":"127.0.0.1:41678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:31.023798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233098595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:31.023882Z","caller":"traceutil/trace.go:171","msg":"trace[1859361049] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:762; }","duration":"1.233254729s","start":"2026-01-29T18:47:29.790616Z","end":"2026-01-29T18:47:31.023871Z","steps":["trace[1859361049] 'agreement among raft nodes before linearized reading'  (duration: 1.233107376s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.023911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:29.790562Z","time spent":"1.23334141s","remote":"127.0.0.1:41568","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:31.024157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313001138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:31.024184Z","caller":"traceutil/trace.go:171","msg":"trace[2102124550] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:762; }","duration":"1.313070218s","start":"2026-01-29T18:47:29.711107Z","end":"2026-01-29T18:47:31.024177Z","steps":["trace[2102124550] 'agreement among raft nodes before linearized reading'  (duration: 1.313026157s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.024203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:29.711084Z","time spent":"1.31311374s","remote":"127.0.0.1:41844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "}
{"level":"warn","ts":"2026-01-29T18:47:31.024405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.167305651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:47:31.024726Z","caller":"traceutil/trace.go:171","msg":"trace[1790516043] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:762; }","duration":"2.167341461s","start":"2026-01-29T18:47:28.85708Z","end":"2026-01-29T18:47:31.024421Z","steps":["trace[1790516043] 'agreement among raft nodes before linearized reading'  (duration: 2.16725357s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.024767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.857074Z","time spent":"2.167676577s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:31.024992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.169696325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:47:31.025013Z","caller":"traceutil/trace.go:171","msg":"trace[1574746549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"2.169719745s","start":"2026-01-29T18:47:28.855287Z","end":"2026-01-29T18:47:31.025007Z","steps":["trace[1574746549] 'agreement among raft nodes before linearized reading'  (duration: 2.16940252s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.025032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:28.855236Z","time spent":"2.169791966s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:31.74833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.400135ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510164 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-instance.188f48190e1ad8d3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-instance.188f48190e1ad8d3\" value_size:672 lease:9088154144443509957 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:47:31.748519Z","caller":"traceutil/trace.go:171","msg":"trace[1880514721] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:793; }","duration":"394.60349ms","start":"2026-01-29T18:47:31.353903Z","end":"2026-01-29T18:47:31.748506Z","steps":["trace[1880514721] 'read index received'  (duration: 274.213973ms)","trace[1880514721] 'applied index is now lower than readState.Index'  (duration: 120.388867ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:31.748568Z","caller":"traceutil/trace.go:171","msg":"trace[1154957651] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"714.692937ms","start":"2026-01-29T18:47:31.033869Z","end":"2026-01-29T18:47:31.748561Z","steps":["trace[1154957651] 'process raft request'  (duration: 128.000737ms)","trace[1154957651] 'compare'  (duration: 585.880735ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:31.748601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.033836Z","time spent":"714.749008ms","remote":"127.0.0.1:41396","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/kube-apiserver-instance.188f48190e1ad8d3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/kube-apiserver-instance.188f48190e1ad8d3\" value_size:672 lease:9088154144443509957 >> failure:<>"}
{"level":"info","ts":"2026-01-29T18:47:31.748815Z","caller":"traceutil/trace.go:171","msg":"trace[1869858838] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"711.351386ms","start":"2026-01-29T18:47:31.037441Z","end":"2026-01-29T18:47:31.748792Z","steps":["trace[1869858838] 'process raft request'  (duration: 711.042771ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.748887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.983197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:47:31.748909Z","caller":"traceutil/trace.go:171","msg":"trace[368749736] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"395.067158ms","start":"2026-01-29T18:47:31.353835Z","end":"2026-01-29T18:47:31.748902Z","steps":["trace[368749736] 'agreement among raft nodes before linearized reading'  (duration: 394.982927ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.748927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.353805Z","time spent":"395.11794ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:47:31.74895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.037437Z","time spent":"711.437848ms","remote":"127.0.0.1:41590","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-01-29T18:47:31.749138Z","caller":"traceutil/trace.go:171","msg":"trace[99848112] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"711.739892ms","start":"2026-01-29T18:47:31.037391Z","end":"2026-01-29T18:47:31.749131Z","steps":["trace[99848112] 'process raft request'  (duration: 711.01833ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:31.749185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.03738Z","time spent":"711.778934ms","remote":"127.0.0.1:41590","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:756 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-29T18:47:32.053496Z","caller":"traceutil/trace.go:171","msg":"trace[405088261] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"627.673016ms","start":"2026-01-29T18:47:31.425802Z","end":"2026-01-29T18:47:32.053475Z","steps":["trace[405088261] 'process raft request'  (duration: 627.551314ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:32.053655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.425776Z","time spent":"627.809498ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:742 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-01-29T18:47:32.054893Z","caller":"traceutil/trace.go:171","msg":"trace[1875341150] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:797; }","duration":"306.341897ms","start":"2026-01-29T18:47:31.748538Z","end":"2026-01-29T18:47:32.05488Z","steps":["trace[1875341150] 'read index received'  (duration: 306.336987ms)","trace[1875341150] 'applied index is now lower than readState.Index'  (duration: 4µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:32.055045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.647094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-29T18:47:32.055076Z","caller":"traceutil/trace.go:171","msg":"trace[226645343] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:766; }","duration":"321.712106ms","start":"2026-01-29T18:47:31.733355Z","end":"2026-01-29T18:47:32.055068Z","steps":["trace[226645343] 'agreement among raft nodes before linearized reading'  (duration: 321.596364ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:32.055107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.733338Z","time spent":"321.761527ms","remote":"127.0.0.1:41364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "}
{"level":"info","ts":"2026-01-29T18:47:32.057326Z","caller":"traceutil/trace.go:171","msg":"trace[1682368035] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"302.567728ms","start":"2026-01-29T18:47:31.754719Z","end":"2026-01-29T18:47:32.057287Z","steps":["trace[1682368035] 'process raft request'  (duration: 302.436655ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:32.05749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:31.754698Z","time spent":"302.71017ms","remote":"127.0.0.1:41590","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:759 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:32.058061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.999209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:32.058103Z","caller":"traceutil/trace.go:171","msg":"trace[1023671766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:767; }","duration":"203.06731ms","start":"2026-01-29T18:47:31.855023Z","end":"2026-01-29T18:47:32.058091Z","steps":["trace[1023671766] 'agreement among raft nodes before linearized reading'  (duration: 202.997558ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:32.855502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.455982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:47:32.855558Z","caller":"traceutil/trace.go:171","msg":"trace[1849148399] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"145.553673ms","start":"2026-01-29T18:47:32.709993Z","end":"2026-01-29T18:47:32.855547Z","steps":["trace[1849148399] 'range keys from in-memory index tree'  (duration: 145.33938ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:33.344785Z","caller":"traceutil/trace.go:171","msg":"trace[1646412397] linearizableReadLoop","detail":"{readStateIndex:801; appliedIndex:800; }","duration":"306.739253ms","start":"2026-01-29T18:47:33.038021Z","end":"2026-01-29T18:47:33.34476Z","steps":["trace[1646412397] 'read index received'  (duration: 306.5655ms)","trace[1646412397] 'applied index is now lower than readState.Index'  (duration: 172.933µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:33.345315Z","caller":"traceutil/trace.go:171","msg":"trace[757286892] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"484.415872ms","start":"2026-01-29T18:47:32.860886Z","end":"2026-01-29T18:47:33.345302Z","steps":["trace[757286892] 'process raft request'  (duration: 483.75842ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:33.345405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:32.860874Z","time spent":"484.473713ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:760 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:33.344998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.955618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:47:33.34589Z","caller":"traceutil/trace.go:171","msg":"trace[959545221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:769; }","duration":"307.669991ms","start":"2026-01-29T18:47:33.037976Z","end":"2026-01-29T18:47:33.345646Z","steps":["trace[959545221] 'agreement among raft nodes before linearized reading'  (duration: 306.878496ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:33.347154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:33.037927Z","time spent":"308.002866ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:33.979999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.305527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:47:33.980074Z","caller":"traceutil/trace.go:171","msg":"trace[471128395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:770; }","duration":"224.451089ms","start":"2026-01-29T18:47:33.755606Z","end":"2026-01-29T18:47:33.980057Z","steps":["trace[471128395] 'range keys from in-memory index tree'  (duration: 224.137732ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:33.980424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.426634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:33.980454Z","caller":"traceutil/trace.go:171","msg":"trace[1477001840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"128.472755ms","start":"2026-01-29T18:47:33.851971Z","end":"2026-01-29T18:47:33.980444Z","steps":["trace[1477001840] 'range keys from in-memory index tree'  (duration: 128.390903ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:33.980007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.677931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:47:33.980716Z","caller":"traceutil/trace.go:171","msg":"trace[750241806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:770; }","duration":"226.442645ms","start":"2026-01-29T18:47:33.754256Z","end":"2026-01-29T18:47:33.980699Z","steps":["trace[750241806] 'range keys from in-memory index tree'  (duration: 225.419666ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:42.568808Z","caller":"traceutil/trace.go:171","msg":"trace[554893370] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"208.943327ms","start":"2026-01-29T18:47:42.359841Z","end":"2026-01-29T18:47:42.568785Z","steps":["trace[554893370] 'process raft request'  (duration: 208.751584ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:44.209003Z","caller":"traceutil/trace.go:171","msg":"trace[1149152207] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"145.451413ms","start":"2026-01-29T18:47:44.06353Z","end":"2026-01-29T18:47:44.208982Z","steps":["trace[1149152207] 'read index received'  (duration: 145.189608ms)","trace[1149152207] 'applied index is now lower than readState.Index'  (duration: 260.545µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:44.209129Z","caller":"traceutil/trace.go:171","msg":"trace[749040580] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"155.320782ms","start":"2026-01-29T18:47:44.053797Z","end":"2026-01-29T18:47:44.209118Z","steps":["trace[749040580] 'process raft request'  (duration: 155.035767ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:44.209387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.811211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:47:44.20942Z","caller":"traceutil/trace.go:171","msg":"trace[1016425060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"145.896002ms","start":"2026-01-29T18:47:44.063513Z","end":"2026-01-29T18:47:44.209409Z","steps":["trace[1016425060] 'agreement among raft nodes before linearized reading'  (duration: 145.734339ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:44.4305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.056149ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510272 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:794 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:47:44.430632Z","caller":"traceutil/trace.go:171","msg":"trace[1491377593] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"213.050242ms","start":"2026-01-29T18:47:44.217563Z","end":"2026-01-29T18:47:44.430613Z","steps":["trace[1491377593] 'process raft request'  (duration: 107.796628ms)","trace[1491377593] 'compare'  (duration: 104.691493ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:44.431651Z","caller":"traceutil/trace.go:171","msg":"trace[1112111961] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"213.878646ms","start":"2026-01-29T18:47:44.217759Z","end":"2026-01-29T18:47:44.431638Z","steps":["trace[1112111961] 'process raft request'  (duration: 213.809345ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:47:48.592982Z","caller":"traceutil/trace.go:171","msg":"trace[1381925169] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"141.242545ms","start":"2026-01-29T18:47:48.451722Z","end":"2026-01-29T18:47:48.592964Z","steps":["trace[1381925169] 'read index received'  (duration: 141.023351ms)","trace[1381925169] 'applied index is now lower than readState.Index'  (duration: 218.314µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:48.593095Z","caller":"traceutil/trace.go:171","msg":"trace[882831440] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"301.995247ms","start":"2026-01-29T18:47:48.291094Z","end":"2026-01-29T18:47:48.593089Z","steps":["trace[882831440] 'process raft request'  (duration: 301.702811ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:48.593178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:48.291085Z","time spent":"302.026739ms","remote":"127.0.0.1:41590","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:848 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:48.593269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.936682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:47:48.593324Z","caller":"traceutil/trace.go:171","msg":"trace[143982018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:860; }","duration":"129.062885ms","start":"2026-01-29T18:47:48.464249Z","end":"2026-01-29T18:47:48.593312Z","steps":["trace[143982018] 'agreement among raft nodes before linearized reading'  (duration: 128.884702ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:48.593506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.781875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:47:48.593541Z","caller":"traceutil/trace.go:171","msg":"trace[2023258399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:860; }","duration":"141.838027ms","start":"2026-01-29T18:47:48.451695Z","end":"2026-01-29T18:47:48.593533Z","steps":["trace[2023258399] 'agreement among raft nodes before linearized reading'  (duration: 141.725915ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:51.147087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.530079ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510405 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:862 > 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-01-29T18:47:51.147163Z","caller":"traceutil/trace.go:171","msg":"trace[743294395] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"463.659205ms","start":"2026-01-29T18:47:50.683492Z","end":"2026-01-29T18:47:51.147151Z","steps":["trace[743294395] 'process raft request'  (duration: 260.011904ms)","trace[743294395] 'compare'  (duration: 203.354405ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:51.147209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:50.683472Z","time spent":"463.720186ms","remote":"127.0.0.1:41590","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:862 > 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":"warn","ts":"2026-01-29T18:47:52.690104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.417751612s","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510406 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:861 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:47:52.690165Z","caller":"traceutil/trace.go:171","msg":"trace[639639553] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:901; }","duration":"1.838469287s","start":"2026-01-29T18:47:50.851684Z","end":"2026-01-29T18:47:52.690153Z","steps":["trace[639639553] 'read index received'  (duration: 91.772018ms)","trace[639639553] 'applied index is now lower than readState.Index'  (duration: 1.746696659s)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:47:52.690268Z","caller":"traceutil/trace.go:171","msg":"trace[1151573030] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"2.004011404s","start":"2026-01-29T18:47:50.686248Z","end":"2026-01-29T18:47:52.69026Z","steps":["trace[1151573030] 'process raft request'  (duration: 586.053288ms)","trace[1151573030] 'compare'  (duration: 1.417196641s)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:52.690298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:50.686236Z","time spent":"2.004046426s","remote":"127.0.0.1:41590","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:861 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:52.690404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.838725851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:52.690421Z","caller":"traceutil/trace.go:171","msg":"trace[567851508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:867; }","duration":"1.838742801s","start":"2026-01-29T18:47:50.851673Z","end":"2026-01-29T18:47:52.690416Z","steps":["trace[567851508] 'agreement among raft nodes before linearized reading'  (duration: 1.838711591s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:52.690438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:50.851626Z","time spent":"1.838809503s","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:47:52.69059Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.025911812s","expected-duration":"1s"}
{"level":"warn","ts":"2026-01-29T18:47:52.690774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.248219431s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:47:52.69079Z","caller":"traceutil/trace.go:171","msg":"trace[1309734442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:868; }","duration":"1.248259702s","start":"2026-01-29T18:47:51.442525Z","end":"2026-01-29T18:47:52.690785Z","steps":["trace[1309734442] 'agreement among raft nodes before linearized reading'  (duration: 1.24817157s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:52.690802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:51.442511Z","time spent":"1.248288182s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-01-29T18:47:52.690904Z","caller":"traceutil/trace.go:171","msg":"trace[382979424] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"1.026281479s","start":"2026-01-29T18:47:51.664618Z","end":"2026-01-29T18:47:52.6909Z","steps":["trace[382979424] 'process raft request'  (duration: 1.026039805s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:52.690931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:51.664599Z","time spent":"1.026316119s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:790 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:47:52.691077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.499041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-29T18:47:52.69109Z","caller":"traceutil/trace.go:171","msg":"trace[341647563] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:868; }","duration":"956.535891ms","start":"2026-01-29T18:47:51.73455Z","end":"2026-01-29T18:47:52.691086Z","steps":["trace[341647563] 'agreement among raft nodes before linearized reading'  (duration: 956.49663ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:52.691102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:51.734535Z","time spent":"956.563611ms","remote":"127.0.0.1:41364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.33\" "}
{"level":"warn","ts":"2026-01-29T18:47:52.691197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11168308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:47:52.691209Z","caller":"traceutil/trace.go:171","msg":"trace[1823015049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"1.111712561s","start":"2026-01-29T18:47:51.579493Z","end":"2026-01-29T18:47:52.691206Z","steps":["trace[1823015049] 'agreement among raft nodes before linearized reading'  (duration: 1.11167021s)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:52.691224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:51.579477Z","time spent":"1.111744481s","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:47:53.051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.379805ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510412 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:7e1f9c0b138cae8b>","response":"size:40"}
{"level":"warn","ts":"2026-01-29T18:47:53.051099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.694194Z","time spent":"356.901435ms","remote":"127.0.0.1:41364","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-29T18:47:53.088628Z","caller":"traceutil/trace.go:171","msg":"trace[1333285504] linearizableReadLoop","detail":"{readStateIndex:908; appliedIndex:904; }","duration":"385.535775ms","start":"2026-01-29T18:47:52.703071Z","end":"2026-01-29T18:47:53.088607Z","steps":["trace[1333285504] 'read index received'  (duration: 155.601457ms)","trace[1333285504] 'applied index is now lower than readState.Index'  (duration: 229.933438ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:53.088764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.670507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:47:53.088794Z","caller":"traceutil/trace.go:171","msg":"trace[1778005831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"385.732038ms","start":"2026-01-29T18:47:52.703052Z","end":"2026-01-29T18:47:53.088784Z","steps":["trace[1778005831] 'agreement among raft nodes before linearized reading'  (duration: 385.638627ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:53.088829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.703045Z","time spent":"385.775139ms","remote":"127.0.0.1:41332","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-01-29T18:47:53.089156Z","caller":"traceutil/trace.go:171","msg":"trace[2087698213] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"386.82392ms","start":"2026-01-29T18:47:52.702322Z","end":"2026-01-29T18:47:53.089145Z","steps":["trace[2087698213] 'process raft request'  (duration: 386.025105ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:53.089229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.702303Z","time spent":"386.87868ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:863 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-01-29T18:47:53.089357Z","caller":"traceutil/trace.go:171","msg":"trace[27215360] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"386.964862ms","start":"2026-01-29T18:47:52.702381Z","end":"2026-01-29T18:47:53.089346Z","steps":["trace[27215360] 'process raft request'  (duration: 386.117496ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:53.089404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.702375Z","time spent":"387.001763ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:864 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-01-29T18:47:53.089519Z","caller":"traceutil/trace.go:171","msg":"trace[329475047] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"387.145095ms","start":"2026-01-29T18:47:52.702365Z","end":"2026-01-29T18:47:53.08951Z","steps":["trace[329475047] 'process raft request'  (duration: 386.178397ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:53.089563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.702343Z","time spent":"387.195305ms","remote":"127.0.0.1:41590","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:865 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-29T18:47:53.08968Z","caller":"traceutil/trace.go:171","msg":"trace[1810652508] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"353.525593ms","start":"2026-01-29T18:47:52.736144Z","end":"2026-01-29T18:47:53.089669Z","steps":["trace[1810652508] 'process raft request'  (duration: 352.428223ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:53.089726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:52.736119Z","time spent":"353.580424ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:796 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"info","ts":"2026-01-29T18:47:55.40648Z","caller":"traceutil/trace.go:171","msg":"trace[19326087] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"308.513017ms","start":"2026-01-29T18:47:55.09795Z","end":"2026-01-29T18:47:55.406463Z","steps":["trace[19326087] 'process raft request'  (duration: 308.417375ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:55.4066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:55.097931Z","time spent":"308.593968ms","remote":"127.0.0.1:41590","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:870 > 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":"info","ts":"2026-01-29T18:47:55.406901Z","caller":"traceutil/trace.go:171","msg":"trace[1118564578] linearizableReadLoop","detail":"{readStateIndex:913; appliedIndex:913; }","duration":"303.907732ms","start":"2026-01-29T18:47:55.102987Z","end":"2026-01-29T18:47:55.406895Z","steps":["trace[1118564578] 'read index received'  (duration: 303.905532ms)","trace[1118564578] 'applied index is now lower than readState.Index'  (duration: 1.77µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:47:55.406976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.362802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:47:55.406992Z","caller":"traceutil/trace.go:171","msg":"trace[427311427] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:876; }","duration":"298.387052ms","start":"2026-01-29T18:47:55.108599Z","end":"2026-01-29T18:47:55.406986Z","steps":["trace[427311427] 'agreement among raft nodes before linearized reading'  (duration: 298.325631ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:55.407074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.086065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:47:55.407086Z","caller":"traceutil/trace.go:171","msg":"trace[797756026] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:876; }","duration":"304.108655ms","start":"2026-01-29T18:47:55.102974Z","end":"2026-01-29T18:47:55.407082Z","steps":["trace[797756026] 'agreement among raft nodes before linearized reading'  (duration: 304.074675ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:47:55.407099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:47:55.102969Z","time spent":"304.125606ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:47:55.407167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.020468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:47:55.407179Z","caller":"traceutil/trace.go:171","msg":"trace[413565993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:876; }","duration":"182.054918ms","start":"2026-01-29T18:47:55.22512Z","end":"2026-01-29T18:47:55.407175Z","steps":["trace[413565993] 'agreement among raft nodes before linearized reading'  (duration: 182.023168ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:01.987443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443510476,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-29T18:48:02.02031Z","caller":"traceutil/trace.go:171","msg":"trace[235930696] linearizableReadLoop","detail":"{readStateIndex:931; appliedIndex:930; }","duration":"533.224338ms","start":"2026-01-29T18:48:01.487066Z","end":"2026-01-29T18:48:02.020291Z","steps":["trace[235930696] 'read index received'  (duration: 525.60247ms)","trace[235930696] 'applied index is now lower than readState.Index'  (duration: 7.621298ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:02.020488Z","caller":"traceutil/trace.go:171","msg":"trace[1818315988] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"543.653339ms","start":"2026-01-29T18:48:01.476827Z","end":"2026-01-29T18:48:02.02048Z","steps":["trace[1818315988] 'process raft request'  (duration: 535.863807ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:02.020554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:01.476815Z","time spent":"543.691109ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:888 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-29T18:48:02.020696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.627196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:02.020714Z","caller":"traceutil/trace.go:171","msg":"trace[2079287105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:893; }","duration":"533.660047ms","start":"2026-01-29T18:48:01.487048Z","end":"2026-01-29T18:48:02.020708Z","steps":["trace[2079287105] 'agreement among raft nodes before linearized reading'  (duration: 533.590616ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:02.020727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:01.48704Z","time spent":"533.683727ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:48:02.020814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.896675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.33\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-29T18:48:02.020828Z","caller":"traceutil/trace.go:171","msg":"trace[1059947076] range","detail":"{range_begin:/registry/masterleases/199.204.45.33; range_end:; response_count:1; response_revision:893; }","duration":"285.931315ms","start":"2026-01-29T18:48:01.734891Z","end":"2026-01-29T18:48:02.020823Z","steps":["trace[1059947076] 'agreement among raft nodes before linearized reading'  (duration: 285.892854ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:02.021049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.008765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:02.021104Z","caller":"traceutil/trace.go:171","msg":"trace[271183016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:893; }","duration":"173.080635ms","start":"2026-01-29T18:48:01.848001Z","end":"2026-01-29T18:48:02.021082Z","steps":["trace[271183016] 'agreement among raft nodes before linearized reading'  (duration: 173.005684ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:03.167543Z","caller":"traceutil/trace.go:171","msg":"trace[1066638578] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"414.920739ms","start":"2026-01-29T18:48:02.752604Z","end":"2026-01-29T18:48:03.167525Z","steps":["trace[1066638578] 'process raft request'  (duration: 414.815398ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:03.167648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:02.752593Z","time spent":"414.998231ms","remote":"127.0.0.1:41590","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:890 > 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-01-29T18:48:03.21118Z","caller":"traceutil/trace.go:171","msg":"trace[400380982] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"360.908138ms","start":"2026-01-29T18:48:02.850256Z","end":"2026-01-29T18:48:03.211164Z","steps":["trace[400380982] 'read index received'  (duration: 317.687633ms)","trace[400380982] 'applied index is now lower than readState.Index'  (duration: 43.219915ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:03.211281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.00857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:03.211297Z","caller":"traceutil/trace.go:171","msg":"trace[1994404735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"361.067711ms","start":"2026-01-29T18:48:02.850225Z","end":"2026-01-29T18:48:03.211293Z","steps":["trace[1994404735] 'agreement among raft nodes before linearized reading'  (duration: 360.99915ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:03.211316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:02.850207Z","time spent":"361.104222ms","remote":"127.0.0.1:41332","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-01-29T18:48:03.211452Z","caller":"traceutil/trace.go:171","msg":"trace[1134320378] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"418.26998ms","start":"2026-01-29T18:48:02.793176Z","end":"2026-01-29T18:48:03.211446Z","steps":["trace[1134320378] 'process raft request'  (duration: 417.903324ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:03.211495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:02.79316Z","time spent":"418.304711ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:868 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"info","ts":"2026-01-29T18:48:03.541202Z","caller":"traceutil/trace.go:171","msg":"trace[628603003] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"110.418407ms","start":"2026-01-29T18:48:03.430765Z","end":"2026-01-29T18:48:03.541184Z","steps":["trace[628603003] 'process raft request'  (duration: 110.093321ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:03.675641Z","caller":"traceutil/trace.go:171","msg":"trace[1193946900] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"127.888024ms","start":"2026-01-29T18:48:03.547734Z","end":"2026-01-29T18:48:03.675622Z","steps":["trace[1193946900] 'process raft request'  (duration: 44.860494ms)","trace[1193946900] 'compare'  (duration: 82.957489ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:04.853646Z","caller":"traceutil/trace.go:171","msg":"trace[990669515] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"796.484003ms","start":"2026-01-29T18:48:04.057113Z","end":"2026-01-29T18:48:04.853597Z","steps":["trace[990669515] 'process raft request'  (duration: 712.779172ms)","trace[990669515] 'compare'  (duration: 83.481687ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:04.853728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:04.057097Z","time spent":"796.587934ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:894 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-01-29T18:48:05.711726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.970592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:48:05.71178Z","caller":"traceutil/trace.go:171","msg":"trace[634373934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:902; }","duration":"540.054094ms","start":"2026-01-29T18:48:05.171716Z","end":"2026-01-29T18:48:05.71177Z","steps":["trace[634373934] 'range keys from in-memory index tree'  (duration: 539.904201ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:05.711803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:05.171691Z","time spent":"540.102114ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:48:05.712023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.346203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:48:05.712042Z","caller":"traceutil/trace.go:171","msg":"trace[2056696741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:902; }","duration":"166.388934ms","start":"2026-01-29T18:48:05.545648Z","end":"2026-01-29T18:48:05.712037Z","steps":["trace[2056696741] 'range keys from in-memory index tree'  (duration: 166.278402ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:05.953067Z","caller":"traceutil/trace.go:171","msg":"trace[1130224189] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"237.481535ms","start":"2026-01-29T18:48:05.715565Z","end":"2026-01-29T18:48:05.953047Z","steps":["trace[1130224189] 'process raft request'  (duration: 237.354922ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:06.251346Z","caller":"traceutil/trace.go:171","msg":"trace[1172433784] linearizableReadLoop","detail":"{readStateIndex:944; appliedIndex:942; }","duration":"337.609485ms","start":"2026-01-29T18:48:05.91371Z","end":"2026-01-29T18:48:06.25132Z","steps":["trace[1172433784] 'read index received'  (duration: 39.761513ms)","trace[1172433784] 'applied index is now lower than readState.Index'  (duration: 297.846761ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:06.251713Z","caller":"traceutil/trace.go:171","msg":"trace[1632478330] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"535.627793ms","start":"2026-01-29T18:48:05.716065Z","end":"2026-01-29T18:48:06.251693Z","steps":["trace[1632478330] 'process raft request'  (duration: 440.955352ms)","trace[1632478330] 'compare'  (duration: 94.10988ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:06.25185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:05.716058Z","time spent":"535.693364ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:896 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"info","ts":"2026-01-29T18:48:06.252183Z","caller":"traceutil/trace.go:171","msg":"trace[1274753793] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"534.39076ms","start":"2026-01-29T18:48:05.717776Z","end":"2026-01-29T18:48:06.252167Z","steps":["trace[1274753793] 'process raft request'  (duration: 533.488764ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:06.252342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:05.717769Z","time spent":"534.495042ms","remote":"127.0.0.1:41590","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:900 > 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":"warn","ts":"2026-01-29T18:48:06.252568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.850297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:06.252617Z","caller":"traceutil/trace.go:171","msg":"trace[1599829916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"338.926818ms","start":"2026-01-29T18:48:05.913673Z","end":"2026-01-29T18:48:06.2526Z","steps":["trace[1599829916] 'agreement among raft nodes before linearized reading'  (duration: 338.856537ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:06.252656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:05.913653Z","time spent":"338.99325ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:48:06.254308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.44752ms","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-01-29T18:48:06.254468Z","caller":"traceutil/trace.go:171","msg":"trace[1177233751] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"196.640654ms","start":"2026-01-29T18:48:06.057814Z","end":"2026-01-29T18:48:06.254454Z","steps":["trace[1177233751] 'agreement among raft nodes before linearized reading'  (duration: 195.056995ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:08.765273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443510524,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-29T18:48:08.788298Z","caller":"traceutil/trace.go:171","msg":"trace[1020811455] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"827.952724ms","start":"2026-01-29T18:48:07.960329Z","end":"2026-01-29T18:48:08.788282Z","steps":["trace[1020811455] 'process raft request'  (duration: 827.854832ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:08.788392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:07.960317Z","time spent":"828.026816ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:903 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-29T18:48:09.189286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.348589ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443510526 > lease_revoke:<id:7e1f9c0b138cae8b>","response":"size:28"}
{"level":"info","ts":"2026-01-29T18:48:09.189383Z","caller":"traceutil/trace.go:171","msg":"trace[1868409896] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:947; }","duration":"925.053589ms","start":"2026-01-29T18:48:08.264312Z","end":"2026-01-29T18:48:09.189365Z","steps":["trace[1868409896] 'read index received'  (duration: 524.332908ms)","trace[1868409896] 'applied index is now lower than readState.Index'  (duration: 400.719141ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:09.189682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.344854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"}
{"level":"warn","ts":"2026-01-29T18:48:09.189723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"925.048469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:48:09.189733Z","caller":"traceutil/trace.go:171","msg":"trace[1974343547] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:908; }","duration":"925.438855ms","start":"2026-01-29T18:48:08.26428Z","end":"2026-01-29T18:48:09.189719Z","steps":["trace[1974343547] 'agreement among raft nodes before linearized reading'  (duration: 925.262522ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:09.189763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:08.264265Z","time spent":"925.492277ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-29T18:48:09.189758Z","caller":"traceutil/trace.go:171","msg":"trace[1469911688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:908; }","duration":"925.12227ms","start":"2026-01-29T18:48:08.264626Z","end":"2026-01-29T18:48:09.189748Z","steps":["trace[1469911688] 'agreement among raft nodes before linearized reading'  (duration: 924.956917ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:09.189794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:08.26462Z","time spent":"925.16605ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:48:09.190018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.123088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:09.190048Z","caller":"traceutil/trace.go:171","msg":"trace[1564060373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:908; }","duration":"324.1851ms","start":"2026-01-29T18:48:08.865854Z","end":"2026-01-29T18:48:09.190039Z","steps":["trace[1564060373] 'agreement among raft nodes before linearized reading'  (duration: 324.061008ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:09.190075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:08.865836Z","time spent":"324.2326ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-29T18:48:09.190133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.933661ms","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-01-29T18:48:09.190164Z","caller":"traceutil/trace.go:171","msg":"trace[589858516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:908; }","duration":"861.977672ms","start":"2026-01-29T18:48:08.328172Z","end":"2026-01-29T18:48:09.190149Z","steps":["trace[589858516] 'agreement among raft nodes before linearized reading'  (duration: 861.907791ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:09.190183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:08.328161Z","time spent":"862.017212ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-01-29T18:48:11.122941Z","caller":"traceutil/trace.go:171","msg":"trace[1867718144] linearizableReadLoop","detail":"{readStateIndex:953; appliedIndex:952; }","duration":"270.027666ms","start":"2026-01-29T18:48:10.852898Z","end":"2026-01-29T18:48:11.122926Z","steps":["trace[1867718144] 'read index received'  (duration: 269.903304ms)","trace[1867718144] 'applied index is now lower than readState.Index'  (duration: 123.852µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:11.123052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.138418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:11.123073Z","caller":"traceutil/trace.go:171","msg":"trace[455321140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:913; }","duration":"270.20493ms","start":"2026-01-29T18:48:10.852863Z","end":"2026-01-29T18:48:11.123068Z","steps":["trace[455321140] 'agreement among raft nodes before linearized reading'  (duration: 270.121968ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:11.123314Z","caller":"traceutil/trace.go:171","msg":"trace[104842893] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"323.522958ms","start":"2026-01-29T18:48:10.799784Z","end":"2026-01-29T18:48:11.123307Z","steps":["trace[104842893] 'process raft request'  (duration: 323.0619ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:11.123377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:10.799762Z","time spent":"323.572789ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:908 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:411 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"info","ts":"2026-01-29T18:48:11.47426Z","caller":"traceutil/trace.go:171","msg":"trace[1116489899] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"258.906775ms","start":"2026-01-29T18:48:11.215339Z","end":"2026-01-29T18:48:11.474245Z","steps":["trace[1116489899] 'process raft request'  (duration: 258.822814ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:11.479417Z","caller":"traceutil/trace.go:171","msg":"trace[202684585] linearizableReadLoop","detail":"{readStateIndex:955; appliedIndex:954; }","duration":"262.342306ms","start":"2026-01-29T18:48:11.21706Z","end":"2026-01-29T18:48:11.479402Z","steps":["trace[202684585] 'read index received'  (duration: 257.529149ms)","trace[202684585] 'applied index is now lower than readState.Index'  (duration: 4.812447ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:11.479654Z","caller":"traceutil/trace.go:171","msg":"trace[1466486918] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"264.067128ms","start":"2026-01-29T18:48:11.215581Z","end":"2026-01-29T18:48:11.479649Z","steps":["trace[1466486918] 'process raft request'  (duration: 263.740582ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:11.479809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.737154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:11.479832Z","caller":"traceutil/trace.go:171","msg":"trace[929929154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"262.790915ms","start":"2026-01-29T18:48:11.217036Z","end":"2026-01-29T18:48:11.479827Z","steps":["trace[929929154] 'agreement among raft nodes before linearized reading'  (duration: 262.709974ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:11.479916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.784687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:48:11.479935Z","caller":"traceutil/trace.go:171","msg":"trace[825408243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"261.838167ms","start":"2026-01-29T18:48:11.218092Z","end":"2026-01-29T18:48:11.47993Z","steps":["trace[825408243] 'agreement among raft nodes before linearized reading'  (duration: 261.799236ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:19.769341Z","caller":"traceutil/trace.go:171","msg":"trace[79056180] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1000; }","duration":"200.358719ms","start":"2026-01-29T18:48:19.56896Z","end":"2026-01-29T18:48:19.769319Z","steps":["trace[79056180] 'read index received'  (duration: 48.036699ms)","trace[79056180] 'applied index is now lower than readState.Index'  (duration: 152.321ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:19.770236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.225836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:19.77031Z","caller":"traceutil/trace.go:171","msg":"trace[1453781012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:959; }","duration":"201.356708ms","start":"2026-01-29T18:48:19.568935Z","end":"2026-01-29T18:48:19.770291Z","steps":["trace[1453781012] 'agreement among raft nodes before linearized reading'  (duration: 200.472922ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:19.77067Z","caller":"traceutil/trace.go:171","msg":"trace[348625198] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"202.758664ms","start":"2026-01-29T18:48:19.567898Z","end":"2026-01-29T18:48:19.770657Z","steps":["trace[348625198] 'process raft request'  (duration: 166.424971ms)","trace[348625198] 'compare'  (duration: 34.908057ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:23.423519Z","caller":"traceutil/trace.go:171","msg":"trace[2698453] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"237.099139ms","start":"2026-01-29T18:48:23.186401Z","end":"2026-01-29T18:48:23.4235Z","steps":["trace[2698453] 'process raft request'  (duration: 236.976077ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.133848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9088154144443510658,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-29T18:48:24.222861Z","caller":"traceutil/trace.go:171","msg":"trace[154852065] linearizableReadLoop","detail":"{readStateIndex:1013; appliedIndex:1011; }","duration":"590.101756ms","start":"2026-01-29T18:48:23.632739Z","end":"2026-01-29T18:48:24.22284Z","steps":["trace[154852065] 'read index received'  (duration: 541.548477ms)","trace[154852065] 'applied index is now lower than readState.Index'  (duration: 48.552249ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:24.222975Z","caller":"traceutil/trace.go:171","msg":"trace[1596787678] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"648.462956ms","start":"2026-01-29T18:48:23.5745Z","end":"2026-01-29T18:48:24.222963Z","steps":["trace[1596787678] 'process raft request'  (duration: 599.662952ms)","trace[1596787678] 'compare'  (duration: 47.836016ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:24.223073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.574458Z","time spent":"648.542838ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:930 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:472 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-01-29T18:48:24.223152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.398542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:48:24.223215Z","caller":"traceutil/trace.go:171","msg":"trace[1060927943] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:969; }","duration":"590.509633ms","start":"2026-01-29T18:48:23.632688Z","end":"2026-01-29T18:48:24.223198Z","steps":["trace[1060927943] 'agreement among raft nodes before linearized reading'  (duration: 590.33931ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.223253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.632645Z","time spent":"590.600675ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:48:24.223446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.676075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:48:24.223498Z","caller":"traceutil/trace.go:171","msg":"trace[695082715] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:969; }","duration":"496.784387ms","start":"2026-01-29T18:48:23.726699Z","end":"2026-01-29T18:48:24.223483Z","steps":["trace[695082715] 'agreement among raft nodes before linearized reading'  (duration: 496.584064ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.223539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.726666Z","time spent":"496.862588ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-01-29T18:48:24.223568Z","caller":"traceutil/trace.go:171","msg":"trace[2099793879] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"369.742776ms","start":"2026-01-29T18:48:23.853811Z","end":"2026-01-29T18:48:24.223553Z","steps":["trace[2099793879] 'process raft request'  (duration: 368.942061ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.223665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.853798Z","time spent":"369.805746ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:941 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:600 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-29T18:48:24.223769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.706944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:24.223794Z","caller":"traceutil/trace.go:171","msg":"trace[1668743657] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:969; }","duration":"370.765955ms","start":"2026-01-29T18:48:23.853019Z","end":"2026-01-29T18:48:24.223785Z","steps":["trace[1668743657] 'agreement among raft nodes before linearized reading'  (duration: 370.723404ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.223817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.852975Z","time spent":"370.836736ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:48:24.223874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.130394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:48:24.223911Z","caller":"traceutil/trace.go:171","msg":"trace[963837286] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:969; }","duration":"437.204956ms","start":"2026-01-29T18:48:23.786696Z","end":"2026-01-29T18:48:24.223901Z","steps":["trace[963837286] 'agreement among raft nodes before linearized reading'  (duration: 437.105844ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.223939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.786659Z","time spent":"437.274497ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:48:24.224194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.374192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:24.224232Z","caller":"traceutil/trace.go:171","msg":"trace[1642066213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:969; }","duration":"367.422762ms","start":"2026-01-29T18:48:23.856798Z","end":"2026-01-29T18:48:24.224221Z","steps":["trace[1642066213] 'agreement among raft nodes before linearized reading'  (duration: 367.325821ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:24.224262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:48:23.856791Z","time spent":"367.463753ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-01-29T18:48:30.457076Z","caller":"traceutil/trace.go:171","msg":"trace[1902114349] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"177.731171ms","start":"2026-01-29T18:48:30.279321Z","end":"2026-01-29T18:48:30.457052Z","steps":["trace[1902114349] 'process raft request'  (duration: 177.573847ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:30.457636Z","caller":"traceutil/trace.go:171","msg":"trace[1823185018] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1038; }","duration":"175.145143ms","start":"2026-01-29T18:48:30.282462Z","end":"2026-01-29T18:48:30.457607Z","steps":["trace[1823185018] 'read index received'  (duration: 175.134662ms)","trace[1823185018] 'applied index is now lower than readState.Index'  (duration: 8.731µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:30.458203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.713903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:48:30.458259Z","caller":"traceutil/trace.go:171","msg":"trace[1354793443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:993; }","duration":"175.797654ms","start":"2026-01-29T18:48:30.282447Z","end":"2026-01-29T18:48:30.458244Z","steps":["trace[1354793443] 'agreement among raft nodes before linearized reading'  (duration: 175.55575ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:30.568217Z","caller":"traceutil/trace.go:171","msg":"trace[1833935104] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"105.565674ms","start":"2026-01-29T18:48:30.46263Z","end":"2026-01-29T18:48:30.568196Z","steps":["trace[1833935104] 'process raft request'  (duration: 105.503933ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:30.568893Z","caller":"traceutil/trace.go:171","msg":"trace[1229946505] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"285.711529ms","start":"2026-01-29T18:48:30.283164Z","end":"2026-01-29T18:48:30.568875Z","steps":["trace[1229946505] 'process raft request'  (duration: 253.851ms)","trace[1229946505] 'compare'  (duration: 30.460653ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:30.569234Z","caller":"traceutil/trace.go:171","msg":"trace[963275217] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"111.507904ms","start":"2026-01-29T18:48:30.457714Z","end":"2026-01-29T18:48:30.569222Z","steps":["trace[963275217] 'read index received'  (duration: 79.454511ms)","trace[963275217] 'applied index is now lower than readState.Index'  (duration: 32.052153ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:30.569401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.470978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:30.569453Z","caller":"traceutil/trace.go:171","msg":"trace[409318851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"169.571029ms","start":"2026-01-29T18:48:30.39987Z","end":"2026-01-29T18:48:30.569442Z","steps":["trace[409318851] 'agreement among raft nodes before linearized reading'  (duration: 169.400966ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:31.955302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.483119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:48:31.955355Z","caller":"traceutil/trace.go:171","msg":"trace[1950820682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"101.572931ms","start":"2026-01-29T18:48:31.853769Z","end":"2026-01-29T18:48:31.955342Z","steps":["trace[1950820682] 'range keys from in-memory index tree'  (duration: 101.419848ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:48:58.874757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.610957ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443511039 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1131 > 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-01-29T18:48:58.874822Z","caller":"traceutil/trace.go:171","msg":"trace[957011026] linearizableReadLoop","detail":"{readStateIndex:1188; appliedIndex:1187; }","duration":"102.881185ms","start":"2026-01-29T18:48:58.77193Z","end":"2026-01-29T18:48:58.874811Z","steps":["trace[957011026] 'read index received'  (duration: 126.513µs)","trace[957011026] 'applied index is now lower than readState.Index'  (duration: 102.754122ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:48:58.87497Z","caller":"traceutil/trace.go:171","msg":"trace[452023856] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"146.464001ms","start":"2026-01-29T18:48:58.728497Z","end":"2026-01-29T18:48:58.874961Z","steps":["trace[452023856] 'process raft request'  (duration: 34.60096ms)","trace[452023856] 'compare'  (duration: 111.372943ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:48:58.875096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.16569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:48:58.875109Z","caller":"traceutil/trace.go:171","msg":"trace[1513999368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1137; }","duration":"103.21248ms","start":"2026-01-29T18:48:58.771892Z","end":"2026-01-29T18:48:58.875105Z","steps":["trace[1513999368] 'agreement among raft nodes before linearized reading'  (duration: 103.155369ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:48:59.023744Z","caller":"traceutil/trace.go:171","msg":"trace[1991662262] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"143.098299ms","start":"2026-01-29T18:48:58.880625Z","end":"2026-01-29T18:48:59.023723Z","steps":["trace[1991662262] 'process raft request'  (duration: 109.177262ms)","trace[1991662262] 'compare'  (duration: 33.641202ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:19.271624Z","caller":"traceutil/trace.go:171","msg":"trace[1403798826] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"467.732749ms","start":"2026-01-29T18:49:18.803868Z","end":"2026-01-29T18:49:19.271601Z","steps":["trace[1403798826] 'read index received'  (duration: 467.494239ms)","trace[1403798826] 'applied index is now lower than readState.Index'  (duration: 237.42µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:19.272176Z","caller":"traceutil/trace.go:171","msg":"trace[559410021] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"495.957768ms","start":"2026-01-29T18:49:18.776191Z","end":"2026-01-29T18:49:19.272148Z","steps":["trace[559410021] 'process raft request'  (duration: 495.196296ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.272559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:18.776177Z","time spent":"496.268799ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1211 > success:<request_put:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" value_size:453 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" > >"}
{"level":"warn","ts":"2026-01-29T18:49:19.273092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.217074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:49:19.27335Z","caller":"traceutil/trace.go:171","msg":"trace[716643363] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1217; }","duration":"469.499915ms","start":"2026-01-29T18:49:18.803832Z","end":"2026-01-29T18:49:19.273332Z","steps":["trace[716643363] 'agreement among raft nodes before linearized reading'  (duration: 469.162024ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.273546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:18.803814Z","time spent":"469.718256ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:49:19.274087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.355605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"}
{"level":"info","ts":"2026-01-29T18:49:19.274129Z","caller":"traceutil/trace.go:171","msg":"trace[623757445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1217; }","duration":"469.436985ms","start":"2026-01-29T18:49:18.804682Z","end":"2026-01-29T18:49:19.274119Z","steps":["trace[623757445] 'agreement among raft nodes before linearized reading'  (duration: 469.229564ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.274892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:18.804673Z","time spent":"470.206977ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-29T18:49:19.276013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.812861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"}
{"level":"info","ts":"2026-01-29T18:49:19.276049Z","caller":"traceutil/trace.go:171","msg":"trace[638186047] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1217; }","duration":"211.882331ms","start":"2026-01-29T18:49:19.064157Z","end":"2026-01-29T18:49:19.27604Z","steps":["trace[638186047] 'agreement among raft nodes before linearized reading'  (duration: 210.896467ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.276223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.232575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:49:19.276364Z","caller":"traceutil/trace.go:171","msg":"trace[1301679549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1217; }","duration":"267.296695ms","start":"2026-01-29T18:49:19.008949Z","end":"2026-01-29T18:49:19.276245Z","steps":["trace[1301679549] 'agreement among raft nodes before linearized reading'  (duration: 267.180765ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.276637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.54199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"info","ts":"2026-01-29T18:49:19.276674Z","caller":"traceutil/trace.go:171","msg":"trace[395661175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1217; }","duration":"308.618931ms","start":"2026-01-29T18:49:18.968046Z","end":"2026-01-29T18:49:19.276665Z","steps":["trace[395661175] 'agreement among raft nodes before linearized reading'  (duration: 308.42723ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.276704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:18.968024Z","time spent":"308.675441ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-29T18:49:19.276921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.535715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:49:19.276946Z","caller":"traceutil/trace.go:171","msg":"trace[152529939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1217; }","duration":"426.607795ms","start":"2026-01-29T18:49:18.850333Z","end":"2026-01-29T18:49:19.276941Z","steps":["trace[152529939] 'agreement among raft nodes before linearized reading'  (duration: 426.566765ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:19.277068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:18.850313Z","time spent":"426.646965ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:49:27.65595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.616717ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443511342 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1244 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:49:27.656565Z","caller":"traceutil/trace.go:171","msg":"trace[1795223371] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"266.208151ms","start":"2026-01-29T18:49:27.390339Z","end":"2026-01-29T18:49:27.656547Z","steps":["trace[1795223371] 'read index received'  (duration: 34.147117ms)","trace[1795223371] 'applied index is now lower than readState.Index'  (duration: 232.060074ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:27.656879Z","caller":"traceutil/trace.go:171","msg":"trace[1654847020] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"269.125457ms","start":"2026-01-29T18:49:27.387738Z","end":"2026-01-29T18:49:27.656864Z","steps":["trace[1654847020] 'process raft request'  (duration: 95.517911ms)","trace[1654847020] 'compare'  (duration: 172.508125ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:27.657378Z","caller":"traceutil/trace.go:171","msg":"trace[525222243] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"266.351003ms","start":"2026-01-29T18:49:27.391015Z","end":"2026-01-29T18:49:27.657366Z","steps":["trace[525222243] 'process raft request'  (duration: 265.021259ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:27.65869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.328071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:49:27.658817Z","caller":"traceutil/trace.go:171","msg":"trace[362779135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1255; }","duration":"268.475433ms","start":"2026-01-29T18:49:27.390323Z","end":"2026-01-29T18:49:27.658798Z","steps":["trace[362779135] 'agreement among raft nodes before linearized reading'  (duration: 268.090246ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:49:33.62776Z","caller":"traceutil/trace.go:171","msg":"trace[1872004701] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"166.89894ms","start":"2026-01-29T18:49:33.460827Z","end":"2026-01-29T18:49:33.627726Z","steps":["trace[1872004701] 'process raft request'  (duration: 71.236687ms)","trace[1872004701] 'compare'  (duration: 95.431829ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:49:34.924544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.977401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"}
{"level":"info","ts":"2026-01-29T18:49:34.924821Z","caller":"traceutil/trace.go:171","msg":"trace[1459293762] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1281; }","duration":"277.104664ms","start":"2026-01-29T18:49:34.647522Z","end":"2026-01-29T18:49:34.924626Z","steps":["trace[1459293762] 'range keys from in-memory index tree'  (duration: 276.777278ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:49:37.951416Z","caller":"traceutil/trace.go:171","msg":"trace[536717292] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"107.292739ms","start":"2026-01-29T18:49:37.844099Z","end":"2026-01-29T18:49:37.951392Z","steps":["trace[536717292] 'process raft request'  (duration: 106.052486ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:49:37.953974Z","caller":"traceutil/trace.go:171","msg":"trace[1460897968] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"106.888412ms","start":"2026-01-29T18:49:37.843643Z","end":"2026-01-29T18:49:37.950532Z","steps":["trace[1460897968] 'process raft request'  (duration: 88.243545ms)","trace[1460897968] 'compare'  (duration: 17.816551ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:41.688228Z","caller":"traceutil/trace.go:171","msg":"trace[931736337] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"156.732711ms","start":"2026-01-29T18:49:41.531479Z","end":"2026-01-29T18:49:41.688212Z","steps":["trace[931736337] 'read index received'  (duration: 156.537757ms)","trace[931736337] 'applied index is now lower than readState.Index'  (duration: 194.214µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:41.688846Z","caller":"traceutil/trace.go:171","msg":"trace[436261826] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"161.889337ms","start":"2026-01-29T18:49:41.526948Z","end":"2026-01-29T18:49:41.688838Z","steps":["trace[436261826] 'process raft request'  (duration: 161.148813ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:41.689106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.606847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"}
{"level":"info","ts":"2026-01-29T18:49:41.689305Z","caller":"traceutil/trace.go:171","msg":"trace[1569291909] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1307; }","duration":"157.78306ms","start":"2026-01-29T18:49:41.531459Z","end":"2026-01-29T18:49:41.689242Z","steps":["trace[1569291909] 'agreement among raft nodes before linearized reading'  (duration: 157.564916ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:49:49.900512Z","caller":"traceutil/trace.go:171","msg":"trace[1184172930] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"159.783448ms","start":"2026-01-29T18:49:49.740706Z","end":"2026-01-29T18:49:49.900489Z","steps":["trace[1184172930] 'process raft request'  (duration: 159.643565ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.256551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.656211ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9088154144443511575 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1335 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-29T18:49:50.261026Z","caller":"traceutil/trace.go:171","msg":"trace[1312420874] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1401; }","duration":"407.843469ms","start":"2026-01-29T18:49:49.853171Z","end":"2026-01-29T18:49:50.261014Z","steps":["trace[1312420874] 'read index received'  (duration: 47.246689ms)","trace[1312420874] 'applied index is now lower than readState.Index'  (duration: 360.59553ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:49:50.261351Z","caller":"traceutil/trace.go:171","msg":"trace[1860579273] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"519.834517ms","start":"2026-01-29T18:49:49.741504Z","end":"2026-01-29T18:49:50.261339Z","steps":["trace[1860579273] 'process raft request'  (duration: 286.333726ms)","trace[1860579273] 'compare'  (duration: 228.59081ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:49:50.261519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:49.741495Z","time spent":"519.954679ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1335 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-29T18:49:50.261736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.619765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-29T18:49:50.261775Z","caller":"traceutil/trace.go:171","msg":"trace[827297649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"408.699746ms","start":"2026-01-29T18:49:49.853069Z","end":"2026-01-29T18:49:50.261769Z","steps":["trace[827297649] 'agreement among raft nodes before linearized reading'  (duration: 408.628443ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.261822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:49.85305Z","time spent":"408.765918ms","remote":"127.0.0.1:41332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-29T18:49:50.262012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.955559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-01-29T18:49:50.262064Z","caller":"traceutil/trace.go:171","msg":"trace[1275608513] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1342; }","duration":"396.03758ms","start":"2026-01-29T18:49:49.866021Z","end":"2026-01-29T18:49:50.262058Z","steps":["trace[1275608513] 'agreement among raft nodes before linearized reading'  (duration: 395.914578ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.262126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:49.866006Z","time spent":"396.113711ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-29T18:49:50.262324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.801524ms","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-01-29T18:49:50.262398Z","caller":"traceutil/trace.go:171","msg":"trace[1093108752] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1342; }","duration":"228.892165ms","start":"2026-01-29T18:49:50.033498Z","end":"2026-01-29T18:49:50.26239Z","steps":["trace[1093108752] 'agreement among raft nodes before linearized reading'  (duration: 228.788373ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.262567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.421063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-01-29T18:49:50.262623Z","caller":"traceutil/trace.go:171","msg":"trace[1981602783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1342; }","duration":"238.497294ms","start":"2026-01-29T18:49:50.02412Z","end":"2026-01-29T18:49:50.262617Z","steps":["trace[1981602783] 'agreement among raft nodes before linearized reading'  (duration: 238.380302ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.262799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.976055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"}
{"level":"info","ts":"2026-01-29T18:49:50.262853Z","caller":"traceutil/trace.go:171","msg":"trace[1626575238] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1342; }","duration":"245.059736ms","start":"2026-01-29T18:49:50.017788Z","end":"2026-01-29T18:49:50.262848Z","steps":["trace[1626575238] 'agreement among raft nodes before linearized reading'  (duration: 244.949494ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.262974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.052833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-29T18:49:50.263034Z","caller":"traceutil/trace.go:171","msg":"trace[1542232434] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1342; }","duration":"254.142115ms","start":"2026-01-29T18:49:50.008886Z","end":"2026-01-29T18:49:50.263028Z","steps":["trace[1542232434] 'agreement among raft nodes before linearized reading'  (duration: 254.066664ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.263205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.930379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:49:50.263278Z","caller":"traceutil/trace.go:171","msg":"trace[949045372] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1342; }","duration":"322.040771ms","start":"2026-01-29T18:49:49.94123Z","end":"2026-01-29T18:49:50.263271Z","steps":["trace[949045372] 'agreement among raft nodes before linearized reading'  (duration: 321.937709ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:49:50.263316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:49:49.941206Z","time spent":"322.104282ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:49:56.085149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.447077ms","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:533"}
{"level":"info","ts":"2026-01-29T18:49:56.085225Z","caller":"traceutil/trace.go:171","msg":"trace[1263802542] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1366; }","duration":"111.55847ms","start":"2026-01-29T18:49:55.973649Z","end":"2026-01-29T18:49:56.085207Z","steps":["trace[1263802542] 'range keys from in-memory index tree'  (duration: 111.302285ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:49:56.194223Z","caller":"traceutil/trace.go:171","msg":"trace[2109844528] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"102.848777ms","start":"2026-01-29T18:49:56.091356Z","end":"2026-01-29T18:49:56.194205Z","steps":["trace[2109844528] 'process raft request'  (duration: 102.748306ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:28.526131Z","caller":"traceutil/trace.go:171","msg":"trace[1372039916] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"110.174877ms","start":"2026-01-29T18:50:28.415931Z","end":"2026-01-29T18:50:28.526106Z","steps":["trace[1372039916] 'process raft request'  (duration: 109.732468ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:36.914351Z","caller":"traceutil/trace.go:171","msg":"trace[425411004] transaction","detail":"{read_only:false; response_revision:1535; number_of_response:1; }","duration":"151.542842ms","start":"2026-01-29T18:50:36.762784Z","end":"2026-01-29T18:50:36.914327Z","steps":["trace[425411004] 'process raft request'  (duration: 151.495861ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:36.914499Z","caller":"traceutil/trace.go:171","msg":"trace[1798978109] transaction","detail":"{read_only:false; response_revision:1533; number_of_response:1; }","duration":"152.122222ms","start":"2026-01-29T18:50:36.762369Z","end":"2026-01-29T18:50:36.914491Z","steps":["trace[1798978109] 'process raft request'  (duration: 130.682275ms)","trace[1798978109] 'compare'  (duration: 21.086059ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:50:36.914569Z","caller":"traceutil/trace.go:171","msg":"trace[224168931] transaction","detail":"{read_only:false; response_revision:1534; number_of_response:1; }","duration":"152.155033ms","start":"2026-01-29T18:50:36.762408Z","end":"2026-01-29T18:50:36.914563Z","steps":["trace[224168931] 'process raft request'  (duration: 151.842477ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:47.184427Z","caller":"traceutil/trace.go:171","msg":"trace[57075686] transaction","detail":"{read_only:false; response_revision:1578; number_of_response:1; }","duration":"147.453474ms","start":"2026-01-29T18:50:47.036952Z","end":"2026-01-29T18:50:47.184405Z","steps":["trace[57075686] 'process raft request'  (duration: 147.405643ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:47.184931Z","caller":"traceutil/trace.go:171","msg":"trace[528193593] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"152.258485ms","start":"2026-01-29T18:50:47.032657Z","end":"2026-01-29T18:50:47.184915Z","steps":["trace[528193593] 'process raft request'  (duration: 136.68466ms)","trace[528193593] 'compare'  (duration: 14.500355ms)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:50:47.185225Z","caller":"traceutil/trace.go:171","msg":"trace[580195269] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"151.719954ms","start":"2026-01-29T18:50:47.033495Z","end":"2026-01-29T18:50:47.185215Z","steps":["trace[580195269] 'process raft request'  (duration: 150.755136ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:47.18527Z","caller":"traceutil/trace.go:171","msg":"trace[283968070] transaction","detail":"{read_only:false; response_revision:1577; number_of_response:1; }","duration":"150.314097ms","start":"2026-01-29T18:50:47.034948Z","end":"2026-01-29T18:50:47.185262Z","steps":["trace[283968070] 'process raft request'  (duration: 149.37206ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:50:51.302143Z","caller":"traceutil/trace.go:171","msg":"trace[229939925] linearizableReadLoop","detail":"{readStateIndex:1664; appliedIndex:1663; }","duration":"101.3018ms","start":"2026-01-29T18:50:51.200828Z","end":"2026-01-29T18:50:51.30213Z","steps":["trace[229939925] 'read index received'  (duration: 101.175548ms)","trace[229939925] 'applied index is now lower than readState.Index'  (duration: 125.712µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:50:51.302315Z","caller":"traceutil/trace.go:171","msg":"trace[1295483013] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"101.751428ms","start":"2026-01-29T18:50:51.200556Z","end":"2026-01-29T18:50:51.302308Z","steps":["trace[1295483013] 'process raft request'  (duration: 101.488763ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:50:51.302471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.628396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-01-29T18:50:51.302489Z","caller":"traceutil/trace.go:171","msg":"trace[1699759489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1591; }","duration":"101.659746ms","start":"2026-01-29T18:50:51.200824Z","end":"2026-01-29T18:50:51.302484Z","steps":["trace[1699759489] 'agreement among raft nodes before linearized reading'  (duration: 101.586015ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:51:05.264939Z","caller":"traceutil/trace.go:171","msg":"trace[362543619] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"100.31559ms","start":"2026-01-29T18:51:05.164598Z","end":"2026-01-29T18:51:05.264914Z","steps":["trace[362543619] 'process raft request'  (duration: 100.148287ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:51:05.874171Z","caller":"traceutil/trace.go:171","msg":"trace[1724162686] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1724; }","duration":"315.870093ms","start":"2026-01-29T18:51:05.558283Z","end":"2026-01-29T18:51:05.874153Z","steps":["trace[1724162686] 'read index received'  (duration: 315.632979ms)","trace[1724162686] 'applied index is now lower than readState.Index'  (duration: 236.444µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:51:05.874549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.324678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"}
{"level":"info","ts":"2026-01-29T18:51:05.875342Z","caller":"traceutil/trace.go:171","msg":"trace[1002410273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1649; }","duration":"312.146823ms","start":"2026-01-29T18:51:05.563182Z","end":"2026-01-29T18:51:05.875329Z","steps":["trace[1002410273] 'agreement among raft nodes before linearized reading'  (duration: 311.281156ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:05.875378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:51:05.563172Z","time spent":"312.198685ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-29T18:51:05.875155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.194174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"}
{"level":"info","ts":"2026-01-29T18:51:05.875472Z","caller":"traceutil/trace.go:171","msg":"trace[1022827621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1649; }","duration":"311.530641ms","start":"2026-01-29T18:51:05.563935Z","end":"2026-01-29T18:51:05.875466Z","steps":["trace[1022827621] 'agreement among raft nodes before linearized reading'  (duration: 311.172695ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:05.875496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:51:05.563927Z","time spent":"311.564052ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-01-29T18:51:05.87567Z","caller":"traceutil/trace.go:171","msg":"trace[1947879075] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"393.943332ms","start":"2026-01-29T18:51:05.481719Z","end":"2026-01-29T18:51:05.875662Z","steps":["trace[1947879075] 'process raft request'  (duration: 392.290421ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:05.875737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:51:05.4817Z","time spent":"393.996123ms","remote":"127.0.0.1:41590","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:1642 > 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":"warn","ts":"2026-01-29T18:51:05.87474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.441475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"}
{"level":"info","ts":"2026-01-29T18:51:05.878411Z","caller":"traceutil/trace.go:171","msg":"trace[983143056] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1649; }","duration":"320.165105ms","start":"2026-01-29T18:51:05.558237Z","end":"2026-01-29T18:51:05.878403Z","steps":["trace[983143056] 'agreement among raft nodes before linearized reading'  (duration: 316.082797ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:05.878566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:51:05.558217Z","time spent":"320.337918ms","remote":"127.0.0.1:41590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-01-29T18:51:12.376275Z","caller":"traceutil/trace.go:171","msg":"trace[779451289] linearizableReadLoop","detail":"{readStateIndex:1756; appliedIndex:1755; }","duration":"100.700548ms","start":"2026-01-29T18:51:12.275551Z","end":"2026-01-29T18:51:12.376252Z","steps":["trace[779451289] 'read index received'  (duration: 100.525965ms)","trace[779451289] 'applied index is now lower than readState.Index'  (duration: 173.253µs)"],"step_count":2}
{"level":"info","ts":"2026-01-29T18:51:12.376689Z","caller":"traceutil/trace.go:171","msg":"trace[1646982041] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"133.552669ms","start":"2026-01-29T18:51:12.243077Z","end":"2026-01-29T18:51:12.37663Z","steps":["trace[1646982041] 'process raft request'  (duration: 133.018199ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:12.376957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.346771ms","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-01-29T18:51:12.37701Z","caller":"traceutil/trace.go:171","msg":"trace[1666802444] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1678; }","duration":"101.490383ms","start":"2026-01-29T18:51:12.275505Z","end":"2026-01-29T18:51:12.376996Z","steps":["trace[1666802444] 'agreement among raft nodes before linearized reading'  (duration: 100.83435ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-29T18:51:43.528639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.253259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"}
{"level":"info","ts":"2026-01-29T18:51:43.528703Z","caller":"traceutil/trace.go:171","msg":"trace[275473340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1832; }","duration":"118.400121ms","start":"2026-01-29T18:51:43.41029Z","end":"2026-01-29T18:51:43.52869Z","steps":["trace[275473340] 'range keys from in-memory index tree'  (duration: 118.083306ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:51:43.669313Z","caller":"traceutil/trace.go:171","msg":"trace[1413912406] transaction","detail":"{read_only:false; response_revision:1833; number_of_response:1; }","duration":"136.40018ms","start":"2026-01-29T18:51:43.532897Z","end":"2026-01-29T18:51:43.669297Z","steps":["trace[1413912406] 'process raft request'  (duration: 136.276107ms)"],"step_count":1}
{"level":"info","ts":"2026-01-29T18:51:43.929319Z","caller":"traceutil/trace.go:171","msg":"trace[24635186] transaction","detail":"{read_only:false; response_revision:1834; number_of_response:1; }","duration":"396.260606ms","start":"2026-01-29T18:51:43.533029Z","end":"2026-01-29T18:51:43.92929Z","steps":["trace[24635186] 'process raft request'  (duration: 353.946519ms)","trace[24635186] 'compare'  (duration: 42.188775ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-29T18:51:43.929463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T18:51:43.533023Z","time spent":"396.368699ms","remote":"127.0.0.1:41590","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:1822 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}