{"level":"warn","ts":"2026-02-22T17:44:14.994534Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-02-22T17:44:14.994642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-02-22T17:44:14.99474Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-02-22T17:44:14.994753Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]}
{"level":"info","ts":"2026-02-22T17:44:14.994773Z","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-02-22T17:44:14.995557Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]}
{"level":"info","ts":"2026-02-22T17:44:14.995643Z","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.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-02-22T17:44:14.999937Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.019807ms"}
{"level":"info","ts":"2026-02-22T17:44:15.007202Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"}
{"level":"info","ts":"2026-02-22T17:44:15.007357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"}
{"level":"info","ts":"2026-02-22T17:44:15.007432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"}
{"level":"info","ts":"2026-02-22T17:44:15.007486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-02-22T17:44:15.008192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"}
{"level":"info","ts":"2026-02-22T17:44:15.008276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"}
{"level":"warn","ts":"2026-02-22T17:44:15.015531Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-02-22T17:44:15.019952Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-02-22T17:44:15.0219Z","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-02-22T17:44:15.024882Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-02-22T17:44:15.025135Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-02-22T17:44:15.025452Z","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-02-22T17:44:15.025584Z","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-02-22T17:44:15.025609Z","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-02-22T17:44:15.027093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"}
{"level":"info","ts":"2026-02-22T17:44:15.027365Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]}
{"level":"info","ts":"2026-02-22T17:44:15.031104Z","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-02-22T17:44:15.031218Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"}
{"level":"info","ts":"2026-02-22T17:44:15.031255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"}
{"level":"info","ts":"2026-02-22T17:44:15.031381Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-02-22T17:44:15.03143Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-02-22T17:44:15.108431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"}
{"level":"info","ts":"2026-02-22T17:44:15.10848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"}
{"level":"info","ts":"2026-02-22T17:44:15.108504Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"}
{"level":"info","ts":"2026-02-22T17:44:15.108519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"}
{"level":"info","ts":"2026-02-22T17:44:15.108526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"}
{"level":"info","ts":"2026-02-22T17:44:15.108542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"}
{"level":"info","ts":"2026-02-22T17:44:15.108553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"}
{"level":"info","ts":"2026-02-22T17:44:15.109307Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"}
{"level":"info","ts":"2026-02-22T17:44:15.109522Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-22T17:44:15.109576Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-22T17:44:15.110154Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-22T17:44:15.110427Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-02-22T17:44:15.11045Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-02-22T17:44:15.11112Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-22T17:44:15.11245Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-22T17:44:15.112564Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-22T17:44:15.112914Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-02-22T17:44:15.113181Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"}
{"level":"info","ts":"2026-02-22T17:44:18.709804Z","caller":"traceutil/trace.go:171","msg":"trace[739608873] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"192.815462ms","start":"2026-02-22T17:44:18.516958Z","end":"2026-02-22T17:44:18.709774Z","steps":["trace[739608873] 'process raft request'  (duration: 183.107924ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:19.851797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.49877ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15853968689531560367 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" value_size:679 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-22T17:44:19.852024Z","caller":"traceutil/trace.go:171","msg":"trace[1033442980] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:178; }","duration":"819.666038ms","start":"2026-02-22T17:44:19.032339Z","end":"2026-02-22T17:44:19.852005Z","steps":["trace[1033442980] 'read index received'  (duration: 384.37715ms)","trace[1033442980] 'applied index is now lower than readState.Index'  (duration: 435.287098ms)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:44:19.852106Z","caller":"traceutil/trace.go:171","msg":"trace[1389432976] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"960.037383ms","start":"2026-02-22T17:44:18.891991Z","end":"2026-02-22T17:44:19.852029Z","steps":["trace[1389432976] 'process raft request'  (duration: 524.754966ms)","trace[1389432976] 'compare'  (duration: 434.357157ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:44:19.852334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.88538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-22T17:44:19.852429Z","caller":"traceutil/trace.go:171","msg":"trace[1855370315] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:175; }","duration":"820.134754ms","start":"2026-02-22T17:44:19.03228Z","end":"2026-02-22T17:44:19.852414Z","steps":["trace[1855370315] 'agreement among raft nodes before linearized reading'  (duration: 819.84453ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:19.852408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:18.891976Z","time spent":"960.289586ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" value_size:679 >> failure:<>"}
{"level":"warn","ts":"2026-02-22T17:44:19.852584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:19.032248Z","time spent":"820.322515ms","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-02-22T17:44:20.732371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.306491ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15853968689531560371 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" value_size:676 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-22T17:44:20.732538Z","caller":"traceutil/trace.go:171","msg":"trace[1678459444] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"872.528738ms","start":"2026-02-22T17:44:19.859962Z","end":"2026-02-22T17:44:20.732491Z","steps":["trace[1678459444] 'process raft request'  (duration: 637.969524ms)","trace[1678459444] 'compare'  (duration: 233.905407ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:44:20.732753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:19.859924Z","time spent":"872.7256ms","remote":"127.0.0.1:43692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/system:controller:pv-protection-controller\" value_size:676 >> failure:<>"}
{"level":"info","ts":"2026-02-22T17:44:20.749684Z","caller":"traceutil/trace.go:171","msg":"trace[1958416469] linearizableReadLoop","detail":"{readStateIndex:181; appliedIndex:179; }","duration":"716.662751ms","start":"2026-02-22T17:44:20.032968Z","end":"2026-02-22T17:44:20.749631Z","steps":["trace[1958416469] 'read index received'  (duration: 464.923922ms)","trace[1958416469] 'applied index is now lower than readState.Index'  (duration: 251.737809ms)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:44:20.74985Z","caller":"traceutil/trace.go:171","msg":"trace[113486197] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"788.168561ms","start":"2026-02-22T17:44:19.961668Z","end":"2026-02-22T17:44:20.749837Z","steps":["trace[113486197] 'process raft request'  (duration: 787.835798ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:20.74996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:19.961638Z","time spent":"788.245562ms","remote":"127.0.0.1:43498","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2883,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-scheduler-instance\" mod_revision:0 > success:<request_put:<key:\"/registry/pods/kube-system/kube-scheduler-instance\" value_size:2825 >> failure:<>"}
{"level":"warn","ts":"2026-02-22T17:44:20.750051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.466575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-22T17:44:20.750172Z","caller":"traceutil/trace.go:171","msg":"trace[1840240635] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:177; }","duration":"212.636087ms","start":"2026-02-22T17:44:20.537496Z","end":"2026-02-22T17:44:20.750132Z","steps":["trace[1840240635] 'agreement among raft nodes before linearized reading'  (duration: 212.437785ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:20.750409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.435419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-22T17:44:20.750458Z","caller":"traceutil/trace.go:171","msg":"trace[1697840079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:177; }","duration":"717.518261ms","start":"2026-02-22T17:44:20.03293Z","end":"2026-02-22T17:44:20.750448Z","steps":["trace[1697840079] 'agreement among raft nodes before linearized reading'  (duration: 717.44549ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:20.75053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:20.032914Z","time spent":"717.606732ms","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "}
{"level":"info","ts":"2026-02-22T17:44:47.868625Z","caller":"traceutil/trace.go:171","msg":"trace[500163663] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"114.199796ms","start":"2026-02-22T17:44:47.754378Z","end":"2026-02-22T17:44:47.868578Z","steps":["trace[500163663] 'process raft request'  (duration: 113.966204ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:44:49.20908Z","caller":"traceutil/trace.go:171","msg":"trace[876343863] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"135.693634ms","start":"2026-02-22T17:44:49.073347Z","end":"2026-02-22T17:44:49.20904Z","steps":["trace[876343863] 'process raft request'  (duration: 135.568673ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:49.418491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.420753ms","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-02-22T17:44:49.418617Z","caller":"traceutil/trace.go:171","msg":"trace[2087581796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"115.619524ms","start":"2026-02-22T17:44:49.302966Z","end":"2026-02-22T17:44:49.418585Z","steps":["trace[2087581796] 'range keys from in-memory index tree'  (duration: 115.255741ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:44:55.453711Z","caller":"traceutil/trace.go:171","msg":"trace[486759665] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"177.3842ms","start":"2026-02-22T17:44:55.276308Z","end":"2026-02-22T17:44:55.453692Z","steps":["trace[486759665] 'process raft request'  (duration: 177.291329ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:56.037131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.321897ms","expected-duration":"100ms","prefix":"","request":"header:<ID:15853968689531561174 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:549 > 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-02-22T17:44:56.037247Z","caller":"traceutil/trace.go:171","msg":"trace[447647988] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"215.704652ms","start":"2026-02-22T17:44:55.821525Z","end":"2026-02-22T17:44:56.03723Z","steps":["trace[447647988] 'read index received'  (duration: 50.701µs)","trace[447647988] 'applied index is now lower than readState.Index'  (duration: 215.652781ms)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:44:56.037351Z","caller":"traceutil/trace.go:171","msg":"trace[366601644] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"308.490782ms","start":"2026-02-22T17:44:55.728828Z","end":"2026-02-22T17:44:56.037319Z","steps":["trace[366601644] 'process raft request'  (duration: 10.744542ms)","trace[366601644] 'compare'  (duration: 297.206676ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:44:56.03752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:55.728805Z","time spent":"308.632163ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:549 > 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-02-22T17:44:56.037868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.366106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p6zc9\" ","response":"range_response_count:1 size:16656"}
{"level":"info","ts":"2026-02-22T17:44:56.037924Z","caller":"traceutil/trace.go:171","msg":"trace[1217619705] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p6zc9; range_end:; response_count:1; response_revision:556; }","duration":"216.472027ms","start":"2026-02-22T17:44:55.82144Z","end":"2026-02-22T17:44:56.037912Z","steps":["trace[1217619705] 'agreement among raft nodes before linearized reading'  (duration: 215.835442ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:44:56.332819Z","caller":"traceutil/trace.go:171","msg":"trace[449194051] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"278.761703ms","start":"2026-02-22T17:44:56.054016Z","end":"2026-02-22T17:44:56.332778Z","steps":["trace[449194051] 'process raft request'  (duration: 278.612722ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:44:57.775642Z","caller":"traceutil/trace.go:171","msg":"trace[1183737254] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"307.380716ms","start":"2026-02-22T17:44:57.468239Z","end":"2026-02-22T17:44:57.77562Z","steps":["trace[1183737254] 'read index received'  (duration: 307.154715ms)","trace[1183737254] 'applied index is now lower than readState.Index'  (duration: 224.831µs)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:44:57.776141Z","caller":"traceutil/trace.go:171","msg":"trace[2102874992] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"314.364189ms","start":"2026-02-22T17:44:57.461726Z","end":"2026-02-22T17:44:57.77609Z","steps":["trace[2102874992] 'process raft request'  (duration: 313.743654ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:57.7765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.439373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-22T17:44:57.776539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:57.461707Z","time spent":"314.715541ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:550 > 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-02-22T17:44:57.776597Z","caller":"traceutil/trace.go:171","msg":"trace[420414965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"177.554433ms","start":"2026-02-22T17:44:57.599003Z","end":"2026-02-22T17:44:57.776557Z","steps":["trace[420414965] 'agreement among raft nodes before linearized reading'  (duration: 177.431543ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:57.779611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.938113ms","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-02-22T17:44:57.77982Z","caller":"traceutil/trace.go:171","msg":"trace[551274877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"311.588308ms","start":"2026-02-22T17:44:57.468208Z","end":"2026-02-22T17:44:57.779796Z","steps":["trace[551274877] 'agreement among raft nodes before linearized reading'  (duration: 308.018471ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:57.779879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:57.468196Z","time spent":"311.666998ms","remote":"127.0.0.1:43590","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-02-22T17:44:58.572376Z","caller":"traceutil/trace.go:171","msg":"trace[1221776678] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"789.572222ms","start":"2026-02-22T17:44:57.782774Z","end":"2026-02-22T17:44:58.572347Z","steps":["trace[1221776678] 'process raft request'  (duration: 692.639093ms)","trace[1221776678] 'compare'  (duration: 96.826219ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:44:58.57253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:57.782755Z","time spent":"789.689673ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:555 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:371 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-02-22T17:44:58.881398Z","caller":"traceutil/trace.go:171","msg":"trace[1968034896] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.097180327s","start":"2026-02-22T17:44:57.784163Z","end":"2026-02-22T17:44:58.881343Z","steps":["trace[1968034896] 'process raft request'  (duration: 1.095804887s)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:44:58.8815Z","caller":"traceutil/trace.go:171","msg":"trace[1516716776] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:579; }","duration":"840.030462ms","start":"2026-02-22T17:44:58.041445Z","end":"2026-02-22T17:44:58.881475Z","steps":["trace[1516716776] 'read index received'  (duration: 434.170328ms)","trace[1516716776] 'applied index is now lower than readState.Index'  (duration: 405.858954ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:44:58.881545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:57.78415Z","time spent":"1.097321858s","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:551 > 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-02-22T17:44:58.881598Z","caller":"traceutil/trace.go:171","msg":"trace[1167090456] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"925.476087ms","start":"2026-02-22T17:44:57.956103Z","end":"2026-02-22T17:44:58.881579Z","steps":["trace[1167090456] 'process raft request'  (duration: 925.180965ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:58.881696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.587187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"warn","ts":"2026-02-22T17:44:58.881707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:57.956081Z","time spent":"925.564488ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" mod_revision:512 > 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-02-22T17:44:58.881733Z","caller":"traceutil/trace.go:171","msg":"trace[1119344573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"282.649367ms","start":"2026-02-22T17:44:58.599072Z","end":"2026-02-22T17:44:58.881722Z","steps":["trace[1119344573] 'agreement among raft nodes before linearized reading'  (duration: 282.598737ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:58.88191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.455715ms","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-02-22T17:44:58.882185Z","caller":"traceutil/trace.go:171","msg":"trace[1289509972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"840.816658ms","start":"2026-02-22T17:44:58.041352Z","end":"2026-02-22T17:44:58.882168Z","steps":["trace[1289509972] 'agreement among raft nodes before linearized reading'  (duration: 840.236284ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:44:58.882236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:44:58.04133Z","time spent":"840.894979ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-22T17:45:01.315654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.352173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gljrn\" ","response":"range_response_count:1 size:4822"}
{"level":"info","ts":"2026-02-22T17:45:01.315705Z","caller":"traceutil/trace.go:171","msg":"trace[2074747960] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-gljrn; range_end:; response_count:1; response_revision:586; }","duration":"211.463454ms","start":"2026-02-22T17:45:01.104229Z","end":"2026-02-22T17:45:01.315693Z","steps":["trace[2074747960] 'range keys from in-memory index tree'  (duration: 211.218892ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:45:01.315734Z","caller":"traceutil/trace.go:171","msg":"trace[1788861330] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"210.221475ms","start":"2026-02-22T17:45:01.105493Z","end":"2026-02-22T17:45:01.315715Z","steps":["trace[1788861330] 'process raft request'  (duration: 210.111694ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:45:19.315548Z","caller":"traceutil/trace.go:171","msg":"trace[985190005] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"246.934981ms","start":"2026-02-22T17:45:19.06859Z","end":"2026-02-22T17:45:19.315525Z","steps":["trace[985190005] 'read index received'  (duration: 246.758672ms)","trace[985190005] 'applied index is now lower than readState.Index'  (duration: 175.189µs)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:45:19.315622Z","caller":"traceutil/trace.go:171","msg":"trace[475221600] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"307.660955ms","start":"2026-02-22T17:45:19.007941Z","end":"2026-02-22T17:45:19.315602Z","steps":["trace[475221600] 'process raft request'  (duration: 307.450873ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:45:19.315805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:45:19.007913Z","time spent":"307.814893ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:766 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:366 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"warn","ts":"2026-02-22T17:45:19.315855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.04629ms","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-02-22T17:45:19.315946Z","caller":"traceutil/trace.go:171","msg":"trace[174577550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:771; }","duration":"181.186818ms","start":"2026-02-22T17:45:19.134739Z","end":"2026-02-22T17:45:19.315925Z","steps":["trace[174577550] 'agreement among raft nodes before linearized reading'  (duration: 180.947165ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:45:19.316212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.618538ms","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-02-22T17:45:19.316261Z","caller":"traceutil/trace.go:171","msg":"trace[1267139066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"247.704572ms","start":"2026-02-22T17:45:19.068545Z","end":"2026-02-22T17:45:19.31625Z","steps":["trace[1267139066] 'agreement among raft nodes before linearized reading'  (duration: 247.590886ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:45:20.121459Z","caller":"traceutil/trace.go:171","msg":"trace[237046343] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"140.706782ms","start":"2026-02-22T17:45:19.980655Z","end":"2026-02-22T17:45:20.121361Z","steps":["trace[237046343] 'process raft request'  (duration: 139.994624ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:46:32.172209Z","caller":"traceutil/trace.go:171","msg":"trace[1033248550] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"135.253584ms","start":"2026-02-22T17:46:32.036928Z","end":"2026-02-22T17:46:32.172182Z","steps":["trace[1033248550] 'read index received'  (duration: 134.997458ms)","trace[1033248550] 'applied index is now lower than readState.Index'  (duration: 254.996µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-22T17:46:32.172525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.554092ms","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-02-22T17:46:32.172588Z","caller":"traceutil/trace.go:171","msg":"trace[1306704846] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1019; }","duration":"135.678655ms","start":"2026-02-22T17:46:32.036872Z","end":"2026-02-22T17:46:32.172551Z","steps":["trace[1306704846] 'agreement among raft nodes before linearized reading'  (duration: 135.445459ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:46:32.172715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.179552ms","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-02-22T17:46:32.172804Z","caller":"traceutil/trace.go:171","msg":"trace[1818531803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1019; }","duration":"107.293395ms","start":"2026-02-22T17:46:32.065491Z","end":"2026-02-22T17:46:32.172784Z","steps":["trace[1818531803] 'agreement among raft nodes before linearized reading'  (duration: 107.075119ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:46:32.173121Z","caller":"traceutil/trace.go:171","msg":"trace[1230774787] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"396.50798ms","start":"2026-02-22T17:46:31.776597Z","end":"2026-02-22T17:46:32.173105Z","steps":["trace[1230774787] 'process raft request'  (duration: 395.416262ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-22T17:46:32.173219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:31.776579Z","time spent":"396.577161ms","remote":"127.0.0.1:43590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:1010 > 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-02-22T17:46:50.534545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.653807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"}
{"level":"info","ts":"2026-02-22T17:46:50.534632Z","caller":"traceutil/trace.go:171","msg":"trace[2064699266] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1072; }","duration":"110.7687ms","start":"2026-02-22T17:46:50.423843Z","end":"2026-02-22T17:46:50.534611Z","steps":["trace[2064699266] 'range keys from in-memory index tree'  (duration: 110.535755ms)"],"step_count":1}
{"level":"info","ts":"2026-02-22T17:46:50.534992Z","caller":"traceutil/trace.go:171","msg":"trace[1421591964] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"111.120518ms","start":"2026-02-22T17:46:50.423856Z","end":"2026-02-22T17:46:50.534977Z","steps":["trace[1421591964] 'process raft request'  (duration: 44.929428ms)","trace[1421591964] 'compare'  (duration: 65.994115ms)"],"step_count":2}
{"level":"info","ts":"2026-02-22T17:46:50.535022Z","caller":"traceutil/trace.go:171","msg":"trace[828126312] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"111.083787ms","start":"2026-02-22T17:46:50.423906Z","end":"2026-02-22T17:46:50.53499Z","steps":["trace[828126312] 'process raft request'  (duration: 111.012935ms)"],"step_count":1}