{"level":"warn","ts":"2026-03-18T12:46:09.79118Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-18T12:46:09.791261Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.187: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.19.213.187:2380","--initial-cluster=instance=https://199.19.213.187:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.187:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.187:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]}
{"level":"warn","ts":"2026-03-18T12:46:09.79133Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-03-18T12:46:09.791341Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.187:2380"]}
{"level":"info","ts":"2026-03-18T12:46:09.79136Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-03-18T12:46:09.791958Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"]}
{"level":"info","ts":"2026-03-18T12:46:09.79203Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.187:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"}
{"level":"info","ts":"2026-03-18T12:46:09.795832Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.538035ms"}
{"level":"info","ts":"2026-03-18T12:46:09.804712Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"bf16572acb5c7f39","cluster-id":"acf20a96895e2b74"}
{"level":"info","ts":"2026-03-18T12:46:09.804782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=()"}
{"level":"info","ts":"2026-03-18T12:46:09.8048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 0"}
{"level":"info","ts":"2026-03-18T12:46:09.804817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft bf16572acb5c7f39 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-03-18T12:46:09.804826Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became follower at term 1"}
{"level":"info","ts":"2026-03-18T12:46:09.804864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"}
{"level":"warn","ts":"2026-03-18T12:46:09.809525Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-03-18T12:46:09.810954Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-03-18T12:46:09.812799Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"}
{"level":"info","ts":"2026-03-18T12:46:09.815334Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"bf16572acb5c7f39","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-03-18T12:46:09.815498Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"bf16572acb5c7f39","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-03-18T12:46:09.815513Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-18T12:46:09.815651Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-18T12:46:09.815673Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"}
{"level":"info","ts":"2026-03-18T12:46:09.817999Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 switched to configuration voters=(13769288752043949881)"}
{"level":"info","ts":"2026-03-18T12:46:09.818243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","added-peer-id":"bf16572acb5c7f39","added-peer-peer-urls":["https://199.19.213.187:2380"]}
{"level":"info","ts":"2026-03-18T12:46:09.820574Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]}
{"level":"info","ts":"2026-03-18T12:46:09.820695Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.187:2380"}
{"level":"info","ts":"2026-03-18T12:46:09.820742Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.187:2380"}
{"level":"info","ts":"2026-03-18T12:46:09.820874Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"bf16572acb5c7f39","initial-advertise-peer-urls":["https://199.19.213.187:2380"],"listen-peer-urls":["https://199.19.213.187:2380"],"advertise-client-urls":["https://199.19.213.187:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.187:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-03-18T12:46:09.820915Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-03-18T12:46:10.705562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 is starting a new election at term 1"}
{"level":"info","ts":"2026-03-18T12:46:10.705809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became pre-candidate at term 1"}
{"level":"info","ts":"2026-03-18T12:46:10.705829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgPreVoteResp from bf16572acb5c7f39 at term 1"}
{"level":"info","ts":"2026-03-18T12:46:10.705841Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became candidate at term 2"}
{"level":"info","ts":"2026-03-18T12:46:10.705969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 received MsgVoteResp from bf16572acb5c7f39 at term 2"}
{"level":"info","ts":"2026-03-18T12:46:10.705989Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"bf16572acb5c7f39 became leader at term 2"}
{"level":"info","ts":"2026-03-18T12:46:10.705996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: bf16572acb5c7f39 elected leader bf16572acb5c7f39 at term 2"}
{"level":"info","ts":"2026-03-18T12:46:10.708043Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-18T12:46:10.709831Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-18T12:46:10.709828Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"bf16572acb5c7f39","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.187:2379]}","request-path":"/0/members/bf16572acb5c7f39/attributes","cluster-id":"acf20a96895e2b74","publish-timeout":"7s"}
{"level":"info","ts":"2026-03-18T12:46:10.70987Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-03-18T12:46:10.710132Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-03-18T12:46:10.71019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-03-18T12:46:10.710299Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"acf20a96895e2b74","local-member-id":"bf16572acb5c7f39","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-18T12:46:10.7104Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-18T12:46:10.710422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-03-18T12:46:10.71307Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.187:2379"}
{"level":"info","ts":"2026-03-18T12:46:10.713455Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-03-18T12:46:46.097122Z","caller":"traceutil/trace.go:171","msg":"trace[538735993] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"139.815071ms","start":"2026-03-18T12:46:45.957287Z","end":"2026-03-18T12:46:46.097102Z","steps":["trace[538735993] 'process raft request'  (duration: 139.671988ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:46:46.656767Z","caller":"traceutil/trace.go:171","msg":"trace[1774515366] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"351.645386ms","start":"2026-03-18T12:46:46.305096Z","end":"2026-03-18T12:46:46.656741Z","steps":["trace[1774515366] 'process raft request'  (duration: 351.485953ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:46:46.656782Z","caller":"traceutil/trace.go:171","msg":"trace[942640] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:550; }","duration":"324.470542ms","start":"2026-03-18T12:46:46.332283Z","end":"2026-03-18T12:46:46.656753Z","steps":["trace[942640] 'read index received'  (duration: 324.460302ms)","trace[942640] 'applied index is now lower than readState.Index'  (duration: 8.35µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:46:46.657013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.705276ms","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-03-18T12:46:46.65715Z","caller":"traceutil/trace.go:171","msg":"trace[485719308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"324.8921ms","start":"2026-03-18T12:46:46.332229Z","end":"2026-03-18T12:46:46.657121Z","steps":["trace[485719308] 'agreement among raft nodes before linearized reading'  (duration: 324.673046ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:46:46.657221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:46:46.332206Z","time spent":"324.999882ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-18T12:46:46.657491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:46:46.305069Z","time spent":"351.766518ms","remote":"127.0.0.1:48030","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:521 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"warn","ts":"2026-03-18T12:46:46.723959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.517965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"info","ts":"2026-03-18T12:46:46.724042Z","caller":"traceutil/trace.go:171","msg":"trace[116376980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"391.621806ms","start":"2026-03-18T12:46:46.332397Z","end":"2026-03-18T12:46:46.724019Z","steps":["trace[116376980] 'agreement among raft nodes before linearized reading'  (duration: 391.480084ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:46:46.724051Z","caller":"traceutil/trace.go:171","msg":"trace[748922934] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"246.387235ms","start":"2026-03-18T12:46:46.477622Z","end":"2026-03-18T12:46:46.72401Z","steps":["trace[748922934] 'process raft request'  (duration: 207.397483ms)","trace[748922934] 'compare'  (duration: 38.741258ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:46:46.724107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:46:46.332388Z","time spent":"391.704208ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-18T12:46:46.72415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.968017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-18T12:46:46.72423Z","caller":"traceutil/trace.go:171","msg":"trace[900346869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"260.092089ms","start":"2026-03-18T12:46:46.464115Z","end":"2026-03-18T12:46:46.724207Z","steps":["trace[900346869] 'agreement among raft nodes before linearized reading'  (duration: 259.936696ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:46:46.724603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.903125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:46:46.724722Z","caller":"traceutil/trace.go:171","msg":"trace[739107128] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"240.066017ms","start":"2026-03-18T12:46:46.484631Z","end":"2026-03-18T12:46:46.724697Z","steps":["trace[739107128] 'agreement among raft nodes before linearized reading'  (duration: 239.898194ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:46:47.275308Z","caller":"traceutil/trace.go:171","msg":"trace[1301448667] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"321.286712ms","start":"2026-03-18T12:46:46.953991Z","end":"2026-03-18T12:46:47.275278Z","steps":["trace[1301448667] 'process raft request'  (duration: 321.127988ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:46:47.275457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:46:46.953968Z","time spent":"321.409824ms","remote":"127.0.0.1:47958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4745,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:514 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4711 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"info","ts":"2026-03-18T12:47:07.830993Z","caller":"traceutil/trace.go:171","msg":"trace[1518877409] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"307.709831ms","start":"2026-03-18T12:47:07.523261Z","end":"2026-03-18T12:47:07.830971Z","steps":["trace[1518877409] 'process raft request'  (duration: 307.582669ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:47:07.831132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:47:07.523249Z","time spent":"307.814073ms","remote":"127.0.0.1:56766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":813,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/coredns-7c96b6546b-hhmv2.189df047b56b88af\" mod_revision:673 > success:<request_put:<key:\"/registry/events/kube-system/coredns-7c96b6546b-hhmv2.189df047b56b88af\" value_size:725 lease:9167531144032914932 >> failure:<request_range:<key:\"/registry/events/kube-system/coredns-7c96b6546b-hhmv2.189df047b56b88af\" > >"}
{"level":"warn","ts":"2026-03-18T12:48:01.532362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.096855ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9167531144032916516 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-xgt6r\" mod_revision:867 > success:<request_put:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-xgt6r\" value_size:1421 >> failure:<request_range:<key:\"/registry/endpointslices/kube-system/ceph-csi-rbd-nodeplugin-http-metrics-xgt6r\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-18T12:48:01.532505Z","caller":"traceutil/trace.go:171","msg":"trace[1855004073] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"190.210844ms","start":"2026-03-18T12:48:01.342235Z","end":"2026-03-18T12:48:01.532446Z","steps":["trace[1855004073] 'process raft request'  (duration: 47.812063ms)","trace[1855004073] 'compare'  (duration: 142.003564ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:01.839723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.312182ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9167531144032916517 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" mod_revision:866 > success:<request_put:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" value_size:830 >> failure:<request_range:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-18T12:48:01.839848Z","caller":"traceutil/trace.go:171","msg":"trace[786783065] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"491.01347ms","start":"2026-03-18T12:48:01.348822Z","end":"2026-03-18T12:48:01.839836Z","steps":["trace[786783065] 'process raft request'  (duration: 315.526465ms)","trace[786783065] 'compare'  (duration: 175.21279ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:01.839884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:48:01.348798Z","time spent":"491.070211ms","remote":"127.0.0.1:47950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" mod_revision:866 > success:<request_put:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" value_size:830 >> failure:<request_range:<key:\"/registry/services/endpoints/kube-system/ceph-csi-rbd-nodeplugin-http-metrics\" > >"}
{"level":"info","ts":"2026-03-18T12:48:01.839975Z","caller":"traceutil/trace.go:171","msg":"trace[316581296] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:986; }","duration":"355.005515ms","start":"2026-03-18T12:48:01.484937Z","end":"2026-03-18T12:48:01.839942Z","steps":["trace[316581296] 'read index received'  (duration: 179.420588ms)","trace[316581296] 'applied index is now lower than readState.Index'  (duration: 175.582867ms)"],"step_count":2}
{"level":"info","ts":"2026-03-18T12:48:01.8401Z","caller":"traceutil/trace.go:171","msg":"trace[1427454077] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"489.651905ms","start":"2026-03-18T12:48:01.350438Z","end":"2026-03-18T12:48:01.840089Z","steps":["trace[1427454077] 'process raft request'  (duration: 489.36018ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:01.840131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.703269ms","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-03-18T12:48:01.84015Z","caller":"traceutil/trace.go:171","msg":"trace[91369964] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:949; }","duration":"100.765121ms","start":"2026-03-18T12:48:01.739378Z","end":"2026-03-18T12:48:01.840143Z","steps":["trace[91369964] 'agreement among raft nodes before linearized reading'  (duration: 100.71449ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:01.84023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.305101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:48:01.840248Z","caller":"traceutil/trace.go:171","msg":"trace[351853833] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"355.360991ms","start":"2026-03-18T12:48:01.484883Z","end":"2026-03-18T12:48:01.840244Z","steps":["trace[351853833] 'agreement among raft nodes before linearized reading'  (duration: 355.335861ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:01.84026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:48:01.484862Z","time spent":"355.395873ms","remote":"127.0.0.1:56672","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-03-18T12:48:01.840212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:48:01.350428Z","time spent":"489.708597ms","remote":"127.0.0.1:48224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" mod_revision:856 > success:<request_put:<key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" value_size:9311 >> failure:<request_range:<key:\"/registry/daemonsets/kube-system/ceph-csi-rbd-nodeplugin\" > >"}
{"level":"warn","ts":"2026-03-18T12:48:02.588237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.293407ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9167531144032916525 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-n9pbf.189df05668845f4d\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-n9pbf.189df05668845f4d\" value_size:683 lease:9167531144032916297 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-03-18T12:48:02.588368Z","caller":"traceutil/trace.go:171","msg":"trace[1001649200] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"407.706639ms","start":"2026-03-18T12:48:02.180629Z","end":"2026-03-18T12:48:02.588335Z","steps":["trace[1001649200] 'read index received'  (duration: 27.251769ms)","trace[1001649200] 'applied index is now lower than readState.Index'  (duration: 380.45331ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:02.588499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.866152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:48:02.588547Z","caller":"traceutil/trace.go:171","msg":"trace[1084435275] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:952; }","duration":"407.944993ms","start":"2026-03-18T12:48:02.180588Z","end":"2026-03-18T12:48:02.588533Z","steps":["trace[1084435275] 'agreement among raft nodes before linearized reading'  (duration: 407.814811ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:02.588596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:48:02.180572Z","time spent":"408.013114ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-03-18T12:48:02.588881Z","caller":"traceutil/trace.go:171","msg":"trace[472529862] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"419.88714ms","start":"2026-03-18T12:48:02.168957Z","end":"2026-03-18T12:48:02.588844Z","steps":["trace[472529862] 'process raft request'  (duration: 38.918681ms)","trace[472529862] 'compare'  (duration: 380.157314ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:02.589038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:48:02.168935Z","time spent":"420.024703ms","remote":"127.0.0.1:56766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-n9pbf.189df05668845f4d\" mod_revision:0 > success:<request_put:<key:\"/registry/events/kube-system/ceph-csi-rbd-provisioner-69cb97fb6-n9pbf.189df05668845f4d\" value_size:683 lease:9167531144032916297 >> failure:<>"}
{"level":"info","ts":"2026-03-18T12:48:02.775476Z","caller":"traceutil/trace.go:171","msg":"trace[1584897565] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"182.706708ms","start":"2026-03-18T12:48:02.592743Z","end":"2026-03-18T12:48:02.77545Z","steps":["trace[1584897565] 'process raft request'  (duration: 182.461033ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:03.527685Z","caller":"traceutil/trace.go:171","msg":"trace[290726178] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"186.217032ms","start":"2026-03-18T12:48:03.341442Z","end":"2026-03-18T12:48:03.527659Z","steps":["trace[290726178] 'process raft request'  (duration: 185.554381ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:03.527947Z","caller":"traceutil/trace.go:171","msg":"trace[802457672] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"185.697723ms","start":"2026-03-18T12:48:03.342186Z","end":"2026-03-18T12:48:03.527884Z","steps":["trace[802457672] 'process raft request'  (duration: 184.98171ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:15.402268Z","caller":"traceutil/trace.go:171","msg":"trace[1956772952] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"134.705931ms","start":"2026-03-18T12:48:15.267527Z","end":"2026-03-18T12:48:15.402233Z","steps":["trace[1956772952] 'process raft request'  (duration: 113.879141ms)","trace[1956772952] 'compare'  (duration: 20.455783ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:15.651325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.174367ms","expected-duration":"100ms","prefix":"","request":"header:<ID:9167531144032916701 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:1026 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-18T12:48:15.651703Z","caller":"traceutil/trace.go:171","msg":"trace[1453175690] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"244.035329ms","start":"2026-03-18T12:48:15.407654Z","end":"2026-03-18T12:48:15.651689Z","steps":["trace[1453175690] 'process raft request'  (duration: 243.767613ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:15.652335Z","caller":"traceutil/trace.go:171","msg":"trace[761581025] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"244.612379ms","start":"2026-03-18T12:48:15.407512Z","end":"2026-03-18T12:48:15.652125Z","steps":["trace[761581025] 'process raft request'  (duration: 124.557756ms)","trace[761581025] 'compare'  (duration: 119.071545ms)"],"step_count":2}
{"level":"info","ts":"2026-03-18T12:48:15.652464Z","caller":"traceutil/trace.go:171","msg":"trace[115608773] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1076; }","duration":"166.622424ms","start":"2026-03-18T12:48:15.485011Z","end":"2026-03-18T12:48:15.651633Z","steps":["trace[115608773] 'read index received'  (duration: 47.020038ms)","trace[115608773] 'applied index is now lower than readState.Index'  (duration: 119.601466ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:15.652965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.950128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:48:15.653029Z","caller":"traceutil/trace.go:171","msg":"trace[1133198317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1036; }","duration":"168.04678ms","start":"2026-03-18T12:48:15.484955Z","end":"2026-03-18T12:48:15.653002Z","steps":["trace[1133198317] 'agreement among raft nodes before linearized reading'  (duration: 166.696585ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:15.654324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.611904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"}
{"level":"info","ts":"2026-03-18T12:48:15.654353Z","caller":"traceutil/trace.go:171","msg":"trace[1370119054] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1036; }","duration":"126.638044ms","start":"2026-03-18T12:48:15.527704Z","end":"2026-03-18T12:48:15.654342Z","steps":["trace[1370119054] 'agreement among raft nodes before linearized reading'  (duration: 124.861561ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:41.277443Z","caller":"traceutil/trace.go:171","msg":"trace[645194529] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"248.131373ms","start":"2026-03-18T12:48:41.029287Z","end":"2026-03-18T12:48:41.277418Z","steps":["trace[645194529] 'read index received'  (duration: 248.00042ms)","trace[645194529] 'applied index is now lower than readState.Index'  (duration: 130.033µs)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:48:41.27766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.352686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:48:41.277697Z","caller":"traceutil/trace.go:171","msg":"trace[1885911299] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1140; }","duration":"248.452018ms","start":"2026-03-18T12:48:41.029233Z","end":"2026-03-18T12:48:41.277685Z","steps":["trace[1885911299] 'agreement among raft nodes before linearized reading'  (duration: 248.339716ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:48:41.27815Z","caller":"traceutil/trace.go:171","msg":"trace[1752874406] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"282.43771ms","start":"2026-03-18T12:48:40.995698Z","end":"2026-03-18T12:48:41.278136Z","steps":["trace[1752874406] 'process raft request'  (duration: 281.516293ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:48:41.660269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.926092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-18T12:48:41.66045Z","caller":"traceutil/trace.go:171","msg":"trace[1987021295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1140; }","duration":"298.152117ms","start":"2026-03-18T12:48:41.36228Z","end":"2026-03-18T12:48:41.660432Z","steps":["trace[1987021295] 'range keys from in-memory index tree'  (duration: 297.791329ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.390215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9167531144032917203,"retry-timeout":"500ms"}
{"level":"info","ts":"2026-03-18T12:49:04.816809Z","caller":"traceutil/trace.go:171","msg":"trace[1231138900] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"928.784162ms","start":"2026-03-18T12:49:03.887996Z","end":"2026-03-18T12:49:04.81678Z","steps":["trace[1231138900] 'process raft request'  (duration: 928.599339ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.817046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:03.887969Z","time spent":"928.915515ms","remote":"127.0.0.1:48030","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:1224 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:430 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >"}
{"level":"info","ts":"2026-03-18T12:49:04.824066Z","caller":"traceutil/trace.go:171","msg":"trace[1752239078] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1284; }","duration":"934.029538ms","start":"2026-03-18T12:49:03.890014Z","end":"2026-03-18T12:49:04.824044Z","steps":["trace[1752239078] 'read index received'  (duration: 926.572312ms)","trace[1752239078] 'applied index is now lower than readState.Index'  (duration: 7.456416ms)"],"step_count":2}
{"level":"info","ts":"2026-03-18T12:49:04.824148Z","caller":"traceutil/trace.go:171","msg":"trace[12602571] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"934.274562ms","start":"2026-03-18T12:49:03.889837Z","end":"2026-03-18T12:49:04.824112Z","steps":["trace[12602571] 'process raft request'  (duration: 934.096039ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.824259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.219212ms","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":"warn","ts":"2026-03-18T12:49:04.82429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.336261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"}
{"level":"info","ts":"2026-03-18T12:49:04.824324Z","caller":"traceutil/trace.go:171","msg":"trace[1845970269] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1234; }","duration":"934.299943ms","start":"2026-03-18T12:49:03.890005Z","end":"2026-03-18T12:49:04.824305Z","steps":["trace[1845970269] 'agreement among raft nodes before linearized reading'  (duration: 934.122809ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:49:04.824335Z","caller":"traceutil/trace.go:171","msg":"trace[1371859145] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1234; }","duration":"930.419803ms","start":"2026-03-18T12:49:03.893903Z","end":"2026-03-18T12:49:04.824322Z","steps":["trace[1371859145] 'agreement among raft nodes before linearized reading'  (duration: 930.26363ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.824366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:03.889821Z","time spent":"934.434266ms","remote":"127.0.0.1:48030","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:1225 > 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-03-18T12:49:04.824391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:03.889978Z","time spent":"934.405316ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "}
{"level":"warn","ts":"2026-03-18T12:49:04.824375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.042392ms","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":"warn","ts":"2026-03-18T12:49:04.8244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:03.893893Z","time spent":"930.497515ms","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-03-18T12:49:04.824444Z","caller":"traceutil/trace.go:171","msg":"trace[1679289224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1234; }","duration":"916.155223ms","start":"2026-03-18T12:49:03.908273Z","end":"2026-03-18T12:49:04.824429Z","steps":["trace[1679289224] 'agreement among raft nodes before linearized reading'  (duration: 915.93781ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.824491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:03.908261Z","time spent":"916.223414ms","remote":"127.0.0.1:48030","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-03-18T12:49:04.824441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.229269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:49:04.824718Z","caller":"traceutil/trace.go:171","msg":"trace[368106834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"342.575965ms","start":"2026-03-18T12:49:04.482115Z","end":"2026-03-18T12:49:04.824691Z","steps":["trace[368106834] 'agreement among raft nodes before linearized reading'  (duration: 342.069346ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:04.824766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.482099Z","time spent":"342.654196ms","remote":"127.0.0.1:56672","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-03-18T12:49:07.4862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000374145s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"}
{"level":"info","ts":"2026-03-18T12:49:07.486289Z","caller":"traceutil/trace.go:171","msg":"trace[1718552022] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000507087s","start":"2026-03-18T12:49:05.485761Z","end":"2026-03-18T12:49:07.486268Z","steps":["trace[1718552022] 'agreement among raft nodes before linearized reading'  (duration: 2.000404095s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:07.486342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:05.485741Z","time spent":"2.000588939s","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/03/18 12:49:07 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-03-18T12:49:09.486963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000352174s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-03-18T12:49:09.487102Z","caller":"traceutil/trace.go:171","msg":"trace[2107315220] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000544748s","start":"2026-03-18T12:49:07.486518Z","end":"2026-03-18T12:49:09.487063Z","steps":["trace[2107315220] 'agreement among raft nodes before linearized reading'  (duration: 2.000390585s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:09.487164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:07.486492Z","time spent":"2.00066012s","remote":"127.0.0.1:56686","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-03-18T12:49:09.826945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.828728Z","time spent":"4.998208931s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""}
{"level":"warn","ts":"2026-03-18T12:49:09.82731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.829317Z","time spent":"4.997986458s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""}
2026/03/18 12:49:09 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-03-18T12:49:10.610638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00017194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"}
{"level":"info","ts":"2026-03-18T12:49:10.610794Z","caller":"traceutil/trace.go:171","msg":"trace[956503027] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000373984s","start":"2026-03-18T12:49:08.610396Z","end":"2026-03-18T12:49:10.61077Z","steps":["trace[956503027] 'agreement among raft nodes before linearized reading'  (duration: 2.000201591s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.610877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:08.610376Z","time spent":"2.000485996s","remote":"127.0.0.1:56672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "}
2026/03/18 12:49:10 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing"
{"level":"warn","ts":"2026-03-18T12:49:10.63921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"5.791476998s","expected-duration":"1s"}
{"level":"warn","ts":"2026-03-18T12:49:10.643722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.795268567s","expected-duration":"100ms","prefix":"","request":"header:<ID:9167531144032917209 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1226 > success:<request_put:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" value_size:452 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" > >>","response":"size:16"}
{"level":"info","ts":"2026-03-18T12:49:10.644023Z","caller":"traceutil/trace.go:171","msg":"trace[1270014604] linearizableReadLoop","detail":"{readStateIndex:1289; appliedIndex:1286; }","duration":"5.811321708s","start":"2026-03-18T12:49:04.83268Z","end":"2026-03-18T12:49:10.644002Z","steps":["trace[1270014604] 'read index received'  (duration: 15.082783ms)","trace[1270014604] 'applied index is now lower than readState.Index'  (duration: 5.796238065s)"],"step_count":2}
{"level":"info","ts":"2026-03-18T12:49:10.644029Z","caller":"traceutil/trace.go:171","msg":"trace[1650137537] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"5.816250738s","start":"2026-03-18T12:49:04.827752Z","end":"2026-03-18T12:49:10.644003Z","steps":["trace[1650137537] 'process raft request'  (duration: 19.999763ms)","trace[1650137537] 'compare'  (duration: 5.794352311s)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:49:10.644335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.827734Z","time spent":"5.816463292s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" mod_revision:1226 > success:<request_put:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" value_size:452 >> failure:<request_range:<key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" > >"}
{"level":"warn","ts":"2026-03-18T12:49:10.644343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.811642323s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"}
{"level":"info","ts":"2026-03-18T12:49:10.645214Z","caller":"traceutil/trace.go:171","msg":"trace[1658776678] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1237; }","duration":"5.812555321s","start":"2026-03-18T12:49:04.832622Z","end":"2026-03-18T12:49:10.645178Z","steps":["trace[1658776678] 'agreement among raft nodes before linearized reading'  (duration: 5.811608343s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.645259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.832608Z","time spent":"5.812639402s","remote":"127.0.0.1:48030","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-03-18T12:49:10.645387Z","caller":"traceutil/trace.go:171","msg":"trace[530200312] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"4.309313254s","start":"2026-03-18T12:49:06.336021Z","end":"2026-03-18T12:49:10.645334Z","steps":["trace[530200312] 'process raft request'  (duration: 4.308919857s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.64565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.335999Z","time spent":"4.309511197s","remote":"127.0.0.1:48030","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:1204 > 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-03-18T12:49:10.653934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.713380515s","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":"warn","ts":"2026-03-18T12:49:10.654127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.988274432s","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-03-18T12:49:10.654139Z","caller":"traceutil/trace.go:171","msg":"trace[875018527] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1239; }","duration":"5.713591249s","start":"2026-03-18T12:49:04.940434Z","end":"2026-03-18T12:49:10.654025Z","steps":["trace[875018527] 'agreement among raft nodes before linearized reading'  (duration: 5.713231423s)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:49:10.65423Z","caller":"traceutil/trace.go:171","msg":"trace[324646707] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1239; }","duration":"2.988564438s","start":"2026-03-18T12:49:07.665641Z","end":"2026-03-18T12:49:10.654205Z","steps":["trace[324646707] 'agreement among raft nodes before linearized reading'  (duration: 2.988042038s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.832738891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"}
{"level":"warn","ts":"2026-03-18T12:49:10.654284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:07.665625Z","time spent":"2.988648569s","remote":"127.0.0.1:48030","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":"info","ts":"2026-03-18T12:49:10.654284Z","caller":"traceutil/trace.go:171","msg":"trace[179387267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1239; }","duration":"3.832810712s","start":"2026-03-18T12:49:06.821461Z","end":"2026-03-18T12:49:10.654271Z","steps":["trace[179387267] 'agreement among raft nodes before linearized reading'  (duration: 3.83273639s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.821442Z","time spent":"3.832873534s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-03-18T12:49:10.654294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:04.94041Z","time spent":"5.713861835s","remote":"127.0.0.1:48048","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-03-18T12:49:10.654294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.825421197s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"warn","ts":"2026-03-18T12:49:10.654395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.836512ms","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":"warn","ts":"2026-03-18T12:49:10.654454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123250863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-18T12:49:10.654487Z","caller":"traceutil/trace.go:171","msg":"trace[1528946446] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1239; }","duration":"1.123328613s","start":"2026-03-18T12:49:09.531149Z","end":"2026-03-18T12:49:10.654478Z","steps":["trace[1528946446] 'agreement among raft nodes before linearized reading'  (duration: 1.123259372s)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:49:10.654484Z","caller":"traceutil/trace.go:171","msg":"trace[765524361] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1239; }","duration":"302.979994ms","start":"2026-03-18T12:49:10.351484Z","end":"2026-03-18T12:49:10.654464Z","steps":["trace[765524361] 'agreement among raft nodes before linearized reading'  (duration: 302.837252ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:09.531123Z","time spent":"1.123388525s","remote":"127.0.0.1:48118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "}
{"level":"warn","ts":"2026-03-18T12:49:10.654535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:10.35146Z","time spent":"303.064776ms","remote":"127.0.0.1:48164","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":"info","ts":"2026-03-18T12:49:10.654494Z","caller":"traceutil/trace.go:171","msg":"trace[1911063787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1239; }","duration":"3.825647153s","start":"2026-03-18T12:49:06.8288Z","end":"2026-03-18T12:49:10.654447Z","steps":["trace[1911063787] 'agreement among raft nodes before linearized reading'  (duration: 3.825302436s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.828779Z","time spent":"3.825839256s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-03-18T12:49:10.654164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.338894712s","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-03-18T12:49:10.654687Z","caller":"traceutil/trace.go:171","msg":"trace[617548588] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1239; }","duration":"4.339473004s","start":"2026-03-18T12:49:06.315204Z","end":"2026-03-18T12:49:10.654677Z","steps":["trace[617548588] 'agreement among raft nodes before linearized reading'  (duration: 4.338810891s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.315181Z","time spent":"4.339529584s","remote":"127.0.0.1:48030","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-03-18T12:49:10.654726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.160271495s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:49:10.654754Z","caller":"traceutil/trace.go:171","msg":"trace[103246487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"1.160318936s","start":"2026-03-18T12:49:09.494427Z","end":"2026-03-18T12:49:10.654746Z","steps":["trace[103246487] 'agreement among raft nodes before linearized reading'  (duration: 1.160220524s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.65475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:09.490811Z","time spent":"1.163936252s","remote":"127.0.0.1:56766","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-03-18T12:49:10.654823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.681793426s","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":"warn","ts":"2026-03-18T12:49:10.65483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.351725366s","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-03-18T12:49:10.654848Z","caller":"traceutil/trace.go:171","msg":"trace[1359940003] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1240; }","duration":"2.681838997s","start":"2026-03-18T12:49:07.973003Z","end":"2026-03-18T12:49:10.654842Z","steps":["trace[1359940003] 'agreement among raft nodes before linearized reading'  (duration: 2.681768155s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:07.972987Z","time spent":"2.681875717s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "}
{"level":"info","ts":"2026-03-18T12:49:10.654883Z","caller":"traceutil/trace.go:171","msg":"trace[325424844] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:1240; }","duration":"4.351812258s","start":"2026-03-18T12:49:06.303044Z","end":"2026-03-18T12:49:10.654856Z","steps":["trace[325424844] 'agreement among raft nodes before linearized reading'  (duration: 4.351610194s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:09.494415Z","time spent":"1.160358447s","remote":"127.0.0.1:56686","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-03-18T12:49:10.65495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.303024Z","time spent":"4.351913279s","remote":"127.0.0.1:47972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true "}
{"level":"info","ts":"2026-03-18T12:49:10.655057Z","caller":"traceutil/trace.go:171","msg":"trace[1886836025] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"600.746792ms","start":"2026-03-18T12:49:10.0543Z","end":"2026-03-18T12:49:10.655047Z","steps":["trace[1886836025] 'process raft request'  (duration: 599.167494ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.654601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.879669723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-03-18T12:49:10.655156Z","caller":"traceutil/trace.go:171","msg":"trace[678466050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1239; }","duration":"4.880352087s","start":"2026-03-18T12:49:05.774794Z","end":"2026-03-18T12:49:10.655146Z","steps":["trace[678466050] 'agreement among raft nodes before linearized reading'  (duration: 4.879508311s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.655192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:05.774772Z","time spent":"4.880409938s","remote":"127.0.0.1:48030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"warn","ts":"2026-03-18T12:49:10.655126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:10.05426Z","time spent":"600.820874ms","remote":"127.0.0.1:48030","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-node-lease/instance\" mod_revision:1221 > success:<request_put:<key:\"/registry/leases/kube-node-lease/instance\" value_size:471 >> failure:<request_range:<key:\"/registry/leases/kube-node-lease/instance\" > >"}
{"level":"warn","ts":"2026-03-18T12:49:10.655441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.09372671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"}
{"level":"info","ts":"2026-03-18T12:49:10.65548Z","caller":"traceutil/trace.go:171","msg":"trace[712366744] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1240; }","duration":"5.093806651s","start":"2026-03-18T12:49:05.561664Z","end":"2026-03-18T12:49:10.65547Z","steps":["trace[712366744] 'agreement among raft nodes before linearized reading'  (duration: 5.093723529s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.655506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:05.561644Z","time spent":"5.093855542s","remote":"127.0.0.1:56736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "}
{"level":"warn","ts":"2026-03-18T12:49:10.656573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.82503258s","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-03-18T12:49:10.656718Z","caller":"traceutil/trace.go:171","msg":"trace[1680679207] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1239; }","duration":"3.827555656s","start":"2026-03-18T12:49:06.829151Z","end":"2026-03-18T12:49:10.656706Z","steps":["trace[1680679207] 'agreement among raft nodes before linearized reading'  (duration: 3.82500542s)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:10.656986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:06.829144Z","time spent":"3.827830361s","remote":"127.0.0.1:47926","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":"info","ts":"2026-03-18T12:49:38.487825Z","caller":"traceutil/trace.go:171","msg":"trace[801317679] linearizableReadLoop","detail":"{readStateIndex:1414; appliedIndex:1413; }","duration":"490.419995ms","start":"2026-03-18T12:49:37.997384Z","end":"2026-03-18T12:49:38.487804Z","steps":["trace[801317679] 'read index received'  (duration: 490.176391ms)","trace[801317679] 'applied index is now lower than readState.Index'  (duration: 242.894µs)"],"step_count":2}
{"level":"info","ts":"2026-03-18T12:49:38.487936Z","caller":"traceutil/trace.go:171","msg":"trace[1934918028] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"497.590657ms","start":"2026-03-18T12:49:37.990338Z","end":"2026-03-18T12:49:38.487928Z","steps":["trace[1934918028] 'process raft request'  (duration: 497.325252ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:38.488023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:37.990312Z","time spent":"497.647097ms","remote":"127.0.0.1:48030","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:1349 > 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-03-18T12:49:38.488155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.149827ms","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":"warn","ts":"2026-03-18T12:49:38.488223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.817652ms","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-03-18T12:49:38.488268Z","caller":"traceutil/trace.go:171","msg":"trace[1740320741] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1355; }","duration":"286.32108ms","start":"2026-03-18T12:49:38.201926Z","end":"2026-03-18T12:49:38.488247Z","steps":["trace[1740320741] 'agreement among raft nodes before linearized reading'  (duration: 286.097986ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:49:38.488301Z","caller":"traceutil/trace.go:171","msg":"trace[1089686018] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"490.935795ms","start":"2026-03-18T12:49:37.997346Z","end":"2026-03-18T12:49:38.488281Z","steps":["trace[1089686018] 'agreement among raft nodes before linearized reading'  (duration: 490.742791ms)"],"step_count":1}
{"level":"warn","ts":"2026-03-18T12:49:38.488481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:49:37.997332Z","time spent":"491.126158ms","remote":"127.0.0.1:48030","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-03-18T12:49:38.732265Z","caller":"traceutil/trace.go:171","msg":"trace[261585295] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"120.438781ms","start":"2026-03-18T12:49:38.611801Z","end":"2026-03-18T12:49:38.73224Z","steps":["trace[261585295] 'read index received'  (duration: 97.798289ms)","trace[261585295] 'applied index is now lower than readState.Index'  (duration: 22.639552ms)"],"step_count":2}
{"level":"warn","ts":"2026-03-18T12:49:38.732399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.575295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-03-18T12:49:38.732429Z","caller":"traceutil/trace.go:171","msg":"trace[1703440122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1356; }","duration":"120.623245ms","start":"2026-03-18T12:49:38.611795Z","end":"2026-03-18T12:49:38.732418Z","steps":["trace[1703440122] 'agreement among raft nodes before linearized reading'  (duration: 120.555894ms)"],"step_count":1}
{"level":"info","ts":"2026-03-18T12:49:38.732496Z","caller":"traceutil/trace.go:171","msg":"trace[795165534] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"239.545149ms","start":"2026-03-18T12:49:38.492921Z","end":"2026-03-18T12:49:38.732466Z","steps":["trace[795165534] 'process raft request'  (duration: 216.774925ms)","trace[795165534] 'compare'  (duration: 22.424458ms)"],"step_count":2}