{"level":"warn","ts":"2026-04-07T15:04:40.641751Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-07T15:04:40.641836Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-04-07T15:04:40.641916Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."}
{"level":"info","ts":"2026-04-07T15:04:40.641934Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]}
{"level":"info","ts":"2026-04-07T15:04:40.641955Z","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-04-07T15:04:40.642608Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]}
{"level":"info","ts":"2026-04-07T15:04:40.642705Z","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":16,"max-cpu-available":16,"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.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-04-07T15:04:40.646865Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.946053ms"}
{"level":"info","ts":"2026-04-07T15:04:40.653778Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"}
{"level":"info","ts":"2026-04-07T15:04:40.653969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"}
{"level":"info","ts":"2026-04-07T15:04:40.654019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"}
{"level":"info","ts":"2026-04-07T15:04:40.654039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-04-07T15:04:40.654071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"}
{"level":"info","ts":"2026-04-07T15:04:40.654136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"}
{"level":"warn","ts":"2026-04-07T15:04:40.65949Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-04-07T15:04:40.661903Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-04-07T15:04:40.663286Z","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-04-07T15:04:40.666382Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-04-07T15:04:40.666609Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-04-07T15:04:40.666555Z","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-04-07T15:04:40.667443Z","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-04-07T15:04:40.667578Z","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-04-07T15:04:40.668586Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"}
{"level":"info","ts":"2026-04-07T15:04:40.669269Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]}
{"level":"info","ts":"2026-04-07T15:04:40.671218Z","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-04-07T15:04:40.67131Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"}
{"level":"info","ts":"2026-04-07T15:04:40.671362Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"}
{"level":"info","ts":"2026-04-07T15:04:40.671495Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-04-07T15:04:40.671526Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-04-07T15:04:40.954858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"}
{"level":"info","ts":"2026-04-07T15:04:40.95494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"}
{"level":"info","ts":"2026-04-07T15:04:40.954991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"}
{"level":"info","ts":"2026-04-07T15:04:40.955119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"}
{"level":"info","ts":"2026-04-07T15:04:40.955145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"}
{"level":"info","ts":"2026-04-07T15:04:40.955164Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"}
{"level":"info","ts":"2026-04-07T15:04:40.955178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"}
{"level":"info","ts":"2026-04-07T15:04:40.956341Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T15:04:40.95695Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"}
{"level":"info","ts":"2026-04-07T15:04:40.956954Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-07T15:04:40.956991Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-04-07T15:04:40.957347Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T15:04:40.957457Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-04-07T15:04:40.95756Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-04-07T15:04:40.957519Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T15:04:40.957604Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-04-07T15:04:40.960525Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-04-07T15:04:40.9609Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"}
{"level":"info","ts":"2026-04-07T15:04:45.967207Z","caller":"traceutil/trace.go:171","msg":"trace[527671155] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"145.495618ms","start":"2026-04-07T15:04:45.821682Z","end":"2026-04-07T15:04:45.967177Z","steps":["trace[527671155] 'process raft request'  (duration: 145.269611ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:46.070141Z","caller":"traceutil/trace.go:171","msg":"trace[752589578] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"201.860235ms","start":"2026-04-07T15:04:45.868239Z","end":"2026-04-07T15:04:46.0701Z","steps":["trace[752589578] 'process raft request'  (duration: 179.568016ms)","trace[752589578] 'compare'  (duration: 22.072502ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:46.427956Z","caller":"traceutil/trace.go:171","msg":"trace[2063811681] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"115.041084ms","start":"2026-04-07T15:04:46.312874Z","end":"2026-04-07T15:04:46.427915Z","steps":["trace[2063811681] 'process raft request'  (duration: 32.214789ms)","trace[2063811681] 'compare'  (duration: 82.522865ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:46.818946Z","caller":"traceutil/trace.go:171","msg":"trace[589997900] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:254; }","duration":"215.870683ms","start":"2026-04-07T15:04:46.603032Z","end":"2026-04-07T15:04:46.818903Z","steps":["trace[589997900] 'read index received'  (duration: 174.480517ms)","trace[589997900] 'applied index is now lower than readState.Index'  (duration: 41.388826ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:46.819012Z","caller":"traceutil/trace.go:171","msg":"trace[1784798828] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"216.680858ms","start":"2026-04-07T15:04:46.602289Z","end":"2026-04-07T15:04:46.81897Z","steps":["trace[1784798828] 'process raft request'  (duration: 175.104486ms)","trace[1784798828] 'compare'  (duration: 41.378706ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T15:04:46.819072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.035318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T15:04:46.819213Z","caller":"traceutil/trace.go:171","msg":"trace[856817450] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:249; }","duration":"216.227584ms","start":"2026-04-07T15:04:46.602964Z","end":"2026-04-07T15:04:46.819191Z","steps":["trace[856817450] 'agreement among raft nodes before linearized reading'  (duration: 216.048089ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:46.82355Z","caller":"traceutil/trace.go:171","msg":"trace[337753334] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"220.503458ms","start":"2026-04-07T15:04:46.603022Z","end":"2026-04-07T15:04:46.823526Z","steps":["trace[337753334] 'process raft request'  (duration: 220.362714ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:46.984391Z","caller":"traceutil/trace.go:171","msg":"trace[1305662868] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"118.627646ms","start":"2026-04-07T15:04:46.86574Z","end":"2026-04-07T15:04:46.984367Z","steps":["trace[1305662868] 'process raft request'  (duration: 117.81194ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:46.984486Z","caller":"traceutil/trace.go:171","msg":"trace[119089642] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:258; }","duration":"117.537222ms","start":"2026-04-07T15:04:46.866917Z","end":"2026-04-07T15:04:46.984454Z","steps":["trace[119089642] 'read index received'  (duration: 2.439566ms)","trace[119089642] 'applied index is now lower than readState.Index'  (duration: 115.096086ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:46.98473Z","caller":"traceutil/trace.go:171","msg":"trace[998356486] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"117.224493ms","start":"2026-04-07T15:04:46.867487Z","end":"2026-04-07T15:04:46.984711Z","steps":["trace[998356486] 'process raft request'  (duration: 116.81614ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:46.984762Z","caller":"traceutil/trace.go:171","msg":"trace[929573869] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"117.040687ms","start":"2026-04-07T15:04:46.867708Z","end":"2026-04-07T15:04:46.984748Z","steps":["trace[929573869] 'process raft request'  (duration: 116.665775ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:46.984793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.854582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"}
{"level":"info","ts":"2026-04-07T15:04:46.984906Z","caller":"traceutil/trace.go:171","msg":"trace[1444811199] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:256; }","duration":"117.988076ms","start":"2026-04-07T15:04:46.866891Z","end":"2026-04-07T15:04:46.984879Z","steps":["trace[1444811199] 'agreement among raft nodes before linearized reading'  (duration: 117.80925ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.011273Z","caller":"traceutil/trace.go:171","msg":"trace[71442427] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"139.422518ms","start":"2026-04-07T15:04:46.871824Z","end":"2026-04-07T15:04:47.011247Z","steps":["trace[71442427] 'process raft request'  (duration: 139.238902ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:47.204982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.707635ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6605827825484812399 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-controller-manager\" mod_revision:214 > success:<request_put:<key:\"/registry/leases/kube-system/kube-controller-manager\" value_size:431 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-controller-manager\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-07T15:04:47.205293Z","caller":"traceutil/trace.go:171","msg":"trace[458026970] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"189.137524ms","start":"2026-04-07T15:04:47.016094Z","end":"2026-04-07T15:04:47.205231Z","steps":["trace[458026970] 'process raft request'  (duration: 188.981219ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.205348Z","caller":"traceutil/trace.go:171","msg":"trace[393468892] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"190.336291ms","start":"2026-04-07T15:04:47.014995Z","end":"2026-04-07T15:04:47.205331Z","steps":["trace[393468892] 'process raft request'  (duration: 87.642935ms)","trace[393468892] 'compare'  (duration: 101.560681ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:47.205363Z","caller":"traceutil/trace.go:171","msg":"trace[274322730] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"187.667108ms","start":"2026-04-07T15:04:47.01765Z","end":"2026-04-07T15:04:47.205317Z","steps":["trace[274322730] 'process raft request'  (duration: 187.514343ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.20557Z","caller":"traceutil/trace.go:171","msg":"trace[1228762047] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:270; }","duration":"189.223806ms","start":"2026-04-07T15:04:47.016335Z","end":"2026-04-07T15:04:47.205559Z","steps":["trace[1228762047] 'read index received'  (duration: 4.094508ms)","trace[1228762047] 'applied index is now lower than readState.Index'  (duration: 185.127898ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T15:04:47.205733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.394282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"}
{"level":"info","ts":"2026-04-07T15:04:47.205772Z","caller":"traceutil/trace.go:171","msg":"trace[1290491319] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:263; }","duration":"189.473745ms","start":"2026-04-07T15:04:47.016287Z","end":"2026-04-07T15:04:47.205761Z","steps":["trace[1290491319] 'agreement among raft nodes before linearized reading'  (duration: 189.371781ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.206049Z","caller":"traceutil/trace.go:171","msg":"trace[11626907] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"182.349771ms","start":"2026-04-07T15:04:47.023686Z","end":"2026-04-07T15:04:47.206036Z","steps":["trace[11626907] 'process raft request'  (duration: 181.518565ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:47.206159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.357981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3391"}
{"level":"info","ts":"2026-04-07T15:04:47.20624Z","caller":"traceutil/trace.go:171","msg":"trace[1919549455] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:264; }","duration":"189.510185ms","start":"2026-04-07T15:04:47.016715Z","end":"2026-04-07T15:04:47.206225Z","steps":["trace[1919549455] 'agreement among raft nodes before linearized reading'  (duration: 189.281428ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:47.435204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.020918ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6605827825484812407 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/events/default/instance.18a41b68167328f3\" mod_revision:0 > success:<request_put:<key:\"/registry/events/default/instance.18a41b68167328f3\" value_size:568 lease:6605827825484812281 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-04-07T15:04:47.435525Z","caller":"traceutil/trace.go:171","msg":"trace[179925119] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"225.298238ms","start":"2026-04-07T15:04:47.210197Z","end":"2026-04-07T15:04:47.435495Z","steps":["trace[179925119] 'process raft request'  (duration: 225.117773ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.435506Z","caller":"traceutil/trace.go:171","msg":"trace[899770552] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"226.3035ms","start":"2026-04-07T15:04:47.209154Z","end":"2026-04-07T15:04:47.435457Z","steps":["trace[899770552] 'process raft request'  (duration: 99.961152ms)","trace[899770552] 'compare'  (duration: 125.881034ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:47.435589Z","caller":"traceutil/trace.go:171","msg":"trace[2034238887] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"219.984732ms","start":"2026-04-07T15:04:47.215585Z","end":"2026-04-07T15:04:47.43557Z","steps":["trace[2034238887] 'process raft request'  (duration: 219.92229ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.435636Z","caller":"traceutil/trace.go:171","msg":"trace[1597849265] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"222.852531ms","start":"2026-04-07T15:04:47.212744Z","end":"2026-04-07T15:04:47.435596Z","steps":["trace[1597849265] 'process raft request'  (duration: 222.663526ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.435916Z","caller":"traceutil/trace.go:171","msg":"trace[1702608535] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"223.10948ms","start":"2026-04-07T15:04:47.212765Z","end":"2026-04-07T15:04:47.435874Z","steps":["trace[1702608535] 'process raft request'  (duration: 222.704247ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:47.63517Z","caller":"traceutil/trace.go:171","msg":"trace[2032407101] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"161.434657ms","start":"2026-04-07T15:04:47.473713Z","end":"2026-04-07T15:04:47.635148Z","steps":["trace[2032407101] 'process raft request'  (duration: 72.576924ms)","trace[2032407101] 'compare'  (duration: 88.685197ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:47.635235Z","caller":"traceutil/trace.go:171","msg":"trace[614053318] linearizableReadLoop","detail":"{readStateIndex:284; appliedIndex:283; }","duration":"137.90941ms","start":"2026-04-07T15:04:47.497302Z","end":"2026-04-07T15:04:47.635211Z","steps":["trace[614053318] 'read index received'  (duration: 32.375114ms)","trace[614053318] 'applied index is now lower than readState.Index'  (duration: 105.533305ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:47.635174Z","caller":"traceutil/trace.go:171","msg":"trace[2121825806] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"100.443946ms","start":"2026-04-07T15:04:47.534714Z","end":"2026-04-07T15:04:47.635158Z","steps":["trace[2121825806] 'process raft request'  (duration: 100.380014ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:47.635331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.020274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T15:04:47.635371Z","caller":"traceutil/trace.go:171","msg":"trace[248279297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:274; }","duration":"138.110236ms","start":"2026-04-07T15:04:47.497249Z","end":"2026-04-07T15:04:47.63536Z","steps":["trace[248279297] 'agreement among raft nodes before linearized reading'  (duration: 138.014873ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.201208Z","caller":"traceutil/trace.go:171","msg":"trace[1656103262] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"264.461985ms","start":"2026-04-07T15:04:47.936731Z","end":"2026-04-07T15:04:48.201193Z","steps":["trace[1656103262] 'process raft request'  (duration: 264.28706ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.201205Z","caller":"traceutil/trace.go:171","msg":"trace[44257594] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"278.822365ms","start":"2026-04-07T15:04:47.922356Z","end":"2026-04-07T15:04:48.201178Z","steps":["trace[44257594] 'process raft request'  (duration: 278.633209ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.201252Z","caller":"traceutil/trace.go:171","msg":"trace[1289821330] linearizableReadLoop","detail":"{readStateIndex:298; appliedIndex:294; }","duration":"218.900948ms","start":"2026-04-07T15:04:47.982288Z","end":"2026-04-07T15:04:48.201189Z","steps":["trace[1289821330] 'read index received'  (duration: 218.598829ms)","trace[1289821330] 'applied index is now lower than readState.Index'  (duration: 300.749µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:48.201312Z","caller":"traceutil/trace.go:171","msg":"trace[1515229884] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"249.164626ms","start":"2026-04-07T15:04:47.952117Z","end":"2026-04-07T15:04:48.201282Z","steps":["trace[1515229884] 'process raft request'  (duration: 248.98029ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:48.201501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.176756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4600"}
{"level":"info","ts":"2026-04-07T15:04:48.201099Z","caller":"traceutil/trace.go:171","msg":"trace[442581509] transaction","detail":"{read_only:false; number_of_response:0; response_revision:282; }","duration":"279.569248ms","start":"2026-04-07T15:04:47.921503Z","end":"2026-04-07T15:04:48.201072Z","steps":["trace[442581509] 'process raft request'  (duration: 279.383093ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.202142Z","caller":"traceutil/trace.go:171","msg":"trace[1837760907] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:284; }","duration":"219.732374ms","start":"2026-04-07T15:04:47.98224Z","end":"2026-04-07T15:04:48.201972Z","steps":["trace[1837760907] 'agreement among raft nodes before linearized reading'  (duration: 219.124275ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.424983Z","caller":"traceutil/trace.go:171","msg":"trace[1297466205] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"206.673185ms","start":"2026-04-07T15:04:48.218283Z","end":"2026-04-07T15:04:48.424956Z","steps":["trace[1297466205] 'read index received'  (duration: 206.407376ms)","trace[1297466205] 'applied index is now lower than readState.Index'  (duration: 264.949µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:48.425092Z","caller":"traceutil/trace.go:171","msg":"trace[326778573] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"211.080653ms","start":"2026-04-07T15:04:48.213933Z","end":"2026-04-07T15:04:48.425014Z","steps":["trace[326778573] 'process raft request'  (duration: 210.804634ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:48.425237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.932413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"}
{"level":"info","ts":"2026-04-07T15:04:48.425312Z","caller":"traceutil/trace.go:171","msg":"trace[1845479844] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:285; }","duration":"207.023706ms","start":"2026-04-07T15:04:48.218267Z","end":"2026-04-07T15:04:48.42529Z","steps":["trace[1845479844] 'agreement among raft nodes before linearized reading'  (duration: 206.881942ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:48.931339Z","caller":"traceutil/trace.go:171","msg":"trace[562317288] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"101.318374ms","start":"2026-04-07T15:04:48.829983Z","end":"2026-04-07T15:04:48.931301Z","steps":["trace[562317288] 'process raft request'  (duration: 61.518137ms)","trace[562317288] 'compare'  (duration: 39.635972ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T15:04:49.761191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.32942ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6605827825484812512 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/configmaps/default/kube-root-ca.crt\" mod_revision:0 > success:<request_put:<key:\"/registry/configmaps/default/kube-root-ca.crt\" value_size:1670 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-04-07T15:04:49.761315Z","caller":"traceutil/trace.go:171","msg":"trace[862333309] linearizableReadLoop","detail":"{readStateIndex:323; appliedIndex:322; }","duration":"130.292892ms","start":"2026-04-07T15:04:49.631Z","end":"2026-04-07T15:04:49.761293Z","steps":["trace[862333309] 'read index received'  (duration: 24.731675ms)","trace[862333309] 'applied index is now lower than readState.Index'  (duration: 105.559537ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:49.761328Z","caller":"traceutil/trace.go:171","msg":"trace[685640643] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"142.105522ms","start":"2026-04-07T15:04:49.619198Z","end":"2026-04-07T15:04:49.761304Z","steps":["trace[685640643] 'process raft request'  (duration: 36.548535ms)","trace[685640643] 'compare'  (duration: 105.175015ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T15:04:49.761504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.500648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"}
{"level":"warn","ts":"2026-04-07T15:04:49.761572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.232115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"}
{"level":"info","ts":"2026-04-07T15:04:49.76164Z","caller":"traceutil/trace.go:171","msg":"trace[1779224386] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:309; }","duration":"111.337309ms","start":"2026-04-07T15:04:49.650288Z","end":"2026-04-07T15:04:49.761625Z","steps":["trace[1779224386] 'agreement among raft nodes before linearized reading'  (duration: 111.182104ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:49.761652Z","caller":"traceutil/trace.go:171","msg":"trace[1672929889] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:309; }","duration":"130.595051ms","start":"2026-04-07T15:04:49.630949Z","end":"2026-04-07T15:04:49.761544Z","steps":["trace[1672929889] 'agreement among raft nodes before linearized reading'  (duration: 130.459427ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:49.967455Z","caller":"traceutil/trace.go:171","msg":"trace[2137283770] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"116.889262ms","start":"2026-04-07T15:04:49.850537Z","end":"2026-04-07T15:04:49.967427Z","steps":["trace[2137283770] 'process raft request'  (duration: 116.775689ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:49.967557Z","caller":"traceutil/trace.go:171","msg":"trace[803818447] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:327; }","duration":"117.46123ms","start":"2026-04-07T15:04:49.850074Z","end":"2026-04-07T15:04:49.967536Z","steps":["trace[803818447] 'read index received'  (duration: 8.793525ms)","trace[803818447] 'applied index is now lower than readState.Index'  (duration: 108.665655ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:49.967548Z","caller":"traceutil/trace.go:171","msg":"trace[1269008821] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"118.329907ms","start":"2026-04-07T15:04:49.849182Z","end":"2026-04-07T15:04:49.967512Z","steps":["trace[1269008821] 'process raft request'  (duration: 35.569794ms)","trace[1269008821] 'compare'  (duration: 82.443513ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:49.967616Z","caller":"traceutil/trace.go:171","msg":"trace[1907071613] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"104.860415ms","start":"2026-04-07T15:04:49.862733Z","end":"2026-04-07T15:04:49.967593Z","steps":["trace[1907071613] 'process raft request'  (duration: 104.640718ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:49.967735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.643705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"}
{"level":"info","ts":"2026-04-07T15:04:49.96778Z","caller":"traceutil/trace.go:171","msg":"trace[314741197] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:316; }","duration":"117.716748ms","start":"2026-04-07T15:04:49.850052Z","end":"2026-04-07T15:04:49.967768Z","steps":["trace[314741197] 'agreement among raft nodes before linearized reading'  (duration: 117.598804ms)"],"step_count":1}
{"level":"info","ts":"2026-04-07T15:04:50.120091Z","caller":"traceutil/trace.go:171","msg":"trace[1079058908] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"101.717907ms","start":"2026-04-07T15:04:50.018357Z","end":"2026-04-07T15:04:50.120075Z","steps":["trace[1079058908] 'process raft request'  (duration: 91.92553ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:57.363498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827825484812617,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-04-07T15:04:57.794033Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.83523833s","expected-duration":"1s"}
{"level":"info","ts":"2026-04-07T15:04:57.794469Z","caller":"traceutil/trace.go:171","msg":"trace[1139396447] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"1.835770498s","start":"2026-04-07T15:04:55.958669Z","end":"2026-04-07T15:04:57.79444Z","steps":["trace[1139396447] 'process raft request'  (duration: 1.835562781s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:57.794635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:55.95864Z","time spent":"1.835922363s","remote":"127.0.0.1:48252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5083,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/etcd-instance\" mod_revision:367 > success:<request_put:<key:\"/registry/pods/kube-system/etcd-instance\" value_size:5035 >> failure:<request_range:<key:\"/registry/pods/kube-system/etcd-instance\" > >"}
{"level":"info","ts":"2026-04-07T15:04:58.263005Z","caller":"traceutil/trace.go:171","msg":"trace[1542428312] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:385; }","duration":"1.400008479s","start":"2026-04-07T15:04:56.862967Z","end":"2026-04-07T15:04:58.262975Z","steps":["trace[1542428312] 'read index received'  (duration: 931.263505ms)","trace[1542428312] 'applied index is now lower than readState.Index'  (duration: 468.743574ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:04:58.263053Z","caller":"traceutil/trace.go:171","msg":"trace[795094997] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"1.187089168s","start":"2026-04-07T15:04:57.075939Z","end":"2026-04-07T15:04:58.263028Z","steps":["trace[795094997] 'process raft request'  (duration: 1.182933862s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:58.264744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:57.075909Z","time spent":"1.188712912s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-node-lease/instance\" mod_revision:256 > 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-04-07T15:04:58.264826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.401823099s","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-04-07T15:04:58.265068Z","caller":"traceutil/trace.go:171","msg":"trace[252518621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.402058767s","start":"2026-04-07T15:04:56.862933Z","end":"2026-04-07T15:04:58.264991Z","steps":["trace[252518621] 'agreement among raft nodes before linearized reading'  (duration: 1.400136944s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:58.265071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.297821074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"warn","ts":"2026-04-07T15:04:58.265242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:56.862918Z","time spent":"1.402252103s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"info","ts":"2026-04-07T15:04:58.265291Z","caller":"traceutil/trace.go:171","msg":"trace[1944097378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"1.298082173s","start":"2026-04-07T15:04:56.967179Z","end":"2026-04-07T15:04:58.265261Z","steps":["trace[1944097378] 'agreement among raft nodes before linearized reading'  (duration: 1.297762402s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:58.265293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.935757ms","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-04-07T15:04:58.265348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:56.967156Z","time spent":"1.298179896s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "}
{"level":"info","ts":"2026-04-07T15:04:58.265518Z","caller":"traceutil/trace.go:171","msg":"trace[1509485044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:371; }","duration":"963.075061ms","start":"2026-04-07T15:04:57.302284Z","end":"2026-04-07T15:04:58.265359Z","steps":["trace[1509485044] 'agreement among raft nodes before linearized reading'  (duration: 962.887405ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:58.265643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:57.302258Z","time spent":"963.35764ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"warn","ts":"2026-04-07T15:04:58.266162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.952761ms","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-04-07T15:04:58.26625Z","caller":"traceutil/trace.go:171","msg":"trace[334430731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:371; }","duration":"650.061524ms","start":"2026-04-07T15:04:57.616151Z","end":"2026-04-07T15:04:58.266212Z","steps":["trace[334430731] 'agreement among raft nodes before linearized reading'  (duration: 648.925037ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:04:58.266303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:04:57.616132Z","time spent":"650.157918ms","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"info","ts":"2026-04-07T15:05:16.767892Z","caller":"traceutil/trace.go:171","msg":"trace[1366809910] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"261.846592ms","start":"2026-04-07T15:05:16.506023Z","end":"2026-04-07T15:05:16.767869Z","steps":["trace[1366809910] 'read index received'  (duration: 261.691737ms)","trace[1366809910] 'applied index is now lower than readState.Index'  (duration: 154.115µs)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:05:16.76799Z","caller":"traceutil/trace.go:171","msg":"trace[1234707366] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"262.529375ms","start":"2026-04-07T15:05:16.505438Z","end":"2026-04-07T15:05:16.767967Z","steps":["trace[1234707366] 'process raft request'  (duration: 262.299437ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:16.76804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.013587ms","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-04-07T15:05:16.76807Z","caller":"traceutil/trace.go:171","msg":"trace[1981454710] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:445; }","duration":"262.108471ms","start":"2026-04-07T15:05:16.505949Z","end":"2026-04-07T15:05:16.768058Z","steps":["trace[1981454710] 'agreement among raft nodes before linearized reading'  (duration: 262.041278ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:16.768136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.027625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"}
{"level":"info","ts":"2026-04-07T15:05:16.768248Z","caller":"traceutil/trace.go:171","msg":"trace[1049467523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:445; }","duration":"261.18603ms","start":"2026-04-07T15:05:16.507036Z","end":"2026-04-07T15:05:16.768222Z","steps":["trace[1049467523] 'agreement among raft nodes before linearized reading'  (duration: 260.973753ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:16.768268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.891241ms","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-04-07T15:05:16.768409Z","caller":"traceutil/trace.go:171","msg":"trace[662946190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:445; }","duration":"261.108458ms","start":"2026-04-07T15:05:16.507271Z","end":"2026-04-07T15:05:16.76838Z","steps":["trace[662946190] 'agreement among raft nodes before linearized reading'  (duration: 260.820029ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:17.915186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:16.826827Z","time spent":"1.088336475s","remote":"127.0.0.1:35808","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""}
{"level":"warn","ts":"2026-04-07T15:05:17.915573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.939082ms","expected-duration":"100ms","prefix":"","request":"header:<ID:6605827825484812781 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:443 > 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\" > >>","response":"size:16"}
{"level":"info","ts":"2026-04-07T15:05:17.915688Z","caller":"traceutil/trace.go:171","msg":"trace[1820062542] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"1.144098242s","start":"2026-04-07T15:05:16.771567Z","end":"2026-04-07T15:05:17.915665Z","steps":["trace[1820062542] 'process raft request'  (duration: 380.948194ms)","trace[1820062542] 'compare'  (duration: 762.642821ms)"],"step_count":2}
{"level":"warn","ts":"2026-04-07T15:05:17.915799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:16.771547Z","time spent":"1.144207046s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:443 > 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-04-07T15:05:17.923326Z","caller":"traceutil/trace.go:171","msg":"trace[1499820312] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"1.150926297s","start":"2026-04-07T15:05:16.772358Z","end":"2026-04-07T15:05:17.923284Z","steps":["trace[1499820312] 'process raft request'  (duration: 1.150591206s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:17.923559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:16.772329Z","time spent":"1.151102033s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:441 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:369 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-04-07T15:05:17.923737Z","caller":"traceutil/trace.go:171","msg":"trace[72140145] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"1.150691899s","start":"2026-04-07T15:05:16.773014Z","end":"2026-04-07T15:05:17.923706Z","steps":["trace[72140145] 'process raft request'  (duration: 1.15011069s)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:17.923844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:16.773005Z","time spent":"1.150787493s","remote":"127.0.0.1:48316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:442 > 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-04-07T15:05:17.923932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.879749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"}
{"level":"info","ts":"2026-04-07T15:05:17.923973Z","caller":"traceutil/trace.go:171","msg":"trace[728416255] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:467; }","duration":"977.531788ms","start":"2026-04-07T15:05:16.946047Z","end":"2026-04-07T15:05:17.923579Z","steps":["trace[728416255] 'read index received'  (duration: 206.486169ms)","trace[728416255] 'applied index is now lower than readState.Index'  (duration: 771.044759ms)"],"step_count":2}
{"level":"info","ts":"2026-04-07T15:05:17.92399Z","caller":"traceutil/trace.go:171","msg":"trace[374720241] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:448; }","duration":"977.972342ms","start":"2026-04-07T15:05:16.946Z","end":"2026-04-07T15:05:17.923973Z","steps":["trace[374720241] 'agreement among raft nodes before linearized reading'  (duration: 977.853628ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:17.924036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T15:05:16.945979Z","time spent":"978.043614ms","remote":"127.0.0.1:48174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":4,"response size":1406,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "}
{"level":"warn","ts":"2026-04-07T15:05:18.255829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.230735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T15:05:18.25593Z","caller":"traceutil/trace.go:171","msg":"trace[134019424] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:448; }","duration":"177.337369ms","start":"2026-04-07T15:05:18.07856Z","end":"2026-04-07T15:05:18.255898Z","steps":["trace[134019424] 'range keys from in-memory index tree'  (duration: 177.118342ms)"],"step_count":1}
{"level":"warn","ts":"2026-04-07T15:05:32.299463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.222599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-04-07T15:05:32.299564Z","caller":"traceutil/trace.go:171","msg":"trace[405960378] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:480; }","duration":"134.351954ms","start":"2026-04-07T15:05:32.165191Z","end":"2026-04-07T15:05:32.299543Z","steps":["trace[405960378] 'range keys from in-memory index tree'  (duration: 134.060934ms)"],"step_count":1}