{"level":"warn","ts":"2026-01-23T10:15:50.523269Z","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-23T10:15:50.523357Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-23T10:15:50.52342Z","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-23T10:15:50.523427Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]}
{"level":"info","ts":"2026-01-23T10:15:50.523443Z","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-23T10:15:50.52405Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]}
{"level":"info","ts":"2026-01-23T10:15:50.524119Z","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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-23T10:15:50.529722Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.416478ms"}
{"level":"info","ts":"2026-01-23T10:15:50.551835Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"}
{"level":"info","ts":"2026-01-23T10:15:50.551981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"}
{"level":"info","ts":"2026-01-23T10:15:50.55204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"}
{"level":"info","ts":"2026-01-23T10:15:50.552078Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-01-23T10:15:50.552124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"}
{"level":"info","ts":"2026-01-23T10:15:50.552177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"}
{"level":"warn","ts":"2026-01-23T10:15:50.562148Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-01-23T10:15:50.56895Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-01-23T10:15:50.571747Z","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-23T10:15:50.575383Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-01-23T10:15:50.575685Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-01-23T10:15:50.575765Z","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-23T10:15:50.575794Z","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-23T10:15:50.575801Z","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-23T10:15:50.578419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"}
{"level":"info","ts":"2026-01-23T10:15:50.578525Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]}
{"level":"info","ts":"2026-01-23T10:15:50.589504Z","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-23T10:15:50.589741Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-01-23T10:15:50.589859Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-01-23T10:15:50.590777Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"}
{"level":"info","ts":"2026-01-23T10:15:50.590843Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"}
{"level":"info","ts":"2026-01-23T10:15:50.953878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"}
{"level":"info","ts":"2026-01-23T10:15:50.953941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"}
{"level":"info","ts":"2026-01-23T10:15:50.953955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"}
{"level":"info","ts":"2026-01-23T10:15:50.954118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"}
{"level":"info","ts":"2026-01-23T10:15:50.954136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"}
{"level":"info","ts":"2026-01-23T10:15:50.954147Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"}
{"level":"info","ts":"2026-01-23T10:15:50.954194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"}
{"level":"info","ts":"2026-01-23T10:15:50.960805Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T10:15:50.965252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"}
{"level":"info","ts":"2026-01-23T10:15:50.965725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-23T10:15:50.966054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-01-23T10:15:50.966983Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-01-23T10:15:50.967085Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-01-23T10:15:50.972545Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"}
{"level":"info","ts":"2026-01-23T10:15:50.972738Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T10:15:50.972783Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T10:15:50.972816Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-01-23T10:15:50.975302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-01-23T10:15:56.980245Z","caller":"traceutil/trace.go:171","msg":"trace[709699335] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"147.465132ms","start":"2026-01-23T10:15:56.832765Z","end":"2026-01-23T10:15:56.980231Z","steps":["trace[709699335] 'read index received'  (duration: 29.281801ms)","trace[709699335] 'applied index is now lower than readState.Index'  (duration: 118.182761ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:15:56.980343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.558264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"}
{"level":"info","ts":"2026-01-23T10:15:56.980363Z","caller":"traceutil/trace.go:171","msg":"trace[1290502648] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:249; }","duration":"147.607574ms","start":"2026-01-23T10:15:56.83275Z","end":"2026-01-23T10:15:56.980357Z","steps":["trace[1290502648] 'agreement among raft nodes before linearized reading'  (duration: 147.539673ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:15:56.980523Z","caller":"traceutil/trace.go:171","msg":"trace[293279017] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"149.789956ms","start":"2026-01-23T10:15:56.830726Z","end":"2026-01-23T10:15:56.980516Z","steps":["trace[293279017] 'process raft request'  (duration: 83.349447ms)","trace[293279017] 'compare'  (duration: 66.04951ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:15:56.980578Z","caller":"traceutil/trace.go:171","msg":"trace[1889466928] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"136.253237ms","start":"2026-01-23T10:15:56.844319Z","end":"2026-01-23T10:15:56.980573Z","steps":["trace[1889466928] 'process raft request'  (duration: 135.883438ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:15:56.987082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.552113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:15:56.987111Z","caller":"traceutil/trace.go:171","msg":"trace[377814529] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:252; }","duration":"108.612124ms","start":"2026-01-23T10:15:56.878492Z","end":"2026-01-23T10:15:56.987104Z","steps":["trace[377814529] 'agreement among raft nodes before linearized reading'  (duration: 108.527472ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:15:56.987182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.318692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:15:56.987194Z","caller":"traceutil/trace.go:171","msg":"trace[953894758] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:252; }","duration":"151.351712ms","start":"2026-01-23T10:15:56.835838Z","end":"2026-01-23T10:15:56.98719Z","steps":["trace[953894758] 'agreement among raft nodes before linearized reading'  (duration: 151.332382ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:09.731469Z","caller":"traceutil/trace.go:171","msg":"trace[538264503] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"104.555998ms","start":"2026-01-23T10:16:09.626881Z","end":"2026-01-23T10:16:09.731437Z","steps":["trace[538264503] 'process raft request'  (duration: 104.460756ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:09.785518Z","caller":"traceutil/trace.go:171","msg":"trace[1420843196] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"104.2046ms","start":"2026-01-23T10:16:09.681296Z","end":"2026-01-23T10:16:09.785501Z","steps":["trace[1420843196] 'read index received'  (duration: 50.250165ms)","trace[1420843196] 'applied index is now lower than readState.Index'  (duration: 53.953825ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:09.785721Z","caller":"traceutil/trace.go:171","msg":"trace[1003518896] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"158.737947ms","start":"2026-01-23T10:16:09.626975Z","end":"2026-01-23T10:16:09.785713Z","steps":["trace[1003518896] 'process raft request'  (duration: 121.814865ms)","trace[1003518896] 'compare'  (duration: 36.643376ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:09.785894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.587579ms","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-23T10:16:09.785927Z","caller":"traceutil/trace.go:171","msg":"trace[1830158526] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"104.668312ms","start":"2026-01-23T10:16:09.681252Z","end":"2026-01-23T10:16:09.785921Z","steps":["trace[1830158526] 'agreement among raft nodes before linearized reading'  (duration: 104.6056ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:14.031024Z","caller":"traceutil/trace.go:171","msg":"trace[1182916498] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"276.070666ms","start":"2026-01-23T10:16:13.754931Z","end":"2026-01-23T10:16:14.031001Z","steps":["trace[1182916498] 'process raft request'  (duration: 275.950863ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:14.031704Z","caller":"traceutil/trace.go:171","msg":"trace[1772690618] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:436; }","duration":"234.022944ms","start":"2026-01-23T10:16:13.797668Z","end":"2026-01-23T10:16:14.031691Z","steps":["trace[1772690618] 'read index received'  (duration: 234.019033ms)","trace[1772690618] 'applied index is now lower than readState.Index'  (duration: 3.281µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:14.032464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.785552ms","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-23T10:16:14.032639Z","caller":"traceutil/trace.go:171","msg":"trace[78773274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"234.972416ms","start":"2026-01-23T10:16:13.797654Z","end":"2026-01-23T10:16:14.032627Z","steps":["trace[78773274] 'agreement among raft nodes before linearized reading'  (duration: 234.755441ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:14.253865Z","caller":"traceutil/trace.go:171","msg":"trace[758281017] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"377.427849ms","start":"2026-01-23T10:16:13.876415Z","end":"2026-01-23T10:16:14.253843Z","steps":["trace[758281017] 'process raft request'  (duration: 377.267935ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:14.254392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:13.876398Z","time spent":"377.537892ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:365 > 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-23T10:16:14.255043Z","caller":"traceutil/trace.go:171","msg":"trace[1209125925] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"218.408465ms","start":"2026-01-23T10:16:14.036623Z","end":"2026-01-23T10:16:14.255032Z","steps":["trace[1209125925] 'process raft request'  (duration: 218.358344ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:14.255374Z","caller":"traceutil/trace.go:171","msg":"trace[852005247] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:438; }","duration":"222.979412ms","start":"2026-01-23T10:16:14.032385Z","end":"2026-01-23T10:16:14.255364Z","steps":["trace[852005247] 'read index received'  (duration: 222.975282ms)","trace[852005247] 'applied index is now lower than readState.Index'  (duration: 3.29µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:14.255434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.746564ms","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-23T10:16:14.255461Z","caller":"traceutil/trace.go:171","msg":"trace[2042029305] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"360.802565ms","start":"2026-01-23T10:16:13.89465Z","end":"2026-01-23T10:16:14.255453Z","steps":["trace[2042029305] 'agreement among raft nodes before linearized reading'  (duration: 360.750424ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:14.255488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:13.894636Z","time spent":"360.845667ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:16:14.255602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.468387ms","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-23T10:16:14.259103Z","caller":"traceutil/trace.go:171","msg":"trace[1420644471] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"363.979902ms","start":"2026-01-23T10:16:13.895115Z","end":"2026-01-23T10:16:14.259095Z","steps":["trace[1420644471] 'agreement among raft nodes before linearized reading'  (duration: 360.461338ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:14.259301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:13.89511Z","time spent":"364.138195ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:16:24.584272Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669913679,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T10:16:24.738769Z","caller":"traceutil/trace.go:171","msg":"trace[1009036249] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"706.042545ms","start":"2026-01-23T10:16:24.032705Z","end":"2026-01-23T10:16:24.738748Z","steps":["trace[1009036249] 'process raft request'  (duration: 705.86176ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:24.738908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.032689Z","time spent":"706.151819ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" value_size:23189 >> failure:<>"}
{"level":"info","ts":"2026-01-23T10:16:25.444869Z","caller":"traceutil/trace.go:171","msg":"trace[2044882927] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:527; }","duration":"1.361543258s","start":"2026-01-23T10:16:24.083308Z","end":"2026-01-23T10:16:25.444851Z","steps":["trace[2044882927] 'read index received'  (duration: 655.617155ms)","trace[2044882927] 'applied index is now lower than readState.Index'  (duration: 705.925363ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:25.445089Z","caller":"traceutil/trace.go:171","msg":"trace[1800130132] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.003033495s","start":"2026-01-23T10:16:24.442043Z","end":"2026-01-23T10:16:25.445076Z","steps":["trace[1800130132] 'process raft request'  (duration: 1.002714228s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.449621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.442026Z","time spent":"1.006935937s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" value_size:9240 >> failure:<>"}
{"level":"info","ts":"2026-01-23T10:16:25.44578Z","caller":"traceutil/trace.go:171","msg":"trace[601381899] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.214837525s","start":"2026-01-23T10:16:24.23093Z","end":"2026-01-23T10:16:25.445768Z","steps":["trace[601381899] 'process raft request'  (duration: 1.213643396s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.450309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.230913Z","time spent":"1.219315521s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" value_size:8170 >> failure:<>"}
{"level":"warn","ts":"2026-01-23T10:16:25.448687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365354837s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"info","ts":"2026-01-23T10:16:25.450742Z","caller":"traceutil/trace.go:171","msg":"trace[628651106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:511; }","duration":"1.367432756s","start":"2026-01-23T10:16:24.083293Z","end":"2026-01-23T10:16:25.450726Z","steps":["trace[628651106] 'agreement among raft nodes before linearized reading'  (duration: 1.365279365s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.450833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.083247Z","time spent":"1.367569409s","remote":"127.0.0.1:54932","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-23T10:16:25.448836Z","caller":"traceutil/trace.go:171","msg":"trace[676446462] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"986.193038ms","start":"2026-01-23T10:16:24.462631Z","end":"2026-01-23T10:16:25.448824Z","steps":["trace[676446462] 'process raft request'  (duration: 982.159573ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.451253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.462624Z","time spent":"988.584215ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:421 > 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-23T10:16:25.931438Z","caller":"traceutil/trace.go:171","msg":"trace[1728556732] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"1.04430143s","start":"2026-01-23T10:16:24.887117Z","end":"2026-01-23T10:16:25.931418Z","steps":["trace[1728556732] 'process raft request'  (duration: 1.044219198s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.931587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.887098Z","time spent":"1.044403982s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" mod_revision:0 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" value_size:187346 >> failure:<>"}
{"level":"info","ts":"2026-01-23T10:16:25.932045Z","caller":"traceutil/trace.go:171","msg":"trace[1079393900] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:532; }","duration":"483.169924ms","start":"2026-01-23T10:16:25.448861Z","end":"2026-01-23T10:16:25.93203Z","steps":["trace[1079393900] 'read index received'  (duration: 483.161034ms)","trace[1079393900] 'applied index is now lower than readState.Index'  (duration: 7.8µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:25.932222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.168624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"}
{"level":"info","ts":"2026-01-23T10:16:25.932261Z","caller":"traceutil/trace.go:171","msg":"trace[555570818] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"502.255356ms","start":"2026-01-23T10:16:25.429993Z","end":"2026-01-23T10:16:25.932248Z","steps":["trace[555570818] 'agreement among raft nodes before linearized reading'  (duration: 502.123613ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.932296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:25.429973Z","time spent":"502.315887ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4204,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.932509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.337051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"}
{"level":"info","ts":"2026-01-23T10:16:25.932542Z","caller":"traceutil/trace.go:171","msg":"trace[252032083] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"703.403643ms","start":"2026-01-23T10:16:25.22913Z","end":"2026-01-23T10:16:25.932534Z","steps":["trace[252032083] 'agreement among raft nodes before linearized reading'  (duration: 703.310871ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.932574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:25.229114Z","time spent":"703.452495ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":13253,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.93277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.696991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"}
{"level":"info","ts":"2026-01-23T10:16:25.932805Z","caller":"traceutil/trace.go:171","msg":"trace[784615541] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:513; }","duration":"903.752732ms","start":"2026-01-23T10:16:25.029042Z","end":"2026-01-23T10:16:25.932795Z","steps":["trace[784615541] 'agreement among raft nodes before linearized reading'  (duration: 903.66936ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.932832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:25.029032Z","time spent":"903.793854ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.932961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.186999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-23T10:16:25.932985Z","caller":"traceutil/trace.go:171","msg":"trace[1516810548] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:513; }","duration":"939.24433ms","start":"2026-01-23T10:16:24.993733Z","end":"2026-01-23T10:16:25.932978Z","steps":["trace[1516810548] 'agreement among raft nodes before linearized reading'  (duration: 939.200509ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.933008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.993715Z","time spent":"939.288471ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.933115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182988772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:25.933137Z","caller":"traceutil/trace.go:171","msg":"trace[1987688882] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:513; }","duration":"1.183042374s","start":"2026-01-23T10:16:24.750088Z","end":"2026-01-23T10:16:25.933131Z","steps":["trace[1987688882] 'agreement among raft nodes before linearized reading'  (duration: 1.183009013s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.933161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.750076Z","time spent":"1.183080284s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-01-23T10:16:25.933614Z","caller":"traceutil/trace.go:171","msg":"trace[342158381] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.176205343s","start":"2026-01-23T10:16:24.757396Z","end":"2026-01-23T10:16:25.933601Z","steps":["trace[342158381] 'process raft request'  (duration: 1.173764985s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.933872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.757378Z","time spent":"1.176267475s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" mod_revision:508 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" value_size:24132 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:25.941098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.583341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"}
{"level":"info","ts":"2026-01-23T10:16:25.941121Z","caller":"traceutil/trace.go:171","msg":"trace[89243164] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:514; }","duration":"310.609201ms","start":"2026-01-23T10:16:25.630506Z","end":"2026-01-23T10:16:25.941115Z","steps":["trace[89243164] 'agreement among raft nodes before linearized reading'  (duration: 310.52381ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.941136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:25.630443Z","time spent":"310.688904ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "}
{"level":"info","ts":"2026-01-23T10:16:25.941239Z","caller":"traceutil/trace.go:171","msg":"trace[1256904975] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"482.756595ms","start":"2026-01-23T10:16:25.458478Z","end":"2026-01-23T10:16:25.941235Z","steps":["trace[1256904975] 'process raft request'  (duration: 476.311332ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.941267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:25.458463Z","time spent":"482.786717ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:475 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:367 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:25.941354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.457301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"}
{"level":"info","ts":"2026-01-23T10:16:25.941367Z","caller":"traceutil/trace.go:171","msg":"trace[1443080398] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:514; }","duration":"111.505052ms","start":"2026-01-23T10:16:25.829858Z","end":"2026-01-23T10:16:25.941363Z","steps":["trace[1443080398] 'agreement among raft nodes before linearized reading'  (duration: 111.456271ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.941471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.312643774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:25.941484Z","caller":"traceutil/trace.go:171","msg":"trace[769686269] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:514; }","duration":"1.312679635s","start":"2026-01-23T10:16:24.628801Z","end":"2026-01-23T10:16:25.94148Z","steps":["trace[769686269] 'agreement among raft nodes before linearized reading'  (duration: 1.312657114s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.941833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261959897s","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-23T10:16:25.941852Z","caller":"traceutil/trace.go:171","msg":"trace[680735557] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:513; }","duration":"1.262003168s","start":"2026-01-23T10:16:24.679844Z","end":"2026-01-23T10:16:25.941848Z","steps":["trace[680735557] 'agreement among raft nodes before linearized reading'  (duration: 1.253411105s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.941865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.679831Z","time spent":"1.262031449s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.942075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.641576498s","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-23T10:16:25.942089Z","caller":"traceutil/trace.go:171","msg":"trace[1221801192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:514; }","duration":"1.641603148s","start":"2026-01-23T10:16:24.300481Z","end":"2026-01-23T10:16:25.942085Z","steps":["trace[1221801192] 'agreement among raft nodes before linearized reading'  (duration: 1.641572967s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.942101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.30047Z","time spent":"1.641628239s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.942159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.637586373s","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-23T10:16:25.942171Z","caller":"traceutil/trace.go:171","msg":"trace[1657331864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:514; }","duration":"1.637631614s","start":"2026-01-23T10:16:24.304535Z","end":"2026-01-23T10:16:25.942167Z","steps":["trace[1657331864] 'agreement among raft nodes before linearized reading'  (duration: 1.637610283s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.942183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.304528Z","time spent":"1.637652874s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:16:25.942287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.642661173s","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-23T10:16:25.942299Z","caller":"traceutil/trace.go:171","msg":"trace[1705126909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"1.642699744s","start":"2026-01-23T10:16:24.299596Z","end":"2026-01-23T10:16:25.942295Z","steps":["trace[1705126909] 'agreement among raft nodes before linearized reading'  (duration: 1.642676613s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:25.942311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.29957Z","time spent":"1.642738755s","remote":"127.0.0.1:54932","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-23T10:16:25.942378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:24.628786Z","time spent":"1.312707605s","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "}
{"level":"info","ts":"2026-01-23T10:16:26.657796Z","caller":"traceutil/trace.go:171","msg":"trace[2143539256] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:545; }","duration":"228.576365ms","start":"2026-01-23T10:16:26.429203Z","end":"2026-01-23T10:16:26.657779Z","steps":["trace[2143539256] 'read index received'  (duration: 228.169915ms)","trace[2143539256] 'applied index is now lower than readState.Index'  (duration: 405.94µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:26.658052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.822201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"}
{"level":"info","ts":"2026-01-23T10:16:26.658076Z","caller":"traceutil/trace.go:171","msg":"trace[341685261] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:526; }","duration":"228.871402ms","start":"2026-01-23T10:16:26.429197Z","end":"2026-01-23T10:16:26.658069Z","steps":["trace[341685261] 'agreement among raft nodes before linearized reading'  (duration: 228.653577ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:26.658484Z","caller":"traceutil/trace.go:171","msg":"trace[32244547] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"374.266935ms","start":"2026-01-23T10:16:26.284211Z","end":"2026-01-23T10:16:26.658478Z","steps":["trace[32244547] 'process raft request'  (duration: 373.25601ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:26.658553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:26.284198Z","time spent":"374.317116ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" mod_revision:525 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" value_size:188371 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" > >"}
{"level":"info","ts":"2026-01-23T10:16:27.308808Z","caller":"traceutil/trace.go:171","msg":"trace[2088805802] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"279.18798ms","start":"2026-01-23T10:16:27.029606Z","end":"2026-01-23T10:16:27.308794Z","steps":["trace[2088805802] 'read index received'  (duration: 278.72052ms)","trace[2088805802] 'applied index is now lower than readState.Index'  (duration: 465.18µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:27.308915Z","caller":"traceutil/trace.go:171","msg":"trace[883193882] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"321.627902ms","start":"2026-01-23T10:16:26.987279Z","end":"2026-01-23T10:16:27.308907Z","steps":["trace[883193882] 'process raft request'  (duration: 321.240533ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:27.309007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:26.98726Z","time spent":"321.677653ms","remote":"127.0.0.1:54742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" mod_revision:528 > success:<request_put:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" value_size:188139 >> failure:<request_range:<key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:27.309387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.787544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"}
{"level":"info","ts":"2026-01-23T10:16:27.309408Z","caller":"traceutil/trace.go:171","msg":"trace[1927580040] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:529; }","duration":"279.835596ms","start":"2026-01-23T10:16:27.029565Z","end":"2026-01-23T10:16:27.309401Z","steps":["trace[1927580040] 'agreement among raft nodes before linearized reading'  (duration: 279.640581ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:34.341117Z","caller":"traceutil/trace.go:171","msg":"trace[917419944] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"340.912576ms","start":"2026-01-23T10:16:34.000189Z","end":"2026-01-23T10:16:34.341101Z","steps":["trace[917419944] 'read index received'  (duration: 340.782003ms)","trace[917419944] 'applied index is now lower than readState.Index'  (duration: 130.043µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:34.341241Z","caller":"traceutil/trace.go:171","msg":"trace[85191206] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"350.3546ms","start":"2026-01-23T10:16:33.990881Z","end":"2026-01-23T10:16:34.341236Z","steps":["trace[85191206] 'process raft request'  (duration: 350.126615ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.34132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:33.990854Z","time spent":"350.414762ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:543 > 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":"warn","ts":"2026-01-23T10:16:34.341588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.684336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-01-23T10:16:34.341735Z","caller":"traceutil/trace.go:171","msg":"trace[129660793] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:548; }","duration":"297.83505ms","start":"2026-01-23T10:16:34.043878Z","end":"2026-01-23T10:16:34.341713Z","steps":["trace[129660793] 'agreement among raft nodes before linearized reading'  (duration: 297.642886ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.341868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.678424ms","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-23T10:16:34.341901Z","caller":"traceutil/trace.go:171","msg":"trace[1284018957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:548; }","duration":"341.733796ms","start":"2026-01-23T10:16:34.000162Z","end":"2026-01-23T10:16:34.341896Z","steps":["trace[1284018957] 'agreement among raft nodes before linearized reading'  (duration: 341.655794ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.341925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.000154Z","time spent":"341.766697ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:16:34.341939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.406188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"warn","ts":"2026-01-23T10:16:34.341565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.564865ms","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-23T10:16:34.342461Z","caller":"traceutil/trace.go:171","msg":"trace[45525233] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:548; }","duration":"329.535138ms","start":"2026-01-23T10:16:34.012917Z","end":"2026-01-23T10:16:34.342453Z","steps":["trace[45525233] 'agreement among raft nodes before linearized reading'  (duration: 328.483563ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.342585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.012898Z","time spent":"329.678472ms","remote":"127.0.0.1:54932","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-23T10:16:34.342018Z","caller":"traceutil/trace.go:171","msg":"trace[580894405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:548; }","duration":"299.498289ms","start":"2026-01-23T10:16:34.042481Z","end":"2026-01-23T10:16:34.341979Z","steps":["trace[580894405] 'agreement among raft nodes before linearized reading'  (duration: 299.335355ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.343051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.042471Z","time spent":"300.561514ms","remote":"127.0.0.1:54932","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":"info","ts":"2026-01-23T10:16:34.981326Z","caller":"traceutil/trace.go:171","msg":"trace[1891122175] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"634.693611ms","start":"2026-01-23T10:16:34.346613Z","end":"2026-01-23T10:16:34.981307Z","steps":["trace[1891122175] 'process raft request'  (duration: 634.589839ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:34.98145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.346596Z","time spent":"634.792984ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:544 > 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-23T10:16:35.014457Z","caller":"traceutil/trace.go:171","msg":"trace[430586144] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"666.506152ms","start":"2026-01-23T10:16:34.347935Z","end":"2026-01-23T10:16:35.014441Z","steps":["trace[430586144] 'process raft request'  (duration: 666.367288ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:35.014573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.347917Z","time spent":"666.589274ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:545 > 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-23T10:16:35.01465Z","caller":"traceutil/trace.go:171","msg":"trace[1157203970] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"664.084735ms","start":"2026-01-23T10:16:34.35055Z","end":"2026-01-23T10:16:35.014635Z","steps":["trace[1157203970] 'process raft request'  (duration: 663.840429ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:35.014732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.350537Z","time spent":"664.152416ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:547 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-01-23T10:16:35.014836Z","caller":"traceutil/trace.go:171","msg":"trace[1512163502] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"268.226292ms","start":"2026-01-23T10:16:34.746603Z","end":"2026-01-23T10:16:35.014829Z","steps":["trace[1512163502] 'read index received'  (duration: 235.193932ms)","trace[1512163502] 'applied index is now lower than readState.Index'  (duration: 33.03181ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:35.014892Z","caller":"traceutil/trace.go:171","msg":"trace[409037679] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"664.102775ms","start":"2026-01-23T10:16:34.350785Z","end":"2026-01-23T10:16:35.014887Z","steps":["trace[409037679] 'process raft request'  (duration: 663.797508ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:35.01492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:34.350774Z","time spent":"664.128175ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:546 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:35.014983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.388175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:35.014999Z","caller":"traceutil/trace.go:171","msg":"trace[1575370459] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"268.429136ms","start":"2026-01-23T10:16:34.746565Z","end":"2026-01-23T10:16:35.014994Z","steps":["trace[1575370459] 'agreement among raft nodes before linearized reading'  (duration: 268.398366ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:41.192163Z","caller":"traceutil/trace.go:171","msg":"trace[1164422840] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"143.290862ms","start":"2026-01-23T10:16:41.048855Z","end":"2026-01-23T10:16:41.192146Z","steps":["trace[1164422840] 'read index received'  (duration: 135.14105ms)","trace[1164422840] 'applied index is now lower than readState.Index'  (duration: 8.149232ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:41.192221Z","caller":"traceutil/trace.go:171","msg":"trace[1383790272] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"146.605021ms","start":"2026-01-23T10:16:41.045612Z","end":"2026-01-23T10:16:41.192217Z","steps":["trace[1383790272] 'process raft request'  (duration: 138.350836ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:41.192403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.539328ms","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-23T10:16:41.192421Z","caller":"traceutil/trace.go:171","msg":"trace[1267188743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"143.588179ms","start":"2026-01-23T10:16:41.048827Z","end":"2026-01-23T10:16:41.192415Z","steps":["trace[1267188743] 'agreement among raft nodes before linearized reading'  (duration: 143.485727ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:41.195658Z","caller":"traceutil/trace.go:171","msg":"trace[2114201556] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"142.599916ms","start":"2026-01-23T10:16:41.05305Z","end":"2026-01-23T10:16:41.19565Z","steps":["trace[2114201556] 'process raft request'  (duration: 140.158909ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:41.196346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.287705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-01-23T10:16:41.196387Z","caller":"traceutil/trace.go:171","msg":"trace[1006912306] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:593; }","duration":"108.370677ms","start":"2026-01-23T10:16:41.088006Z","end":"2026-01-23T10:16:41.196377Z","steps":["trace[1006912306] 'agreement among raft nodes before linearized reading'  (duration: 108.244364ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:41.196559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.894642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-01-23T10:16:41.196572Z","caller":"traceutil/trace.go:171","msg":"trace[1509900788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:593; }","duration":"125.945633ms","start":"2026-01-23T10:16:41.070623Z","end":"2026-01-23T10:16:41.196568Z","steps":["trace[1509900788] 'agreement among raft nodes before linearized reading'  (duration: 125.457771ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.701947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669913898,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T10:16:43.946815Z","caller":"traceutil/trace.go:171","msg":"trace[1641883668] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"745.609669ms","start":"2026-01-23T10:16:43.201185Z","end":"2026-01-23T10:16:43.946794Z","steps":["trace[1641883668] 'read index received'  (duration: 745.432215ms)","trace[1641883668] 'applied index is now lower than readState.Index'  (duration: 174.314µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:43.947084Z","caller":"traceutil/trace.go:171","msg":"trace[567425827] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"913.334388ms","start":"2026-01-23T10:16:43.033739Z","end":"2026-01-23T10:16:43.947073Z","steps":["trace[567425827] 'process raft request'  (duration: 912.942439ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.947187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.033725Z","time spent":"913.385789ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:590 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:43.947455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.257565ms","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-23T10:16:43.947486Z","caller":"traceutil/trace.go:171","msg":"trace[1098769458] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"746.300966ms","start":"2026-01-23T10:16:43.201175Z","end":"2026-01-23T10:16:43.947476Z","steps":["trace[1098769458] 'agreement among raft nodes before linearized reading'  (duration: 746.181213ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.947525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.201126Z","time spent":"746.392398ms","remote":"127.0.0.1:54932","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-23T10:16:43.948155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.71861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-01-23T10:16:43.948191Z","caller":"traceutil/trace.go:171","msg":"trace[2100841024] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:603; }","duration":"739.788822ms","start":"2026-01-23T10:16:43.208392Z","end":"2026-01-23T10:16:43.948181Z","steps":["trace[2100841024] 'agreement among raft nodes before linearized reading'  (duration: 739.68619ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.94822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.208358Z","time spent":"739.855004ms","remote":"127.0.0.1:54932","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-23T10:16:43.948295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.429713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:43.948319Z","caller":"traceutil/trace.go:171","msg":"trace[1082549875] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"203.483983ms","start":"2026-01-23T10:16:43.744827Z","end":"2026-01-23T10:16:43.948311Z","steps":["trace[1082549875] 'agreement among raft nodes before linearized reading'  (duration: 203.449242ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.948466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.320955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:43.948493Z","caller":"traceutil/trace.go:171","msg":"trace[1258493155] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:603; }","duration":"368.358785ms","start":"2026-01-23T10:16:43.580127Z","end":"2026-01-23T10:16:43.948485Z","steps":["trace[1258493155] 'agreement among raft nodes before linearized reading'  (duration: 368.318744ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.948516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.580122Z","time spent":"368.388696ms","remote":"127.0.0.1:39262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "}
{"level":"warn","ts":"2026-01-23T10:16:43.94879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.608351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:43.948824Z","caller":"traceutil/trace.go:171","msg":"trace[1719980179] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:0; response_revision:603; }","duration":"368.802406ms","start":"2026-01-23T10:16:43.580012Z","end":"2026-01-23T10:16:43.948814Z","steps":["trace[1719980179] 'agreement among raft nodes before linearized reading'  (duration: 368.626392ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.948852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.579997Z","time spent":"368.847827ms","remote":"127.0.0.1:39262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodes/instance\" "}
{"level":"warn","ts":"2026-01-23T10:16:43.949045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.55282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-01-23T10:16:43.949071Z","caller":"traceutil/trace.go:171","msg":"trace[1104846471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"740.588011ms","start":"2026-01-23T10:16:43.208475Z","end":"2026-01-23T10:16:43.949063Z","steps":["trace[1104846471] 'agreement among raft nodes before linearized reading'  (duration: 740.496868ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:43.949097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:43.208472Z","time spent":"740.617731ms","remote":"127.0.0.1:54932","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-23T10:16:46.894398Z","caller":"traceutil/trace.go:171","msg":"trace[1824969659] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"117.292468ms","start":"2026-01-23T10:16:46.777085Z","end":"2026-01-23T10:16:46.894378Z","steps":["trace[1824969659] 'process raft request'  (duration: 117.134904ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:47.323906Z","caller":"traceutil/trace.go:171","msg":"trace[1174592864] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"483.856952ms","start":"2026-01-23T10:16:46.840034Z","end":"2026-01-23T10:16:47.323891Z","steps":["trace[1174592864] 'process raft request'  (duration: 483.77253ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:47.323999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:46.840016Z","time spent":"483.944153ms","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-77cccfdc44-dq8qd\" mod_revision:0 > success:<request_put:<key:\"/registry/cilium.io/ciliumendpoints/kube-system/coredns-77cccfdc44-dq8qd\" value_size:2181 >> failure:<>"}
{"level":"warn","ts":"2026-01-23T10:16:48.895336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669913976,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T10:16:49.139749Z","caller":"traceutil/trace.go:171","msg":"trace[1382111559] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"825.870994ms","start":"2026-01-23T10:16:48.31386Z","end":"2026-01-23T10:16:49.139731Z","steps":["trace[1382111559] 'process raft request'  (duration: 825.773772ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:49.139847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:48.313839Z","time spent":"825.961176ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-5dd5756b68-7ljw7.188d54bf160c4db3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/coredns-5dd5756b68-7ljw7.188d54bf160c4db3\" value_size:692 lease:9197929244815137279 >> failure:<>"}
{"level":"info","ts":"2026-01-23T10:16:49.140682Z","caller":"traceutil/trace.go:171","msg":"trace[1944953921] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"745.983287ms","start":"2026-01-23T10:16:48.394687Z","end":"2026-01-23T10:16:49.140671Z","steps":["trace[1944953921] 'read index received'  (duration: 745.574938ms)","trace[1944953921] 'applied index is now lower than readState.Index'  (duration: 407.779µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:49.140813Z","caller":"traceutil/trace.go:171","msg":"trace[1515209796] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"781.916265ms","start":"2026-01-23T10:16:48.35889Z","end":"2026-01-23T10:16:49.140807Z","steps":["trace[1515209796] 'process raft request'  (duration: 781.717181ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:49.140858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:48.358869Z","time spent":"781.960887ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:574 > 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-23T10:16:49.141009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.321396ms","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-23T10:16:49.14103Z","caller":"traceutil/trace.go:171","msg":"trace[1782245537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:643; }","duration":"746.370066ms","start":"2026-01-23T10:16:48.394654Z","end":"2026-01-23T10:16:49.141024Z","steps":["trace[1782245537] 'agreement among raft nodes before linearized reading'  (duration: 746.281634ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:49.141048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:48.394644Z","time spent":"746.399927ms","remote":"127.0.0.1:54932","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-23T10:16:49.141119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.436896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:49.141131Z","caller":"traceutil/trace.go:171","msg":"trace[1473062959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"393.484927ms","start":"2026-01-23T10:16:48.747642Z","end":"2026-01-23T10:16:49.141127Z","steps":["trace[1473062959] 'agreement among raft nodes before linearized reading'  (duration: 393.466926ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:49.141143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:48.747627Z","time spent":"393.513158ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-01-23T10:16:57.277141Z","caller":"traceutil/trace.go:171","msg":"trace[1018599530] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"107.229438ms","start":"2026-01-23T10:16:57.169892Z","end":"2026-01-23T10:16:57.277121Z","steps":["trace[1018599530] 'process raft request'  (duration: 107.136286ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:16:58.208988Z","caller":"traceutil/trace.go:171","msg":"trace[1893309197] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"155.256534ms","start":"2026-01-23T10:16:58.053716Z","end":"2026-01-23T10:16:58.208972Z","steps":["trace[1893309197] 'process raft request'  (duration: 143.367873ms)","trace[1893309197] 'compare'  (duration: 11.685826ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:58.45728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.431834ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914106 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:696 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:16:58.457341Z","caller":"traceutil/trace.go:171","msg":"trace[1014542230] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"396.521456ms","start":"2026-01-23T10:16:58.060811Z","end":"2026-01-23T10:16:58.457332Z","steps":["trace[1014542230] 'read index received'  (duration: 136.289497ms)","trace[1014542230] 'applied index is now lower than readState.Index'  (duration: 260.230989ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:16:58.457447Z","caller":"traceutil/trace.go:171","msg":"trace[273928091] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"401.698348ms","start":"2026-01-23T10:16:58.055743Z","end":"2026-01-23T10:16:58.457441Z","steps":["trace[273928091] 'process raft request'  (duration: 290.968116ms)","trace[273928091] 'compare'  (duration: 110.373893ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:16:58.457479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:58.055729Z","time spent":"401.731908ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:696 > success:<request_put:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" value_size:410 >> failure:<request_range:<key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" > >"}
{"level":"warn","ts":"2026-01-23T10:16:58.457624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.812563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-01-23T10:16:58.457643Z","caller":"traceutil/trace.go:171","msg":"trace[125731057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:703; }","duration":"396.851343ms","start":"2026-01-23T10:16:58.060787Z","end":"2026-01-23T10:16:58.457639Z","steps":["trace[125731057] 'agreement among raft nodes before linearized reading'  (duration: 396.774191ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:58.457662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:58.060777Z","time spent":"396.880334ms","remote":"127.0.0.1:54932","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-23T10:16:58.459204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.368572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:16:58.459231Z","caller":"traceutil/trace.go:171","msg":"trace[585962940] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:704; }","duration":"317.439154ms","start":"2026-01-23T10:16:58.141786Z","end":"2026-01-23T10:16:58.459225Z","steps":["trace[585962940] 'agreement among raft nodes before linearized reading'  (duration: 317.390773ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:58.459249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:16:58.14176Z","time spent":"317.483325ms","remote":"127.0.0.1:54826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "}
{"level":"info","ts":"2026-01-23T10:16:58.459387Z","caller":"traceutil/trace.go:171","msg":"trace[708996307] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"167.623318ms","start":"2026-01-23T10:16:58.291757Z","end":"2026-01-23T10:16:58.45938Z","steps":["trace[708996307] 'process raft request'  (duration: 167.385322ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:16:59.451297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.781942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"info","ts":"2026-01-23T10:16:59.451341Z","caller":"traceutil/trace.go:171","msg":"trace[738490856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:706; }","duration":"170.833383ms","start":"2026-01-23T10:16:59.280497Z","end":"2026-01-23T10:16:59.451331Z","steps":["trace[738490856] 'range keys from in-memory index tree'  (duration: 170.674889ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:10.894546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.80784ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914218 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:738 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:17:10.89464Z","caller":"traceutil/trace.go:171","msg":"trace[1191255592] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"356.611471ms","start":"2026-01-23T10:17:10.538012Z","end":"2026-01-23T10:17:10.894623Z","steps":["trace[1191255592] 'process raft request'  (duration: 55.636337ms)","trace[1191255592] 'compare'  (duration: 300.727168ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:10.894695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:10.537999Z","time spent":"356.670922ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:738 > 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":"info","ts":"2026-01-23T10:17:12.365549Z","caller":"traceutil/trace.go:171","msg":"trace[1259012226] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"101.361969ms","start":"2026-01-23T10:17:12.264169Z","end":"2026-01-23T10:17:12.365531Z","steps":["trace[1259012226] 'process raft request'  (duration: 101.232356ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:12.699181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.454971ms","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-23T10:17:12.699512Z","caller":"traceutil/trace.go:171","msg":"trace[1252388733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"191.948963ms","start":"2026-01-23T10:17:12.507545Z","end":"2026-01-23T10:17:12.699494Z","steps":["trace[1252388733] 'range keys from in-memory index tree'  (duration: 190.490719ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:13.146363Z","caller":"traceutil/trace.go:171","msg":"trace[1498071653] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"258.028167ms","start":"2026-01-23T10:17:12.888312Z","end":"2026-01-23T10:17:13.14634Z","steps":["trace[1498071653] 'read index received'  (duration: 257.874263ms)","trace[1498071653] 'applied index is now lower than readState.Index'  (duration: 153.064µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:13.146835Z","caller":"traceutil/trace.go:171","msg":"trace[1987446369] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"442.499644ms","start":"2026-01-23T10:17:12.70432Z","end":"2026-01-23T10:17:13.14682Z","steps":["trace[1987446369] 'process raft request'  (duration: 441.919919ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:13.147272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:12.704298Z","time spent":"442.578935ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:743 > 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-23T10:17:13.147946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.618085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T10:17:13.147996Z","caller":"traceutil/trace.go:171","msg":"trace[372751233] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:748; }","duration":"259.698397ms","start":"2026-01-23T10:17:12.888285Z","end":"2026-01-23T10:17:13.147984Z","steps":["trace[372751233] 'agreement among raft nodes before linearized reading'  (duration: 259.456331ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:13.148522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.817779ms","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-23T10:17:13.148592Z","caller":"traceutil/trace.go:171","msg":"trace[1087316658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"240.929672ms","start":"2026-01-23T10:17:12.907643Z","end":"2026-01-23T10:17:13.148573Z","steps":["trace[1087316658] 'agreement among raft nodes before linearized reading'  (duration: 240.704647ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:13.686989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.116328ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914232 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:744 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:17:13.687378Z","caller":"traceutil/trace.go:171","msg":"trace[1276672155] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"532.977766ms","start":"2026-01-23T10:17:13.154373Z","end":"2026-01-23T10:17:13.687351Z","steps":["trace[1276672155] 'process raft request'  (duration: 179.425497ms)","trace[1276672155] 'compare'  (duration: 352.230177ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:13.687647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:13.154355Z","time spent":"533.183901ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:744 > 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":"info","ts":"2026-01-23T10:17:13.687084Z","caller":"traceutil/trace.go:171","msg":"trace[26549358] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"195.748012ms","start":"2026-01-23T10:17:13.49132Z","end":"2026-01-23T10:17:13.687068Z","steps":["trace[26549358] 'read index received'  (duration: 51.031µs)","trace[26549358] 'applied index is now lower than readState.Index'  (duration: 195.695491ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:13.689114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.7837ms","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-23T10:17:13.689158Z","caller":"traceutil/trace.go:171","msg":"trace[560144187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:749; }","duration":"197.858712ms","start":"2026-01-23T10:17:13.491281Z","end":"2026-01-23T10:17:13.689139Z","steps":["trace[560144187] 'agreement among raft nodes before linearized reading'  (duration: 197.699358ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:14.144226Z","caller":"traceutil/trace.go:171","msg":"trace[412075976] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"396.235029ms","start":"2026-01-23T10:17:13.74797Z","end":"2026-01-23T10:17:14.144205Z","steps":["trace[412075976] 'read index received'  (duration: 326.16859ms)","trace[412075976] 'applied index is now lower than readState.Index'  (duration: 70.065399ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:14.144321Z","caller":"traceutil/trace.go:171","msg":"trace[1361503828] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"449.844278ms","start":"2026-01-23T10:17:13.694457Z","end":"2026-01-23T10:17:14.144301Z","steps":["trace[1361503828] 'process raft request'  (duration: 379.700878ms)","trace[1361503828] 'compare'  (duration: 69.872884ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:14.14436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.375842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:14.14439Z","caller":"traceutil/trace.go:171","msg":"trace[1591616680] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:750; }","duration":"396.451344ms","start":"2026-01-23T10:17:13.747929Z","end":"2026-01-23T10:17:14.14438Z","steps":["trace[1591616680] 'agreement among raft nodes before linearized reading'  (duration: 396.392282ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:14.144417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:13.747911Z","time spent":"396.499455ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:17:14.144475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:13.694442Z","time spent":"449.93467ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:745 > 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":"warn","ts":"2026-01-23T10:17:14.812401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.819669ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914237 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:746 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:17:14.8132Z","caller":"traceutil/trace.go:171","msg":"trace[332961912] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"663.088463ms","start":"2026-01-23T10:17:14.150082Z","end":"2026-01-23T10:17:14.81317Z","steps":["trace[332961912] 'read index received'  (duration: 454.385164ms)","trace[332961912] 'applied index is now lower than readState.Index'  (duration: 208.697829ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:14.81357Z","caller":"traceutil/trace.go:171","msg":"trace[583572587] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"663.75893ms","start":"2026-01-23T10:17:14.149801Z","end":"2026-01-23T10:17:14.81356Z","steps":["trace[583572587] 'process raft request'  (duration: 454.714202ms)","trace[583572587] 'compare'  (duration: 207.651525ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:14.813769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:14.149781Z","time spent":"663.814841ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:746 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T10:17:14.813966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.881643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:14.813996Z","caller":"traceutil/trace.go:171","msg":"trace[663462679] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:751; }","duration":"663.933994ms","start":"2026-01-23T10:17:14.150055Z","end":"2026-01-23T10:17:14.813989Z","steps":["trace[663462679] 'agreement among raft nodes before linearized reading'  (duration: 663.877333ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:14.814015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:14.150043Z","time spent":"663.967705ms","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:17:14.81422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.846318ms","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-23T10:17:14.814243Z","caller":"traceutil/trace.go:171","msg":"trace[310835718] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:751; }","duration":"444.90794ms","start":"2026-01-23T10:17:14.36933Z","end":"2026-01-23T10:17:14.814238Z","steps":["trace[310835718] 'agreement among raft nodes before linearized reading'  (duration: 444.796437ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:14.81426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:14.369309Z","time spent":"444.946391ms","remote":"127.0.0.1:54932","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-23T10:17:25.474139Z","caller":"traceutil/trace.go:171","msg":"trace[2048687468] transaction","detail":"{read_only:false; response_revision:834; number_of_response:1; }","duration":"233.123228ms","start":"2026-01-23T10:17:25.240999Z","end":"2026-01-23T10:17:25.474122Z","steps":["trace[2048687468] 'process raft request'  (duration: 232.981445ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:26.298261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669914428,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:17:26.799013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669914428,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T10:17:26.995188Z","caller":"traceutil/trace.go:171","msg":"trace[509234245] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"1.208039102s","start":"2026-01-23T10:17:25.787123Z","end":"2026-01-23T10:17:26.995162Z","steps":["trace[509234245] 'process raft request'  (duration: 1.20793899s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:26.995334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:25.787113Z","time spent":"1.208125874s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:809 > 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":"info","ts":"2026-01-23T10:17:27.028699Z","caller":"traceutil/trace.go:171","msg":"trace[1116620794] linearizableReadLoop","detail":"{readStateIndex:873; appliedIndex:872; }","duration":"1.230623257s","start":"2026-01-23T10:17:25.79806Z","end":"2026-01-23T10:17:27.028684Z","steps":["trace[1116620794] 'read index received'  (duration: 1.197672857s)","trace[1116620794] 'applied index is now lower than readState.Index'  (duration: 32.94983ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:27.028937Z","caller":"traceutil/trace.go:171","msg":"trace[306139440] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"311.81321ms","start":"2026-01-23T10:17:26.717119Z","end":"2026-01-23T10:17:27.028932Z","steps":["trace[306139440] 'process raft request'  (duration: 311.466091ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:26.717101Z","time spent":"311.856421ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:757 > 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-23T10:17:27.029117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231052707s","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-23T10:17:27.029141Z","caller":"traceutil/trace.go:171","msg":"trace[838802576] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:836; }","duration":"1.231078807s","start":"2026-01-23T10:17:25.798055Z","end":"2026-01-23T10:17:27.029134Z","steps":["trace[838802576] 'agreement among raft nodes before linearized reading'  (duration: 1.231037826s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:25.798044Z","time spent":"1.231108488s","remote":"127.0.0.1:55156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:17:27.029337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.713972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"}
{"level":"info","ts":"2026-01-23T10:17:27.029355Z","caller":"traceutil/trace.go:171","msg":"trace[1041625065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:836; }","duration":"778.758602ms","start":"2026-01-23T10:17:26.250592Z","end":"2026-01-23T10:17:27.029351Z","steps":["trace[1041625065] 'agreement among raft nodes before linearized reading'  (duration: 778.691191ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:26.250578Z","time spent":"778.786273ms","remote":"127.0.0.1:54932","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-23T10:17:27.029488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.857701ms","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-23T10:17:27.029506Z","caller":"traceutil/trace.go:171","msg":"trace[1879701527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:836; }","duration":"104.876782ms","start":"2026-01-23T10:17:26.924626Z","end":"2026-01-23T10:17:27.029503Z","steps":["trace[1879701527] 'agreement among raft nodes before linearized reading'  (duration: 104.817821ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.606122ms","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-23T10:17:27.029597Z","caller":"traceutil/trace.go:171","msg":"trace[40450761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:836; }","duration":"133.658383ms","start":"2026-01-23T10:17:26.895935Z","end":"2026-01-23T10:17:27.029594Z","steps":["trace[40450761] 'agreement among raft nodes before linearized reading'  (duration: 133.617152ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.278881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:27.029669Z","caller":"traceutil/trace.go:171","msg":"trace[1020935733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:836; }","duration":"285.327433ms","start":"2026-01-23T10:17:26.744338Z","end":"2026-01-23T10:17:27.029666Z","steps":["trace[1020935733] 'agreement among raft nodes before linearized reading'  (duration: 285.305612ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.864044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:27.029755Z","caller":"traceutil/trace.go:171","msg":"trace[281306392] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:836; }","duration":"361.909376ms","start":"2026-01-23T10:17:26.667842Z","end":"2026-01-23T10:17:27.029752Z","steps":["trace[281306392] 'agreement among raft nodes before linearized reading'  (duration: 361.885515ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:26.66783Z","time spent":"361.952166ms","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:17:27.029862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.11906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T10:17:27.029878Z","caller":"traceutil/trace.go:171","msg":"trace[1912307745] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:836; }","duration":"452.160232ms","start":"2026-01-23T10:17:26.577714Z","end":"2026-01-23T10:17:27.029874Z","steps":["trace[1912307745] 'agreement among raft nodes before linearized reading'  (duration: 452.132841ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:27.029891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:26.577701Z","time spent":"452.185502ms","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "}
{"level":"info","ts":"2026-01-23T10:17:27.657784Z","caller":"traceutil/trace.go:171","msg":"trace[1224021410] transaction","detail":"{read_only:false; response_revision:840; number_of_response:1; }","duration":"177.582274ms","start":"2026-01-23T10:17:27.480188Z","end":"2026-01-23T10:17:27.65777Z","steps":["trace[1224021410] 'process raft request'  (duration: 177.478182ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:32.754811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.424482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:32.754911Z","caller":"traceutil/trace.go:171","msg":"trace[1408899319] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:851; }","duration":"562.567806ms","start":"2026-01-23T10:17:32.192323Z","end":"2026-01-23T10:17:32.754891Z","steps":["trace[1408899319] 'count revisions from in-memory index tree'  (duration: 562.358961ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:32.754962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:32.192284Z","time spent":"562.663748ms","remote":"127.0.0.1:54834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:17:32.755427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"943.397428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T10:17:32.75549Z","caller":"traceutil/trace.go:171","msg":"trace[99152339] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:851; }","duration":"943.49195ms","start":"2026-01-23T10:17:31.81198Z","end":"2026-01-23T10:17:32.755472Z","steps":["trace[99152339] 'count revisions from in-memory index tree'  (duration: 943.354867ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:32.755521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:31.811953Z","time spent":"943.560873ms","remote":"127.0.0.1:54802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "}
{"level":"info","ts":"2026-01-23T10:17:33.160999Z","caller":"traceutil/trace.go:171","msg":"trace[213853951] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"142.140884ms","start":"2026-01-23T10:17:33.01882Z","end":"2026-01-23T10:17:33.160961Z","steps":["trace[213853951] 'process raft request'  (duration: 141.94164ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:33.536211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.030876ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914477 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:849 > 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-23T10:17:33.536469Z","caller":"traceutil/trace.go:171","msg":"trace[1915038018] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"360.851501ms","start":"2026-01-23T10:17:33.175598Z","end":"2026-01-23T10:17:33.536449Z","steps":["trace[1915038018] 'process raft request'  (duration: 360.81197ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:33.536536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:33.175591Z","time spent":"360.914053ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:850 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:367 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-01-23T10:17:33.536943Z","caller":"traceutil/trace.go:171","msg":"trace[588792305] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"362.135771ms","start":"2026-01-23T10:17:33.174791Z","end":"2026-01-23T10:17:33.536926Z","steps":["trace[588792305] 'process raft request'  (duration: 98.272156ms)","trace[588792305] 'compare'  (duration: 262.733209ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:33.536999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:33.174771Z","time spent":"362.198823ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:849 > 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-23T10:17:33.537115Z","caller":"traceutil/trace.go:171","msg":"trace[1733525924] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"362.119451ms","start":"2026-01-23T10:17:33.174987Z","end":"2026-01-23T10:17:33.537107Z","steps":["trace[1733525924] 'process raft request'  (duration: 361.345023ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:33.537162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:33.17496Z","time spent":"362.176682ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:848 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"warn","ts":"2026-01-23T10:17:33.885821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.99157ms","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-23T10:17:33.885918Z","caller":"traceutil/trace.go:171","msg":"trace[347197160] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"202.153114ms","start":"2026-01-23T10:17:33.683747Z","end":"2026-01-23T10:17:33.8859Z","steps":["trace[347197160] 'range keys from in-memory index tree'  (duration: 201.742005ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:35.388863Z","caller":"traceutil/trace.go:171","msg":"trace[523295262] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"210.619365ms","start":"2026-01-23T10:17:35.178229Z","end":"2026-01-23T10:17:35.388848Z","steps":["trace[523295262] 'read index received'  (duration: 210.518693ms)","trace[523295262] 'applied index is now lower than readState.Index'  (duration: 99.932µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:35.388997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.752318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"}
{"level":"info","ts":"2026-01-23T10:17:35.389012Z","caller":"traceutil/trace.go:171","msg":"trace[46350237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"210.807339ms","start":"2026-01-23T10:17:35.1782Z","end":"2026-01-23T10:17:35.389007Z","steps":["trace[46350237] 'agreement among raft nodes before linearized reading'  (duration: 210.712567ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:35.38919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:35.000601Z","time spent":"388.587127ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-23T10:17:35.495797Z","caller":"traceutil/trace.go:171","msg":"trace[1773970282] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"102.327321ms","start":"2026-01-23T10:17:35.393447Z","end":"2026-01-23T10:17:35.495774Z","steps":["trace[1773970282] 'process raft request'  (duration: 102.198528ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:35.820202Z","caller":"traceutil/trace.go:171","msg":"trace[1567212471] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"177.512121ms","start":"2026-01-23T10:17:35.642671Z","end":"2026-01-23T10:17:35.820183Z","steps":["trace[1567212471] 'process raft request'  (duration: 177.430309ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:36.075748Z","caller":"traceutil/trace.go:171","msg":"trace[1208301230] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"410.124308ms","start":"2026-01-23T10:17:35.665601Z","end":"2026-01-23T10:17:36.075725Z","steps":["trace[1208301230] 'process raft request'  (duration: 290.534807ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:36.080885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:35.665585Z","time spent":"415.246368ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:853 > 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-23T10:17:36.081056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.609844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:36.081084Z","caller":"traceutil/trace.go:171","msg":"trace[105896147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:861; }","duration":"308.653655ms","start":"2026-01-23T10:17:35.772415Z","end":"2026-01-23T10:17:36.081069Z","steps":["trace[105896147] 'agreement among raft nodes before linearized reading'  (duration: 308.614784ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:36.081105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:35.772404Z","time spent":"308.696266ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-01-23T10:17:35.956339Z","caller":"traceutil/trace.go:171","msg":"trace[1521329774] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:898; }","duration":"183.881032ms","start":"2026-01-23T10:17:35.772444Z","end":"2026-01-23T10:17:35.956325Z","steps":["trace[1521329774] 'read index received'  (duration: 48.061017ms)","trace[1521329774] 'applied index is now lower than readState.Index'  (duration: 135.819365ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:36.081786Z","caller":"traceutil/trace.go:171","msg":"trace[1444979058] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"430.366767ms","start":"2026-01-23T10:17:35.651407Z","end":"2026-01-23T10:17:36.081774Z","steps":["trace[1444979058] 'process raft request'  (duration: 208.790542ms)","trace[1444979058] 'compare'  (duration: 95.837298ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:36.081819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:35.6514Z","time spent":"430.399527ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:854 > 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-23T10:17:36.172009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.380626ms","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-23T10:17:36.172116Z","caller":"traceutil/trace.go:171","msg":"trace[692974897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:861; }","duration":"239.51873ms","start":"2026-01-23T10:17:35.932575Z","end":"2026-01-23T10:17:36.172094Z","steps":["trace[692974897] 'agreement among raft nodes before linearized reading'  (duration: 149.144671ms)","trace[692974897] 'range keys from in-memory index tree'  (duration: 90.079271ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:42.139523Z","caller":"traceutil/trace.go:171","msg":"trace[449547427] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"303.137024ms","start":"2026-01-23T10:17:41.836373Z","end":"2026-01-23T10:17:42.13951Z","steps":["trace[449547427] 'process raft request'  (duration: 302.906529ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:42.139605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:41.836365Z","time spent":"303.197566ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:870 > 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-23T10:17:47.697213Z","caller":"traceutil/trace.go:171","msg":"trace[1953858615] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"100.306244ms","start":"2026-01-23T10:17:47.596879Z","end":"2026-01-23T10:17:47.697185Z","steps":["trace[1953858615] 'process raft request'  (duration: 100.164791ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:48.021167Z","caller":"traceutil/trace.go:171","msg":"trace[495492031] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"420.986605ms","start":"2026-01-23T10:17:47.600166Z","end":"2026-01-23T10:17:48.021152Z","steps":["trace[495492031] 'process raft request'  (duration: 416.631161ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:48.021253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:47.60016Z","time spent":"421.047087ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:863 > 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-23T10:17:48.456877Z","caller":"traceutil/trace.go:171","msg":"trace[943740105] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"173.727602ms","start":"2026-01-23T10:17:48.283133Z","end":"2026-01-23T10:17:48.45686Z","steps":["trace[943740105] 'process raft request'  (duration: 173.616179ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:48.652483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.663135ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914592 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:889 > 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-23T10:17:48.652538Z","caller":"traceutil/trace.go:171","msg":"trace[974963160] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"363.669718ms","start":"2026-01-23T10:17:48.28886Z","end":"2026-01-23T10:17:48.652529Z","steps":["trace[974963160] 'read index received'  (duration: 168.11992ms)","trace[974963160] 'applied index is now lower than readState.Index'  (duration: 195.549158ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:48.652676Z","caller":"traceutil/trace.go:171","msg":"trace[1577194885] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"367.568589ms","start":"2026-01-23T10:17:48.285095Z","end":"2026-01-23T10:17:48.652663Z","steps":["trace[1577194885] 'process raft request'  (duration: 238.663648ms)","trace[1577194885] 'compare'  (duration: 128.601294ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:48.652709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:48.285087Z","time spent":"367.60469ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:889 > 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":"warn","ts":"2026-01-23T10:17:48.652851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.988455ms","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-23T10:17:48.652871Z","caller":"traceutil/trace.go:171","msg":"trace[1284869773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"364.019956ms","start":"2026-01-23T10:17:48.288844Z","end":"2026-01-23T10:17:48.652864Z","steps":["trace[1284869773] 'agreement among raft nodes before linearized reading'  (duration: 363.949284ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:48.652886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:48.288838Z","time spent":"364.045056ms","remote":"127.0.0.1:54932","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-23T10:17:48.652985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.551045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"}
{"level":"info","ts":"2026-01-23T10:17:48.652998Z","caller":"traceutil/trace.go:171","msg":"trace[1831026468] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:894; }","duration":"363.574736ms","start":"2026-01-23T10:17:48.289419Z","end":"2026-01-23T10:17:48.652994Z","steps":["trace[1831026468] 'agreement among raft nodes before linearized reading'  (duration: 363.535145ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:48.653025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:48.289414Z","time spent":"363.605806ms","remote":"127.0.0.1:54932","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-23T10:17:48.882091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.541897ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914596 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:890 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:17:48.882216Z","caller":"traceutil/trace.go:171","msg":"trace[2018904640] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"133.106681ms","start":"2026-01-23T10:17:48.749093Z","end":"2026-01-23T10:17:48.8822Z","steps":["trace[2018904640] 'read index received'  (duration: 11.35516ms)","trace[2018904640] 'applied index is now lower than readState.Index'  (duration: 121.750431ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:48.882299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.202923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:48.88233Z","caller":"traceutil/trace.go:171","msg":"trace[1686000022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:896; }","duration":"133.233214ms","start":"2026-01-23T10:17:48.749088Z","end":"2026-01-23T10:17:48.882321Z","steps":["trace[1686000022] 'agreement among raft nodes before linearized reading'  (duration: 133.167512ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:48.882423Z","caller":"traceutil/trace.go:171","msg":"trace[708909317] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"218.029311ms","start":"2026-01-23T10:17:48.664371Z","end":"2026-01-23T10:17:48.8824Z","steps":["trace[708909317] 'process raft request'  (duration: 96.105825ms)","trace[708909317] 'compare'  (duration: 121.419464ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:50.087652Z","caller":"traceutil/trace.go:171","msg":"trace[1502800109] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"214.530517ms","start":"2026-01-23T10:17:49.873099Z","end":"2026-01-23T10:17:50.087629Z","steps":["trace[1502800109] 'process raft request'  (duration: 214.400694ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:50.668072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.093021ms","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-23T10:17:50.668137Z","caller":"traceutil/trace.go:171","msg":"trace[1054926821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"207.216254ms","start":"2026-01-23T10:17:50.460904Z","end":"2026-01-23T10:17:50.66812Z","steps":["trace[1054926821] 'range keys from in-memory index tree'  (duration: 206.972467ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:17:50.668524Z","caller":"traceutil/trace.go:171","msg":"trace[796716703] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:938; }","duration":"149.449678ms","start":"2026-01-23T10:17:50.519061Z","end":"2026-01-23T10:17:50.668511Z","steps":["trace[796716703] 'read index received'  (duration: 143.713172ms)","trace[796716703] 'applied index is now lower than readState.Index'  (duration: 5.735456ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:17:50.671412Z","caller":"traceutil/trace.go:171","msg":"trace[130730884] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"189.886555ms","start":"2026-01-23T10:17:50.481498Z","end":"2026-01-23T10:17:50.671384Z","steps":["trace[130730884] 'process raft request'  (duration: 181.303072ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:50.671534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.479909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:50.671563Z","caller":"traceutil/trace.go:171","msg":"trace[965829199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:898; }","duration":"152.546911ms","start":"2026-01-23T10:17:50.519007Z","end":"2026-01-23T10:17:50.671554Z","steps":["trace[965829199] 'agreement among raft nodes before linearized reading'  (duration: 152.50117ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.227184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669914605,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:17:51.661355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.933922ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669914606 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:893 > 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-23T10:17:51.662641Z","caller":"traceutil/trace.go:171","msg":"trace[487684945] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"985.001324ms","start":"2026-01-23T10:17:50.677607Z","end":"2026-01-23T10:17:51.662608Z","steps":["trace[487684945] 'process raft request'  (duration: 617.72913ms)","trace[487684945] 'compare'  (duration: 365.8499ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:51.662793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:50.677593Z","time spent":"985.118826ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:893 > 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-23T10:17:51.663184Z","caller":"traceutil/trace.go:171","msg":"trace[1664815660] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"984.161854ms","start":"2026-01-23T10:17:50.679012Z","end":"2026-01-23T10:17:51.663174Z","steps":["trace[1664815660] 'process raft request'  (duration: 982.450323ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.663239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:50.679004Z","time spent":"984.205675ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:894 > 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-23T10:17:51.697919Z","caller":"traceutil/trace.go:171","msg":"trace[913752605] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:939; }","duration":"970.887809ms","start":"2026-01-23T10:17:50.726986Z","end":"2026-01-23T10:17:51.697873Z","steps":["trace[913752605] 'read index received'  (duration: 568.366601ms)","trace[913752605] 'applied index is now lower than readState.Index'  (duration: 402.520288ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:17:51.69826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"971.265488ms","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-23T10:17:51.698279Z","caller":"traceutil/trace.go:171","msg":"trace[1926876630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"971.318559ms","start":"2026-01-23T10:17:50.726955Z","end":"2026-01-23T10:17:51.698273Z","steps":["trace[1926876630] 'agreement among raft nodes before linearized reading'  (duration: 971.229487ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.698297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:50.726942Z","time spent":"971.34927ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:17:51.69841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.238025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:51.698424Z","caller":"traceutil/trace.go:171","msg":"trace[1706384699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"954.265986ms","start":"2026-01-23T10:17:50.744152Z","end":"2026-01-23T10:17:51.698418Z","steps":["trace[1706384699] 'agreement among raft nodes before linearized reading'  (duration: 954.241705ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.698434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:50.744144Z","time spent":"954.287686ms","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:17:51.698551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.345913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:17:51.698566Z","caller":"traceutil/trace.go:171","msg":"trace[784315192] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:900; }","duration":"503.383804ms","start":"2026-01-23T10:17:51.195176Z","end":"2026-01-23T10:17:51.69856Z","steps":["trace[784315192] 'agreement among raft nodes before linearized reading'  (duration: 503.360153ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.698579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:51.19516Z","time spent":"503.414864ms","remote":"127.0.0.1:54838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:17:51.698668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"811.186109ms","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-23T10:17:51.698683Z","caller":"traceutil/trace.go:171","msg":"trace[635195000] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"811.2253ms","start":"2026-01-23T10:17:50.887453Z","end":"2026-01-23T10:17:51.698679Z","steps":["trace[635195000] 'agreement among raft nodes before linearized reading'  (duration: 811.184379ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:17:51.698694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:17:50.887423Z","time spent":"811.268061ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-01-23T10:18:05.409339Z","caller":"traceutil/trace.go:171","msg":"trace[1145355460] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"371.894753ms","start":"2026-01-23T10:18:05.037422Z","end":"2026-01-23T10:18:05.409317Z","steps":["trace[1145355460] 'read index received'  (duration: 371.76391ms)","trace[1145355460] 'applied index is now lower than readState.Index'  (duration: 129.703µs)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:18:05.409377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:18:05.003663Z","time spent":"405.710334ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-23T10:18:05.409562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.141159ms","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-23T10:18:05.410143Z","caller":"traceutil/trace.go:171","msg":"trace[1954189444] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:979; }","duration":"372.742703ms","start":"2026-01-23T10:18:05.037377Z","end":"2026-01-23T10:18:05.41012Z","steps":["trace[1954189444] 'agreement among raft nodes before linearized reading'  (duration: 372.048376ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:18:05.410198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:18:05.037365Z","time spent":"372.819375ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-01-23T10:18:05.772273Z","caller":"traceutil/trace.go:171","msg":"trace[94850141] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"307.734073ms","start":"2026-01-23T10:18:05.464524Z","end":"2026-01-23T10:18:05.772258Z","steps":["trace[94850141] 'read index received'  (duration: 293.943096ms)","trace[94850141] 'applied index is now lower than readState.Index'  (duration: 13.790377ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:18:05.772637Z","caller":"traceutil/trace.go:171","msg":"trace[740330244] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"357.85299ms","start":"2026-01-23T10:18:05.414775Z","end":"2026-01-23T10:18:05.772628Z","steps":["trace[740330244] 'process raft request'  (duration: 343.757956ms)","trace[740330244] 'compare'  (duration: 13.578452ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:18:05.773631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:18:05.414766Z","time spent":"358.827263ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" mod_revision:959 > success:<request_put:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" value_size:424 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" > >"}
{"level":"warn","ts":"2026-01-23T10:18:05.773092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.557043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"}
{"level":"info","ts":"2026-01-23T10:18:05.775653Z","caller":"traceutil/trace.go:171","msg":"trace[1685624597] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:981; }","duration":"311.157744ms","start":"2026-01-23T10:18:05.464485Z","end":"2026-01-23T10:18:05.775643Z","steps":["trace[1685624597] 'agreement among raft nodes before linearized reading'  (duration: 308.570464ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:18:05.775729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:18:05.464469Z","time spent":"311.252706ms","remote":"127.0.0.1:54840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "}
{"level":"info","ts":"2026-01-23T10:18:06.050084Z","caller":"traceutil/trace.go:171","msg":"trace[323276379] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"272.61055ms","start":"2026-01-23T10:18:05.777451Z","end":"2026-01-23T10:18:06.050062Z","steps":["trace[323276379] 'read index received'  (duration: 177.471696ms)","trace[323276379] 'applied index is now lower than readState.Index'  (duration: 95.137674ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:18:06.05123Z","caller":"traceutil/trace.go:171","msg":"trace[1018286587] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"274.933275ms","start":"2026-01-23T10:18:05.776248Z","end":"2026-01-23T10:18:06.051182Z","steps":["trace[1018286587] 'process raft request'  (duration: 178.567871ms)","trace[1018286587] 'compare'  (duration: 95.084783ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:18:06.05188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.404993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-23T10:18:06.051943Z","caller":"traceutil/trace.go:171","msg":"trace[1766184783] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:982; }","duration":"274.473155ms","start":"2026-01-23T10:18:05.777446Z","end":"2026-01-23T10:18:06.05192Z","steps":["trace[1766184783] 'agreement among raft nodes before linearized reading'  (duration: 274.28213ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:18:06.057376Z","caller":"traceutil/trace.go:171","msg":"trace[343238965] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"279.76865ms","start":"2026-01-23T10:18:05.777592Z","end":"2026-01-23T10:18:06.057361Z","steps":["trace[343238965] 'process raft request'  (duration: 279.619066ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:18:06.058968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.228036ms","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-23T10:18:06.059044Z","caller":"traceutil/trace.go:171","msg":"trace[1846015751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"219.334788ms","start":"2026-01-23T10:18:05.839695Z","end":"2026-01-23T10:18:06.059029Z","steps":["trace[1846015751] 'agreement among raft nodes before linearized reading'  (duration: 219.162594ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:18:06.059675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.855176ms","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-23T10:18:06.059715Z","caller":"traceutil/trace.go:171","msg":"trace[1020977088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:983; }","duration":"264.937638ms","start":"2026-01-23T10:18:05.794766Z","end":"2026-01-23T10:18:06.059703Z","steps":["trace[1020977088] 'agreement among raft nodes before linearized reading'  (duration: 264.743914ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.256905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916479,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-01-23T10:20:45.448835Z","caller":"traceutil/trace.go:171","msg":"trace[24935208] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1724; }","duration":"692.807632ms","start":"2026-01-23T10:20:44.756006Z","end":"2026-01-23T10:20:45.448814Z","steps":["trace[24935208] 'read index received'  (duration: 692.440553ms)","trace[24935208] 'applied index is now lower than readState.Index'  (duration: 366.099µs)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:20:45.448992Z","caller":"traceutil/trace.go:171","msg":"trace[812438300] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"728.402663ms","start":"2026-01-23T10:20:44.720578Z","end":"2026-01-23T10:20:45.448981Z","steps":["trace[812438300] 'process raft request'  (duration: 727.951312ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.44929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.720563Z","time spent":"728.498205ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1641 > 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-23T10:20:45.449565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.56281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:20:45.449628Z","caller":"traceutil/trace.go:171","msg":"trace[822475585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1648; }","duration":"693.649902ms","start":"2026-01-23T10:20:44.755947Z","end":"2026-01-23T10:20:45.449597Z","steps":["trace[822475585] 'agreement among raft nodes before linearized reading'  (duration: 693.56127ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.450029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.755918Z","time spent":"694.048121ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:20:45.450878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.818143ms","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-23T10:20:45.450951Z","caller":"traceutil/trace.go:171","msg":"trace[1805528368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1648; }","duration":"593.194472ms","start":"2026-01-23T10:20:44.857718Z","end":"2026-01-23T10:20:45.450912Z","steps":["trace[1805528368] 'agreement among raft nodes before linearized reading'  (duration: 592.751972ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.450994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.857686Z","time spent":"593.295595ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:20:45.450888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.243515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-23T10:20:45.451266Z","caller":"traceutil/trace.go:171","msg":"trace[564091472] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1648; }","duration":"438.676555ms","start":"2026-01-23T10:20:45.012565Z","end":"2026-01-23T10:20:45.451242Z","steps":["trace[564091472] 'agreement among raft nodes before linearized reading'  (duration: 438.153163ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.451368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.744656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"}
{"level":"warn","ts":"2026-01-23T10:20:45.451279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.759903ms","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-23T10:20:45.451475Z","caller":"traceutil/trace.go:171","msg":"trace[691756536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1648; }","duration":"506.021699ms","start":"2026-01-23T10:20:44.945441Z","end":"2026-01-23T10:20:45.451462Z","steps":["trace[691756536] 'agreement among raft nodes before linearized reading'  (duration: 505.746423ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.451523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.369372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-01-23T10:20:45.451587Z","caller":"traceutil/trace.go:171","msg":"trace[1102399167] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1648; }","duration":"586.455034ms","start":"2026-01-23T10:20:44.865104Z","end":"2026-01-23T10:20:45.451559Z","steps":["trace[1102399167] 'agreement among raft nodes before linearized reading'  (duration: 586.342741ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.45162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.865082Z","time spent":"586.531366ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-23T10:20:45.451602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.945413Z","time spent":"506.175953ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-23T10:20:45.452003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.099936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-01-23T10:20:45.4521Z","caller":"traceutil/trace.go:171","msg":"trace[1015754338] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1648; }","duration":"281.26469ms","start":"2026-01-23T10:20:45.170823Z","end":"2026-01-23T10:20:45.452088Z","steps":["trace[1015754338] 'agreement among raft nodes before linearized reading'  (duration: 281.067825ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.451364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:45.012543Z","time spent":"438.806717ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.30\" "}
{"level":"info","ts":"2026-01-23T10:20:45.451428Z","caller":"traceutil/trace.go:171","msg":"trace[1274197608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1648; }","duration":"582.824667ms","start":"2026-01-23T10:20:44.868593Z","end":"2026-01-23T10:20:45.451418Z","steps":["trace[1274197608] 'agreement among raft nodes before linearized reading'  (duration: 582.704424ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:45.452585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:44.868585Z","time spent":"583.985585ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-23T10:20:51.248422Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916538,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:20:51.749351Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916538,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:20:52.249624Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916538,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:20:52.750184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916538,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:20:52.748078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000339376s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"}
{"level":"info","ts":"2026-01-23T10:20:52.752021Z","caller":"traceutil/trace.go:171","msg":"trace[301326549] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.004239209s","start":"2026-01-23T10:20:50.747705Z","end":"2026-01-23T10:20:52.751944Z","steps":["trace[301326549] 'agreement among raft nodes before linearized reading'  (duration: 2.000335516s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:52.754028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:50.747667Z","time spent":"2.006343738s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/01/23 10:20:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-01-23T10:20:52.839174Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.380146418s","expected-duration":"1s"}
{"level":"warn","ts":"2026-01-23T10:20:54.631683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999998169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:54.631927Z","caller":"traceutil/trace.go:171","msg":"trace[1618587318] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000228304s","start":"2026-01-23T10:20:52.631621Z","end":"2026-01-23T10:20:54.63185Z","steps":["trace[1618587318] 'agreement among raft nodes before linearized reading'  (duration: 2.000018029s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:54.632225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:52.63161Z","time spent":"2.000599393s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:20:54.751737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999940667s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:54.751806Z","caller":"traceutil/trace.go:171","msg":"trace[2058462408] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00006473s","start":"2026-01-23T10:20:52.751723Z","end":"2026-01-23T10:20:54.751787Z","steps":["trace[2058462408] 'agreement among raft nodes before linearized reading'  (duration: 1.999985968s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:54.751849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:52.751705Z","time spent":"2.000135182s","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:20:56.477865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000684747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:56.477995Z","caller":"traceutil/trace.go:171","msg":"trace[642825980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000898782s","start":"2026-01-23T10:20:51.47708Z","end":"2026-01-23T10:20:56.477979Z","steps":["trace[642825980] 'agreement among raft nodes before linearized reading'  (duration: 5.000751889s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:56.478064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.477065Z","time spent":"5.000989754s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:20:56.528797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999218522s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:56.529145Z","caller":"traceutil/trace.go:171","msg":"trace[1623786970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999479938s","start":"2026-01-23T10:20:51.529491Z","end":"2026-01-23T10:20:56.528971Z","steps":["trace[1623786970] 'agreement among raft nodes before linearized reading'  (duration: 4.999247743s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:56.52926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.529473Z","time spent":"4.999771095s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:20:56.560728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999244642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:56.560792Z","caller":"traceutil/trace.go:171","msg":"trace[981246266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999347584s","start":"2026-01-23T10:20:51.561438Z","end":"2026-01-23T10:20:56.560785Z","steps":["trace[981246266] 'agreement among raft nodes before linearized reading'  (duration: 4.999281393s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:56.560844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.561424Z","time spent":"4.999402975s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-23T10:20:56.75853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000245854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-01-23T10:20:56.758586Z","caller":"traceutil/trace.go:171","msg":"trace[657594935] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000332356s","start":"2026-01-23T10:20:54.75824Z","end":"2026-01-23T10:20:56.758573Z","steps":["trace[657594935] 'agreement among raft nodes before linearized reading'  (duration: 2.000268665s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:56.758625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:54.758228Z","time spent":"2.000387907s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:20:57.459216Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"7fa59bea5a6226e2","error":"etcdserver: request timed out"}
{"level":"warn","ts":"2026-01-23T10:20:58.121481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.120878Z","time spent":"7.000597222s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""}
{"level":"warn","ts":"2026-01-23T10:20:58.7591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000159051s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"}
{"level":"info","ts":"2026-01-23T10:20:58.759504Z","caller":"traceutil/trace.go:171","msg":"trace[539764953] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000621811s","start":"2026-01-23T10:20:56.758863Z","end":"2026-01-23T10:20:58.759484Z","steps":["trace[539764953] 'agreement among raft nodes before linearized reading'  (duration: 2.000190961s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:20:58.75975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:56.758844Z","time spent":"2.000890207s","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/01/23 10:20:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-01-23T10:20:59.071514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:52.070652Z","time spent":"7.000847187s","remote":"127.0.0.1:54726","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""}
{"level":"warn","ts":"2026-01-23T10:21:00.499828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.61681773s","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916539 > lease_revoke:<id:7fa59bea5a6226e2>","response":"size:29"}
{"level":"warn","ts":"2026-01-23T10:21:00.50091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.457225963s","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-23T10:21:00.513088Z","caller":"traceutil/trace.go:171","msg":"trace[2120797742] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1670; }","duration":"2.46939367s","start":"2026-01-23T10:20:58.043672Z","end":"2026-01-23T10:21:00.513066Z","steps":["trace[2120797742] 'range keys from in-memory index tree'  (duration: 2.457217633s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.534662Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"7.651677286s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-23T10:21:00.535023Z","caller":"traceutil/trace.go:171","msg":"trace[1591156268] linearizableReadLoop","detail":"{readStateIndex:1751; appliedIndex:1748; }","duration":"9.787298035s","start":"2026-01-23T10:20:50.74771Z","end":"2026-01-23T10:21:00.535008Z","steps":["trace[1591156268] 'read index received'  (duration: 2.152753685s)","trace[1591156268] 'applied index is now lower than readState.Index'  (duration: 7.63454378s)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:00.544151Z","caller":"traceutil/trace.go:171","msg":"trace[1112365220] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"6.289845095s","start":"2026-01-23T10:20:54.254289Z","end":"2026-01-23T10:21:00.544134Z","steps":["trace[1112365220] 'process raft request'  (duration: 6.289484356s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.544321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:54.75621Z","time spent":"5.788106477s","remote":"127.0.0.1:54770","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-01-23T10:21:00.544341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:54.254269Z","time spent":"6.289978878s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:1647 > 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-23T10:21:00.544494Z","caller":"traceutil/trace.go:171","msg":"trace[1133761292] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1673; }","duration":"2.408735735s","start":"2026-01-23T10:20:58.135743Z","end":"2026-01-23T10:21:00.544479Z","steps":["trace[1133761292] 'process raft request'  (duration: 2.408157991s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.544565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.135722Z","time spent":"2.408789867s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":721,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:1635 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:599 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-23T10:21:00.665076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.124077ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916546 > lease_revoke:<id:7fa59bea5a622743>","response":"size:29"}
{"level":"info","ts":"2026-01-23T10:21:00.665268Z","caller":"traceutil/trace.go:171","msg":"trace[1661452756] linearizableReadLoop","detail":"{readStateIndex:1755; appliedIndex:1751; }","duration":"130.183082ms","start":"2026-01-23T10:21:00.535066Z","end":"2026-01-23T10:21:00.665249Z","steps":["trace[1661452756] 'read index received'  (duration: 8.675906ms)","trace[1661452756] 'applied index is now lower than readState.Index'  (duration: 121.506196ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:00.665747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.571889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:00.665774Z","caller":"traceutil/trace.go:171","msg":"trace[435274660] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1674; }","duration":"146.631552ms","start":"2026-01-23T10:21:00.519136Z","end":"2026-01-23T10:21:00.665768Z","steps":["trace[435274660] 'agreement among raft nodes before linearized reading'  (duration: 146.58452ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.665896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.136265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:00.665914Z","caller":"traceutil/trace.go:171","msg":"trace[248390588] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1674; }","duration":"406.191186ms","start":"2026-01-23T10:21:00.259716Z","end":"2026-01-23T10:21:00.665908Z","steps":["trace[248390588] 'agreement among raft nodes before linearized reading'  (duration: 406.162586ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.665931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.259698Z","time spent":"406.227418ms","remote":"127.0.0.1:55164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:21:00.666013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.899150371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:00.66603Z","caller":"traceutil/trace.go:171","msg":"trace[1421775847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1674; }","duration":"1.899191042s","start":"2026-01-23T10:20:58.766833Z","end":"2026-01-23T10:21:00.666024Z","steps":["trace[1421775847] 'agreement among raft nodes before linearized reading'  (duration: 1.899167291s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.766818Z","time spent":"1.899224012s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.666161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.065669464s","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-23T10:21:00.666176Z","caller":"traceutil/trace.go:171","msg":"trace[1706373381] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1674; }","duration":"2.065704385s","start":"2026-01-23T10:20:58.600467Z","end":"2026-01-23T10:21:00.666171Z","steps":["trace[1706373381] 'agreement among raft nodes before linearized reading'  (duration: 2.065632904s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.600456Z","time spent":"2.065731486s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.666295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.099177297s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-01-23T10:21:00.66631Z","caller":"traceutil/trace.go:171","msg":"trace[1091847477] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1674; }","duration":"2.099193148s","start":"2026-01-23T10:20:58.567112Z","end":"2026-01-23T10:21:00.666305Z","steps":["trace[1091847477] 'agreement among raft nodes before linearized reading'  (duration: 2.099147046s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.56708Z","time spent":"2.099241569s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.666417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.102915705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-01-23T10:21:00.666433Z","caller":"traceutil/trace.go:171","msg":"trace[1292711322] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1674; }","duration":"2.102965146s","start":"2026-01-23T10:20:58.563463Z","end":"2026-01-23T10:21:00.666428Z","steps":["trace[1292711322] 'agreement among raft nodes before linearized reading'  (duration: 2.102918875s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.563445Z","time spent":"2.103004138s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.666532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.259104924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:00.666554Z","caller":"traceutil/trace.go:171","msg":"trace[743780038] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1674; }","duration":"2.259160515s","start":"2026-01-23T10:20:58.407386Z","end":"2026-01-23T10:21:00.666547Z","steps":["trace[743780038] 'agreement among raft nodes before linearized reading'  (duration: 2.259131054s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:58.407364Z","time spent":"2.259203136s","remote":"127.0.0.1:54880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:21:00.666653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.082425401s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T10:21:00.666674Z","caller":"traceutil/trace.go:171","msg":"trace[1834797372] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:1674; }","duration":"5.082485722s","start":"2026-01-23T10:20:55.584183Z","end":"2026-01-23T10:21:00.666669Z","steps":["trace[1834797372] 'agreement among raft nodes before linearized reading'  (duration: 5.082454081s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:55.584162Z","time spent":"5.082527794s","remote":"127.0.0.1:54918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:21:00.666784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.653846409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:00.666818Z","caller":"traceutil/trace.go:171","msg":"trace[1209675893] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:0; response_revision:1674; }","duration":"5.653915s","start":"2026-01-23T10:20:55.012898Z","end":"2026-01-23T10:21:00.666813Z","steps":["trace[1209675893] 'agreement among raft nodes before linearized reading'  (duration: 5.653874019s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:55.01288Z","time spent":"5.653949981s","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/199.204.45.30\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.666949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.105629982s","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-23T10:21:00.666971Z","caller":"traceutil/trace.go:171","msg":"trace[638329621] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1674; }","duration":"8.105678754s","start":"2026-01-23T10:20:52.561286Z","end":"2026-01-23T10:21:00.666965Z","steps":["trace[638329621] 'agreement among raft nodes before linearized reading'  (duration: 8.105628863s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.666989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:52.56127Z","time spent":"8.105715354s","remote":"127.0.0.1:54932","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":"warn","ts":"2026-01-23T10:21:00.667091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.224720872s","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-23T10:21:00.667112Z","caller":"traceutil/trace.go:171","msg":"trace[986272608] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1674; }","duration":"8.224767183s","start":"2026-01-23T10:20:52.442339Z","end":"2026-01-23T10:21:00.667106Z","steps":["trace[986272608] 'agreement among raft nodes before linearized reading'  (duration: 8.224716072s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.667128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:52.442323Z","time spent":"8.224801134s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.667224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.993661836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-01-23T10:21:00.667244Z","caller":"traceutil/trace.go:171","msg":"trace[850984710] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1674; }","duration":"8.993719457s","start":"2026-01-23T10:20:51.673519Z","end":"2026-01-23T10:21:00.667239Z","steps":["trace[850984710] 'agreement among raft nodes before linearized reading'  (duration: 8.993672356s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.667262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.6735Z","time spent":"8.993758648s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.667358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.102430261s","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-23T10:21:00.667377Z","caller":"traceutil/trace.go:171","msg":"trace[395853499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1674; }","duration":"9.102482281s","start":"2026-01-23T10:20:51.56489Z","end":"2026-01-23T10:21:00.667373Z","steps":["trace[395853499] 'agreement among raft nodes before linearized reading'  (duration: 9.10243599s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.667393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.56488Z","time spent":"9.102508823s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.667843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.104597802s","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-23T10:21:00.667883Z","caller":"traceutil/trace.go:171","msg":"trace[2139020591] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1674; }","duration":"9.104651423s","start":"2026-01-23T10:20:51.563223Z","end":"2026-01-23T10:21:00.667874Z","steps":["trace[2139020591] 'agreement among raft nodes before linearized reading'  (duration: 9.10453613s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.667907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.563218Z","time spent":"9.104684025s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.667993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.104785007s","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-23T10:21:00.668013Z","caller":"traceutil/trace.go:171","msg":"trace[611554920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1674; }","duration":"9.104824997s","start":"2026-01-23T10:20:51.563183Z","end":"2026-01-23T10:21:00.668008Z","steps":["trace[611554920] 'agreement among raft nodes before linearized reading'  (duration: 9.104765716s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.668032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.563173Z","time spent":"9.104855489s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-23T10:21:00.668101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.564171842s","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-23T10:21:00.668119Z","caller":"traceutil/trace.go:171","msg":"trace[392709403] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1674; }","duration":"9.564223604s","start":"2026-01-23T10:20:51.103891Z","end":"2026-01-23T10:21:00.668114Z","steps":["trace[392709403] 'agreement among raft nodes before linearized reading'  (duration: 9.564177143s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:00.668139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:20:51.103873Z","time spent":"9.564261954s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-01-23T10:21:00.673446Z","caller":"traceutil/trace.go:171","msg":"trace[292512356] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"127.209031ms","start":"2026-01-23T10:21:00.546227Z","end":"2026-01-23T10:21:00.673436Z","steps":["trace[292512356] 'process raft request'  (duration: 118.939195ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.088995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.243898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:686"}
{"level":"info","ts":"2026-01-23T10:21:01.089084Z","caller":"traceutil/trace.go:171","msg":"trace[322934768] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:1675; }","duration":"542.36707ms","start":"2026-01-23T10:21:00.546697Z","end":"2026-01-23T10:21:01.089064Z","steps":["trace[322934768] 'agreement among raft nodes before linearized reading'  (duration: 126.725479ms)","trace[322934768] 'range keys from in-memory index tree'  (duration: 415.185979ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:01.089129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.546687Z","time spent":"542.433122ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":710,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "}
{"level":"warn","ts":"2026-01-23T10:21:01.08919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.639262ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916551 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1665 > 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-23T10:21:01.089263Z","caller":"traceutil/trace.go:171","msg":"trace[466733434] linearizableReadLoop","detail":"{readStateIndex:1757; appliedIndex:1756; }","duration":"406.586045ms","start":"2026-01-23T10:21:00.682663Z","end":"2026-01-23T10:21:01.089249Z","steps":["trace[466733434] 'read index received'  (duration: 259.719918ms)","trace[466733434] 'applied index is now lower than readState.Index'  (duration: 146.865087ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:01.089438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.76936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:01.089467Z","caller":"traceutil/trace.go:171","msg":"trace[1964021154] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"407.361204ms","start":"2026-01-23T10:21:00.682097Z","end":"2026-01-23T10:21:01.089458Z","steps":["trace[1964021154] 'process raft request'  (duration: 260.380274ms)","trace[1964021154] 'compare'  (duration: 145.972096ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:01.08947Z","caller":"traceutil/trace.go:171","msg":"trace[1447719025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1676; }","duration":"406.81394ms","start":"2026-01-23T10:21:00.682646Z","end":"2026-01-23T10:21:01.08946Z","steps":["trace[1447719025] 'agreement among raft nodes before linearized reading'  (duration: 406.763439ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.089497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.682639Z","time spent":"406.851302ms","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:21:01.089509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.682084Z","time spent":"407.403985ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:1665 > 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-23T10:21:01.102911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.69143Z","time spent":"411.479142ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""}
2026/01/23 10:21:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"info","ts":"2026-01-23T10:21:01.495955Z","caller":"traceutil/trace.go:171","msg":"trace[134077605] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"399.089498ms","start":"2026-01-23T10:21:01.096849Z","end":"2026-01-23T10:21:01.495939Z","steps":["trace[134077605] 'process raft request'  (duration: 399.066787ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.496057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:01.096833Z","time spent":"399.16957ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:1671 > success:<request_put:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" value_size:599 >> failure:<request_range:<key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" > >"}
{"level":"warn","ts":"2026-01-23T10:21:01.496183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.682691Z","time spent":"813.490628ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"info","ts":"2026-01-23T10:21:01.497142Z","caller":"traceutil/trace.go:171","msg":"trace[397119030] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"814.357598ms","start":"2026-01-23T10:21:00.682774Z","end":"2026-01-23T10:21:01.497131Z","steps":["trace[397119030] 'process raft request'  (duration: 812.890014ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.497198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.682767Z","time spent":"814.39837ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1666 > 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-23T10:21:01.497395Z","caller":"traceutil/trace.go:171","msg":"trace[934751392] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"810.694092ms","start":"2026-01-23T10:21:00.686692Z","end":"2026-01-23T10:21:01.497386Z","steps":["trace[934751392] 'process raft request'  (duration: 809.044403ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.497439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.686685Z","time spent":"810.726792ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" mod_revision:1657 > success:<request_put:<key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" value_size:439 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-23T10:21:01.49754Z","caller":"traceutil/trace.go:171","msg":"trace[1733881679] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"810.787064ms","start":"2026-01-23T10:21:00.686741Z","end":"2026-01-23T10:21:01.497529Z","steps":["trace[1733881679] 'process raft request'  (duration: 809.018433ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.497585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.686738Z","time spent":"810.817125ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" mod_revision:1667 > 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-23T10:21:01.497991Z","caller":"traceutil/trace.go:171","msg":"trace[56057559] transaction","detail":"{read_only:false; response_revision:1680; number_of_response:1; }","duration":"811.182055ms","start":"2026-01-23T10:21:00.686797Z","end":"2026-01-23T10:21:01.497979Z","steps":["trace[56057559] 'process raft request'  (duration: 809.001793ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.49804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.686793Z","time spent":"811.216955ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1669 > 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":"info","ts":"2026-01-23T10:21:01.498407Z","caller":"traceutil/trace.go:171","msg":"trace[698641325] transaction","detail":"{read_only:false; response_revision:1681; number_of_response:1; }","duration":"810.030326ms","start":"2026-01-23T10:21:00.688365Z","end":"2026-01-23T10:21:01.498395Z","steps":["trace[698641325] 'process raft request'  (duration: 807.458015ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.498449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.688359Z","time spent":"810.065677ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" mod_revision:1656 > success:<request_put:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" value_size:433 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-23T10:21:01.498897Z","caller":"traceutil/trace.go:171","msg":"trace[718030930] transaction","detail":"{read_only:false; response_revision:1682; number_of_response:1; }","duration":"810.464957ms","start":"2026-01-23T10:21:00.688416Z","end":"2026-01-23T10:21:01.498881Z","steps":["trace[718030930] 'process raft request'  (duration: 807.424815ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.498941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.688412Z","time spent":"810.502678ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" mod_revision:1664 > success:<request_put:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" value_size:405 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-23T10:21:01.499236Z","caller":"traceutil/trace.go:171","msg":"trace[992627694] transaction","detail":"{read_only:false; response_revision:1683; number_of_response:1; }","duration":"808.851289ms","start":"2026-01-23T10:21:00.690371Z","end":"2026-01-23T10:21:01.499223Z","steps":["trace[992627694] 'process raft request'  (duration: 805.489899ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.499273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.690364Z","time spent":"808.88965ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1668 > 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-23T10:21:01.499775Z","caller":"traceutil/trace.go:171","msg":"trace[1528989640] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"806.632216ms","start":"2026-01-23T10:21:00.693129Z","end":"2026-01-23T10:21:01.499761Z","steps":["trace[1528989640] 'process raft request'  (duration: 802.765275ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.499818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.693121Z","time spent":"806.674217ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:1670 > 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":"info","ts":"2026-01-23T10:21:01.503426Z","caller":"traceutil/trace.go:171","msg":"trace[1932196844] linearizableReadLoop","detail":"{readStateIndex:1767; appliedIndex:1757; }","duration":"410.845006ms","start":"2026-01-23T10:21:01.089281Z","end":"2026-01-23T10:21:01.500126Z","steps":["trace[1932196844] 'read index received'  (duration: 313.4189ms)","trace[1932196844] 'applied index is now lower than readState.Index'  (duration: 97.317953ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:01.505207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.461341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.188d54f8771927f7\" ","response":"range_response_count:1 size:774"}
{"level":"info","ts":"2026-01-23T10:21:01.505243Z","caller":"traceutil/trace.go:171","msg":"trace[724577211] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.188d54f8771927f7; range_end:; response_count:1; response_revision:1686; }","duration":"822.513812ms","start":"2026-01-23T10:21:00.682721Z","end":"2026-01-23T10:21:01.505235Z","steps":["trace[724577211] 'agreement among raft nodes before linearized reading'  (duration: 822.403359ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.507874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:00.682719Z","time spent":"825.133514ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.188d54f8771927f7\" "}
{"level":"info","ts":"2026-01-23T10:21:01.730366Z","caller":"traceutil/trace.go:171","msg":"trace[31610169] linearizableReadLoop","detail":"{readStateIndex:1770; appliedIndex:1769; }","duration":"176.392666ms","start":"2026-01-23T10:21:01.55391Z","end":"2026-01-23T10:21:01.730303Z","steps":["trace[31610169] 'read index received'  (duration: 151.400804ms)","trace[31610169] 'applied index is now lower than readState.Index'  (duration: 24.990522ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:01.730556Z","caller":"traceutil/trace.go:171","msg":"trace[316860732] transaction","detail":"{read_only:false; response_revision:1688; number_of_response:1; }","duration":"212.613823ms","start":"2026-01-23T10:21:01.517923Z","end":"2026-01-23T10:21:01.730537Z","steps":["trace[316860732] 'process raft request'  (duration: 187.473588ms)","trace[316860732] 'compare'  (duration: 24.056509ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:01.730902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.0042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"}
{"level":"info","ts":"2026-01-23T10:21:01.730985Z","caller":"traceutil/trace.go:171","msg":"trace[848794464] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1688; }","duration":"177.190326ms","start":"2026-01-23T10:21:01.553754Z","end":"2026-01-23T10:21:01.730944Z","steps":["trace[848794464] 'agreement among raft nodes before linearized reading'  (duration: 177.053613ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:01.731224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.965052ms","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-23T10:21:01.731312Z","caller":"traceutil/trace.go:171","msg":"trace[1257290327] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1688; }","duration":"126.106526ms","start":"2026-01-23T10:21:01.605192Z","end":"2026-01-23T10:21:01.731299Z","steps":["trace[1257290327] 'agreement among raft nodes before linearized reading'  (duration: 125.990573ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:02.259389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.679649ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916578 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-provisioner-http-metrics-pbhwb\" mod_revision:1002 > success:<request_put:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-provisioner-http-metrics-pbhwb\" value_size:1435 >> failure:<request_range:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-provisioner-http-metrics-pbhwb\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:21:02.259706Z","caller":"traceutil/trace.go:171","msg":"trace[1417645968] transaction","detail":"{read_only:false; response_revision:1692; number_of_response:1; }","duration":"184.370304ms","start":"2026-01-23T10:21:02.075318Z","end":"2026-01-23T10:21:02.259688Z","steps":["trace[1417645968] 'process raft request'  (duration: 184.22315ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:02.259904Z","caller":"traceutil/trace.go:171","msg":"trace[1856871560] transaction","detail":"{read_only:false; response_revision:1691; number_of_response:1; }","duration":"185.216356ms","start":"2026-01-23T10:21:02.074679Z","end":"2026-01-23T10:21:02.259895Z","steps":["trace[1856871560] 'process raft request'  (duration: 184.802235ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:02.259996Z","caller":"traceutil/trace.go:171","msg":"trace[1343808521] transaction","detail":"{read_only:false; response_revision:1690; number_of_response:1; }","duration":"191.550785ms","start":"2026-01-23T10:21:02.068427Z","end":"2026-01-23T10:21:02.259978Z","steps":["trace[1343808521] 'process raft request'  (duration: 75.208771ms)","trace[1343808521] 'compare'  (duration: 115.558995ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:02.467452Z","caller":"traceutil/trace.go:171","msg":"trace[96963183] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"186.503576ms","start":"2026-01-23T10:21:02.280926Z","end":"2026-01-23T10:21:02.467429Z","steps":["trace[96963183] 'process raft request'  (duration: 156.460514ms)","trace[96963183] 'compare'  (duration: 29.95554ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:03.142894Z","caller":"traceutil/trace.go:171","msg":"trace[841076378] transaction","detail":"{read_only:false; response_revision:1697; number_of_response:1; }","duration":"132.25952ms","start":"2026-01-23T10:21:03.010608Z","end":"2026-01-23T10:21:03.142868Z","steps":["trace[841076378] 'process raft request'  (duration: 131.484051ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:11.414669Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:11.915604Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:12.415769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:12.916306Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:13.417054Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:13.746829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999696278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"}
{"level":"info","ts":"2026-01-23T10:21:13.746897Z","caller":"traceutil/trace.go:171","msg":"trace[1123838605] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999828421s","start":"2026-01-23T10:21:11.747054Z","end":"2026-01-23T10:21:13.746883Z","steps":["trace[1123838605] 'agreement among raft nodes before linearized reading'  (duration: 1.999736089s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:13.747101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.747024Z","time spent":"2.000067136s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/01/23 10:21:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-01-23T10:21:13.917855Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:14.418681Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421301281669916676,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-01-23T10:21:14.744248Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.181044116s","expected-duration":"1s"}
{"level":"info","ts":"2026-01-23T10:21:14.744591Z","caller":"traceutil/trace.go:171","msg":"trace[1535641616] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"4.181426305s","start":"2026-01-23T10:21:10.563139Z","end":"2026-01-23T10:21:14.744565Z","steps":["trace[1535641616] 'process raft request'  (duration: 4.181271312s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.744797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:10.563117Z","time spent":"4.181545758s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:1673 > 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-23T10:21:14.789718Z","caller":"traceutil/trace.go:171","msg":"trace[714016841] linearizableReadLoop","detail":"{readStateIndex:1815; appliedIndex:1814; }","duration":"3.875687554s","start":"2026-01-23T10:21:10.914011Z","end":"2026-01-23T10:21:14.789698Z","steps":["trace[714016841] 'read index received'  (duration: 3.831310227s)","trace[714016841] 'applied index is now lower than readState.Index'  (duration: 44.376387ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:14.790102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.876069303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:14.790148Z","caller":"traceutil/trace.go:171","msg":"trace[315438639] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1732; }","duration":"3.876180906s","start":"2026-01-23T10:21:10.913956Z","end":"2026-01-23T10:21:14.790137Z","steps":["trace[315438639] 'agreement among raft nodes before linearized reading'  (duration: 3.876073883s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.790183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:10.913915Z","time spent":"3.876259408s","remote":"127.0.0.1:54822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "}
{"level":"info","ts":"2026-01-23T10:21:14.790364Z","caller":"traceutil/trace.go:171","msg":"trace[1832665221] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"2.895995913s","start":"2026-01-23T10:21:11.894357Z","end":"2026-01-23T10:21:14.790353Z","steps":["trace[1832665221] 'process raft request'  (duration: 2.895244884s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.790455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.894324Z","time spent":"2.896083974s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:1686 > 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-23T10:21:14.790951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.499543699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-01-23T10:21:14.79099Z","caller":"traceutil/trace.go:171","msg":"trace[1005341230] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1732; }","duration":"3.49958321s","start":"2026-01-23T10:21:11.291396Z","end":"2026-01-23T10:21:14.790979Z","steps":["trace[1005341230] 'agreement among raft nodes before linearized reading'  (duration: 3.499470638s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.791018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.291359Z","time spent":"3.499652783s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.791141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.044005222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:14.791171Z","caller":"traceutil/trace.go:171","msg":"trace[44682060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"1.044075824s","start":"2026-01-23T10:21:13.747088Z","end":"2026-01-23T10:21:14.791164Z","steps":["trace[44682060] 'agreement among raft nodes before linearized reading'  (duration: 1.044034163s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.791195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:13.747069Z","time spent":"1.044120874s","remote":"127.0.0.1:54714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.791323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19459167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:14.791366Z","caller":"traceutil/trace.go:171","msg":"trace[1634298079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1732; }","duration":"1.194719263s","start":"2026-01-23T10:21:13.596639Z","end":"2026-01-23T10:21:14.791358Z","steps":["trace[1634298079] 'agreement among raft nodes before linearized reading'  (duration: 1.194666862s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.791399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:13.596617Z","time spent":"1.194775215s","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.791603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940484465s","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:510"}
{"level":"info","ts":"2026-01-23T10:21:14.791636Z","caller":"traceutil/trace.go:171","msg":"trace[1770663242] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1732; }","duration":"1.940553046s","start":"2026-01-23T10:21:12.851074Z","end":"2026-01-23T10:21:14.791627Z","steps":["trace[1770663242] 'agreement among raft nodes before linearized reading'  (duration: 1.940432994s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.791661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:12.851055Z","time spent":"1.940600387s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.79178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.010876781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:14.791816Z","caller":"traceutil/trace.go:171","msg":"trace[1316738325] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1732; }","duration":"2.010965493s","start":"2026-01-23T10:21:12.780843Z","end":"2026-01-23T10:21:14.791808Z","steps":["trace[1316738325] 'agreement among raft nodes before linearized reading'  (duration: 2.010915081s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.791867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:12.780824Z","time spent":"2.011035905s","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "}
{"level":"warn","ts":"2026-01-23T10:21:14.792063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.104428277s","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-23T10:21:14.792095Z","caller":"traceutil/trace.go:171","msg":"trace[1032993853] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1732; }","duration":"3.104476688s","start":"2026-01-23T10:21:11.68761Z","end":"2026-01-23T10:21:14.792087Z","steps":["trace[1032993853] 'agreement among raft nodes before linearized reading'  (duration: 3.104366395s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.79212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.687601Z","time spent":"3.104513369s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.792268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.105236686s","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-23T10:21:14.7923Z","caller":"traceutil/trace.go:171","msg":"trace[1166899083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1732; }","duration":"3.105294087s","start":"2026-01-23T10:21:11.686999Z","end":"2026-01-23T10:21:14.792293Z","steps":["trace[1166899083] 'agreement among raft nodes before linearized reading'  (duration: 3.105214755s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.792324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.686991Z","time spent":"3.105327917s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.792456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.105775198s","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-23T10:21:14.792485Z","caller":"traceutil/trace.go:171","msg":"trace[948991420] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1732; }","duration":"3.105826619s","start":"2026-01-23T10:21:11.686652Z","end":"2026-01-23T10:21:14.792478Z","steps":["trace[948991420] 'agreement among raft nodes before linearized reading'  (duration: 3.105757278s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.792516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.686641Z","time spent":"3.105869031s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.793424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.190796922s","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-23T10:21:14.793469Z","caller":"traceutil/trace.go:171","msg":"trace[1556601861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1732; }","duration":"3.190870313s","start":"2026-01-23T10:21:11.602586Z","end":"2026-01-23T10:21:14.793456Z","steps":["trace[1556601861] 'agreement among raft nodes before linearized reading'  (duration: 3.19074217s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.793508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.602558Z","time spent":"3.190941755s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.793665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.259336195s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"}
{"level":"info","ts":"2026-01-23T10:21:14.793697Z","caller":"traceutil/trace.go:171","msg":"trace[179754070] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1732; }","duration":"3.259379105s","start":"2026-01-23T10:21:11.53431Z","end":"2026-01-23T10:21:14.793689Z","steps":["trace[179754070] 'agreement among raft nodes before linearized reading'  (duration: 3.259305034s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.793722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.534303Z","time spent":"3.259413106s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.793987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.261983187s","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-23T10:21:14.794024Z","caller":"traceutil/trace.go:171","msg":"trace[1823058425] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1732; }","duration":"3.26210531s","start":"2026-01-23T10:21:11.531909Z","end":"2026-01-23T10:21:14.794015Z","steps":["trace[1823058425] 'agreement among raft nodes before linearized reading'  (duration: 3.262013888s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.794051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.531903Z","time spent":"3.262142051s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.794215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.2629651s","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-23T10:21:14.794249Z","caller":"traceutil/trace.go:171","msg":"trace[918311469] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1732; }","duration":"3.263036122s","start":"2026-01-23T10:21:11.531203Z","end":"2026-01-23T10:21:14.794239Z","steps":["trace[918311469] 'agreement among raft nodes before linearized reading'  (duration: 3.26294375s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.794275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.53118Z","time spent":"3.263086813s","remote":"127.0.0.1:54932","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":"warn","ts":"2026-01-23T10:21:14.794386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.394896592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:14.794416Z","caller":"traceutil/trace.go:171","msg":"trace[2031761743] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1732; }","duration":"3.394950844s","start":"2026-01-23T10:21:11.399458Z","end":"2026-01-23T10:21:14.794409Z","steps":["trace[2031761743] 'agreement among raft nodes before linearized reading'  (duration: 3.394909933s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.79444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.399443Z","time spent":"3.394991205s","remote":"127.0.0.1:54838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "}
{"level":"warn","ts":"2026-01-23T10:21:14.792655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.184876811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"}
{"level":"info","ts":"2026-01-23T10:21:14.803197Z","caller":"traceutil/trace.go:171","msg":"trace[1947983772] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1732; }","duration":"3.195348878s","start":"2026-01-23T10:21:11.607763Z","end":"2026-01-23T10:21:14.803111Z","steps":["trace[1947983772] 'agreement among raft nodes before linearized reading'  (duration: 3.18484138s)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:14.804102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:11.607758Z","time spent":"3.195472921s","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "}
{"level":"info","ts":"2026-01-23T10:21:15.116347Z","caller":"traceutil/trace.go:171","msg":"trace[1654161913] linearizableReadLoop","detail":"{readStateIndex:1826; appliedIndex:1825; }","duration":"196.239682ms","start":"2026-01-23T10:21:14.920084Z","end":"2026-01-23T10:21:15.116323Z","steps":["trace[1654161913] 'read index received'  (duration: 108.727977ms)","trace[1654161913] 'applied index is now lower than readState.Index'  (duration: 87.510495ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:15.116568Z","caller":"traceutil/trace.go:171","msg":"trace[2103923424] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"241.294135ms","start":"2026-01-23T10:21:14.875154Z","end":"2026-01-23T10:21:15.116448Z","steps":["trace[2103923424] 'process raft request'  (duration: 153.715528ms)","trace[2103923424] 'compare'  (duration: 87.29531ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:15.11687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.766524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:15.116905Z","caller":"traceutil/trace.go:171","msg":"trace[72438899] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:1743; }","duration":"196.838495ms","start":"2026-01-23T10:21:14.920057Z","end":"2026-01-23T10:21:15.116895Z","steps":["trace[72438899] 'agreement among raft nodes before linearized reading'  (duration: 196.736803ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:15.117186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.990081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"}
{"level":"info","ts":"2026-01-23T10:21:15.117217Z","caller":"traceutil/trace.go:171","msg":"trace[1803898125] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:1743; }","duration":"103.067033ms","start":"2026-01-23T10:21:15.014141Z","end":"2026-01-23T10:21:15.117208Z","steps":["trace[1803898125] 'agreement among raft nodes before linearized reading'  (duration: 102.94853ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:15.513606Z","caller":"traceutil/trace.go:171","msg":"trace[619078354] transaction","detail":"{read_only:false; response_revision:1748; number_of_response:1; }","duration":"179.832214ms","start":"2026-01-23T10:21:15.333747Z","end":"2026-01-23T10:21:15.51358Z","steps":["trace[619078354] 'process raft request'  (duration: 100.359768ms)","trace[619078354] 'compare'  (duration: 79.223371ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:16.172012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.380379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"}
{"level":"info","ts":"2026-01-23T10:21:16.17226Z","caller":"traceutil/trace.go:171","msg":"trace[1819017011] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1750; }","duration":"208.697566ms","start":"2026-01-23T10:21:15.96354Z","end":"2026-01-23T10:21:16.172237Z","steps":["trace[1819017011] 'range keys from in-memory index tree'  (duration: 208.287317ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:16.172791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.720211ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916715 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/default/test-pvc-pod.188d54fd671a4ff5\" mod_revision:0 > success:<request_put:<key:\"/registry/events/default/test-pvc-pod.188d54fd671a4ff5\" value_size:695 lease:9197929244815140735 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:21:16.173097Z","caller":"traceutil/trace.go:171","msg":"trace[217429535] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"103.77996ms","start":"2026-01-23T10:21:16.069301Z","end":"2026-01-23T10:21:16.173081Z","steps":["trace[217429535] 'process raft request'  (duration: 103.692388ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:16.175556Z","caller":"traceutil/trace.go:171","msg":"trace[641718940] transaction","detail":"{read_only:false; response_revision:1751; number_of_response:1; }","duration":"208.593463ms","start":"2026-01-23T10:21:15.964858Z","end":"2026-01-23T10:21:16.173452Z","steps":["trace[641718940] 'process raft request'  (duration: 56.165776ms)","trace[641718940] 'compare'  (duration: 151.587088ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:19.111911Z","caller":"traceutil/trace.go:171","msg":"trace[1147575386] transaction","detail":"{read_only:false; response_revision:1760; number_of_response:1; }","duration":"110.888837ms","start":"2026-01-23T10:21:19.000996Z","end":"2026-01-23T10:21:19.111885Z","steps":["trace[1147575386] 'process raft request'  (duration: 110.758644ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:19.308805Z","caller":"traceutil/trace.go:171","msg":"trace[948767149] transaction","detail":"{read_only:false; response_revision:1764; number_of_response:1; }","duration":"303.859322ms","start":"2026-01-23T10:21:19.00492Z","end":"2026-01-23T10:21:19.308779Z","steps":["trace[948767149] 'process raft request'  (duration: 303.79125ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:19.308969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.004889Z","time spent":"303.991804ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1756 > 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-23T10:21:19.309016Z","caller":"traceutil/trace.go:171","msg":"trace[303437579] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"304.962818ms","start":"2026-01-23T10:21:19.004027Z","end":"2026-01-23T10:21:19.308989Z","steps":["trace[303437579] 'process raft request'  (duration: 304.510796ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:19.309164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.004014Z","time spent":"305.061471ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:1758 > 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":"info","ts":"2026-01-23T10:21:19.309206Z","caller":"traceutil/trace.go:171","msg":"trace[1719683044] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"304.342943ms","start":"2026-01-23T10:21:19.004851Z","end":"2026-01-23T10:21:19.309194Z","steps":["trace[1719683044] 'process raft request'  (duration: 303.760679ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:19.309264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.004837Z","time spent":"304.395964ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1757 > 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":"info","ts":"2026-01-23T10:21:19.30936Z","caller":"traceutil/trace.go:171","msg":"trace[311825099] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"307.375485ms","start":"2026-01-23T10:21:19.001968Z","end":"2026-01-23T10:21:19.309344Z","steps":["trace[311825099] 'process raft request'  (duration: 279.165699ms)","trace[311825099] 'compare'  (duration: 27.166611ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:19.309419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.001957Z","time spent":"307.431846ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1755 > 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-23T10:21:20.170019Z","caller":"traceutil/trace.go:171","msg":"trace[1022323267] transaction","detail":"{read_only:false; response_revision:1766; number_of_response:1; }","duration":"243.689502ms","start":"2026-01-23T10:21:19.926298Z","end":"2026-01-23T10:21:20.169987Z","steps":["trace[1022323267] 'process raft request'  (duration: 202.806336ms)","trace[1022323267] 'compare'  (duration: 40.768193ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:20.448049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.197939ms","expected-duration":"100ms","prefix":"","request":"header:<ID:18421301281669916755 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" mod_revision:1742 > success:<request_put:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" value_size:433 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" > >>","response":"size:16"}
{"level":"info","ts":"2026-01-23T10:21:20.448723Z","caller":"traceutil/trace.go:171","msg":"trace[1940273436] transaction","detail":"{read_only:false; response_revision:1767; number_of_response:1; }","duration":"521.253952ms","start":"2026-01-23T10:21:19.927392Z","end":"2026-01-23T10:21:20.448646Z","steps":["trace[1940273436] 'process raft request'  (duration: 394.354447ms)","trace[1940273436] 'compare'  (duration: 126.068986ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:20.448828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.92736Z","time spent":"521.414096ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" mod_revision:1742 > success:<request_put:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" value_size:433 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-23T10:21:20.449462Z","caller":"traceutil/trace.go:171","msg":"trace[2020657304] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"520.044035ms","start":"2026-01-23T10:21:19.929395Z","end":"2026-01-23T10:21:20.449439Z","steps":["trace[2020657304] 'process raft request'  (duration: 518.757844ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:20.44955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.929381Z","time spent":"520.122366ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" mod_revision:1738 > success:<request_put:<key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" value_size:439 >> failure:<request_range:<key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" > >"}
{"level":"info","ts":"2026-01-23T10:21:20.501588Z","caller":"traceutil/trace.go:171","msg":"trace[1587714000] linearizableReadLoop","detail":"{readStateIndex:1853; appliedIndex:1850; }","duration":"555.386278ms","start":"2026-01-23T10:21:19.946168Z","end":"2026-01-23T10:21:20.501554Z","steps":["trace[1587714000] 'read index received'  (duration: 182.946538ms)","trace[1587714000] 'applied index is now lower than readState.Index'  (duration: 372.43697ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:20.501735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.541102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-01-23T10:21:20.501787Z","caller":"traceutil/trace.go:171","msg":"trace[156809636] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1768; }","duration":"555.729716ms","start":"2026-01-23T10:21:19.94604Z","end":"2026-01-23T10:21:20.50177Z","steps":["trace[156809636] 'agreement among raft nodes before linearized reading'  (duration: 555.625034ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:20.501837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:19.946018Z","time spent":"555.803737ms","remote":"127.0.0.1:39248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "}
{"level":"info","ts":"2026-01-23T10:21:29.571657Z","caller":"traceutil/trace.go:171","msg":"trace[1241617835] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"156.683169ms","start":"2026-01-23T10:21:29.414951Z","end":"2026-01-23T10:21:29.571634Z","steps":["trace[1241617835] 'process raft request'  (duration: 156.400252ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:29.834939Z","caller":"traceutil/trace.go:171","msg":"trace[743602785] linearizableReadLoop","detail":"{readStateIndex:1909; appliedIndex:1905; }","duration":"295.587987ms","start":"2026-01-23T10:21:29.539321Z","end":"2026-01-23T10:21:29.834909Z","steps":["trace[743602785] 'read index received'  (duration: 32.212311ms)","trace[743602785] 'applied index is now lower than readState.Index'  (duration: 263.374566ms)"],"step_count":2}
{"level":"info","ts":"2026-01-23T10:21:29.835326Z","caller":"traceutil/trace.go:171","msg":"trace[984828273] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"414.248757ms","start":"2026-01-23T10:21:29.42104Z","end":"2026-01-23T10:21:29.835289Z","steps":["trace[984828273] 'process raft request'  (duration: 393.403946ms)","trace[984828273] 'compare'  (duration: 19.726355ms)"],"step_count":2}
{"level":"warn","ts":"2026-01-23T10:21:29.835646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:29.42103Z","time spent":"414.476762ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:1815 > 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":"info","ts":"2026-01-23T10:21:29.836081Z","caller":"traceutil/trace.go:171","msg":"trace[1043264242] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"412.902975ms","start":"2026-01-23T10:21:29.423159Z","end":"2026-01-23T10:21:29.836062Z","steps":["trace[1043264242] 'process raft request'  (duration: 411.599195ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:29.83713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:29.423153Z","time spent":"413.036998ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1816 > 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-23T10:21:29.837481Z","caller":"traceutil/trace.go:171","msg":"trace[1841378863] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"414.266588ms","start":"2026-01-23T10:21:29.4232Z","end":"2026-01-23T10:21:29.837467Z","steps":["trace[1841378863] 'process raft request'  (duration: 411.660906ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:29.83755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:29.423196Z","time spent":"414.318739ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1814 > 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-23T10:21:29.837821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.496446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-01-23T10:21:29.83786Z","caller":"traceutil/trace.go:171","msg":"trace[729825237] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1822; }","duration":"298.589018ms","start":"2026-01-23T10:21:29.53926Z","end":"2026-01-23T10:21:29.837849Z","steps":["trace[729825237] 'agreement among raft nodes before linearized reading'  (duration: 298.424874ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:37.363532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.688898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"}
{"level":"info","ts":"2026-01-23T10:21:37.363595Z","caller":"traceutil/trace.go:171","msg":"trace[1176890395] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1857; }","duration":"403.79387ms","start":"2026-01-23T10:21:36.959787Z","end":"2026-01-23T10:21:37.363581Z","steps":["trace[1176890395] 'range keys from in-memory index tree'  (duration: 403.565255ms)"],"step_count":1}
{"level":"warn","ts":"2026-01-23T10:21:37.363621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T10:21:36.959764Z","time spent":"403.851651ms","remote":"127.0.0.1:54932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-01-23T10:21:37.364288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.547145ms","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-23T10:21:37.364318Z","caller":"traceutil/trace.go:171","msg":"trace[1102749733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1857; }","duration":"178.606615ms","start":"2026-01-23T10:21:37.185704Z","end":"2026-01-23T10:21:37.364311Z","steps":["trace[1102749733] 'range keys from in-memory index tree'  (duration: 177.489919ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:37.612907Z","caller":"traceutil/trace.go:171","msg":"trace[979237331] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"245.686338ms","start":"2026-01-23T10:21:37.3672Z","end":"2026-01-23T10:21:37.612887Z","steps":["trace[979237331] 'process raft request'  (duration: 245.030192ms)"],"step_count":1}
{"level":"info","ts":"2026-01-23T10:21:37.613087Z","caller":"traceutil/trace.go:171","msg":"trace[1321134417] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"242.948553ms","start":"2026-01-23T10:21:37.370129Z","end":"2026-01-23T10:21:37.613077Z","steps":["trace[1321134417] 'process raft request'  (duration: 242.213586ms)"],"step_count":1}