{"level":"warn","ts":"2026-04-08T12:54:41.42843Z","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-08T12:54:41.4285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.77: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.77:2380","--initial-cluster=instance=https://199.204.45.77:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.77:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.77: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-08T12:54:41.428555Z","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-08T12:54:41.428567Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-08T12:54:41.428584Z","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-08T12:54:41.429173Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"]} {"level":"info","ts":"2026-04-08T12:54:41.42924Z","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.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.77: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-08T12:54:41.482675Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"52.289224ms"} {"level":"info","ts":"2026-04-08T12:54:41.516893Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9133559b3198d4ec","cluster-id":"60f1aee33b0786f5"} {"level":"info","ts":"2026-04-08T12:54:41.517032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=()"} {"level":"info","ts":"2026-04-08T12:54:41.517079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 0"} {"level":"info","ts":"2026-04-08T12:54:41.517097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9133559b3198d4ec [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-08T12:54:41.517113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became follower at term 1"} {"level":"info","ts":"2026-04-08T12:54:41.517184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"warn","ts":"2026-04-08T12:54:41.551073Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-08T12:54:41.569642Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-08T12:54:41.664747Z","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-08T12:54:41.702048Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9133559b3198d4ec","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-08T12:54:41.702229Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9133559b3198d4ec","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-08T12:54:41.702719Z","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-08T12:54:41.70279Z","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-08T12:54:41.702802Z","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-08T12:54:41.70865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec switched to configuration voters=(10462800484352185580)"} {"level":"info","ts":"2026-04-08T12:54:41.708861Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","added-peer-id":"9133559b3198d4ec","added-peer-peer-urls":["https://199.204.45.77:2380"]} {"level":"info","ts":"2026-04-08T12:54:41.712177Z","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-08T12:54:41.71246Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9133559b3198d4ec","initial-advertise-peer-urls":["https://199.204.45.77:2380"],"listen-peer-urls":["https://199.204.45.77:2380"],"advertise-client-urls":["https://199.204.45.77:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.77:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-08T12:54:41.712517Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-08T12:54:41.713037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-08T12:54:41.713067Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.77:2380"} {"level":"info","ts":"2026-04-08T12:54:42.017941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec is starting a new election at term 1"} {"level":"info","ts":"2026-04-08T12:54:42.018046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became pre-candidate at term 1"} {"level":"info","ts":"2026-04-08T12:54:42.018088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgPreVoteResp from 9133559b3198d4ec at term 1"} {"level":"info","ts":"2026-04-08T12:54:42.018112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became candidate at term 2"} {"level":"info","ts":"2026-04-08T12:54:42.01813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec received MsgVoteResp from 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-08T12:54:42.018149Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9133559b3198d4ec became leader at term 2"} {"level":"info","ts":"2026-04-08T12:54:42.018171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9133559b3198d4ec elected leader 9133559b3198d4ec at term 2"} {"level":"info","ts":"2026-04-08T12:54:42.019937Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9133559b3198d4ec","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.77:2379]}","request-path":"/0/members/9133559b3198d4ec/attributes","cluster-id":"60f1aee33b0786f5","publish-timeout":"7s"} {"level":"info","ts":"2026-04-08T12:54:42.020176Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:54:42.020194Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-08T12:54:42.0205Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:42.020626Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-08T12:54:42.02067Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-08T12:54:42.024475Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-08T12:54:42.024532Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.77:2379"} {"level":"info","ts":"2026-04-08T12:54:42.03152Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"60f1aee33b0786f5","local-member-id":"9133559b3198d4ec","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:42.031766Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:54:42.031844Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-08T12:55:06.42223Z","caller":"traceutil/trace.go:171","msg":"trace[1203384295] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"186.31687ms","start":"2026-04-08T12:55:06.235889Z","end":"2026-04-08T12:55:06.422206Z","steps":["trace[1203384295] 'process raft request' (duration: 186.186629ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.096817Z","caller":"traceutil/trace.go:171","msg":"trace[494704388] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"238.273888ms","start":"2026-04-08T12:55:10.858514Z","end":"2026-04-08T12:55:11.096788Z","steps":["trace[494704388] 'process raft request' (duration: 238.113704ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.105729Z","caller":"traceutil/trace.go:171","msg":"trace[580446366] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"242.852001ms","start":"2026-04-08T12:55:10.862845Z","end":"2026-04-08T12:55:11.105697Z","steps":["trace[580446366] 'process raft request' (duration: 242.648326ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.105813Z","caller":"traceutil/trace.go:171","msg":"trace[484813493] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"242.864351ms","start":"2026-04-08T12:55:10.862915Z","end":"2026-04-08T12:55:11.105779Z","steps":["trace[484813493] 'process raft request' (duration: 242.675106ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.106079Z","caller":"traceutil/trace.go:171","msg":"trace[1303613496] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"221.084057ms","start":"2026-04-08T12:55:10.884967Z","end":"2026-04-08T12:55:11.106051Z","steps":["trace[1303613496] 'process raft request' (duration: 220.750979ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.106421Z","caller":"traceutil/trace.go:171","msg":"trace[618436038] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"218.480196ms","start":"2026-04-08T12:55:10.887928Z","end":"2026-04-08T12:55:11.106408Z","steps":["trace[618436038] 'process raft request' (duration: 218.183248ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.106803Z","caller":"traceutil/trace.go:171","msg":"trace[394864206] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"205.325447ms","start":"2026-04-08T12:55:10.901449Z","end":"2026-04-08T12:55:11.106775Z","steps":["trace[394864206] 'process raft request' (duration: 204.916326ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.107048Z","caller":"traceutil/trace.go:171","msg":"trace[410226269] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"204.316439ms","start":"2026-04-08T12:55:10.902713Z","end":"2026-04-08T12:55:11.10703Z","steps":["trace[410226269] 'process raft request' (duration: 203.929859ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.107154Z","caller":"traceutil/trace.go:171","msg":"trace[58419303] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"202.493989ms","start":"2026-04-08T12:55:10.904643Z","end":"2026-04-08T12:55:11.107137Z","steps":["trace[58419303] 'process raft request' (duration: 202.045637ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.107235Z","caller":"traceutil/trace.go:171","msg":"trace[834096161] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"199.035296ms","start":"2026-04-08T12:55:10.90819Z","end":"2026-04-08T12:55:11.107226Z","steps":["trace[834096161] 'process raft request' (duration: 198.81966ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.107432Z","caller":"traceutil/trace.go:171","msg":"trace[1891669831] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"198.902723ms","start":"2026-04-08T12:55:10.908508Z","end":"2026-04-08T12:55:11.107411Z","steps":["trace[1891669831] 'process raft request' (duration: 198.561812ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.107486Z","caller":"traceutil/trace.go:171","msg":"trace[1291495401] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:486; }","duration":"103.650566ms","start":"2026-04-08T12:55:11.003823Z","end":"2026-04-08T12:55:11.107473Z","steps":["trace[1291495401] 'read index received' (duration: 93.486792ms)","trace[1291495401] 'applied index is now lower than readState.Index' (duration: 10.162944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:55:11.107752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.911213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:55:11.1078Z","caller":"traceutil/trace.go:171","msg":"trace[77927619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:479; }","duration":"103.983275ms","start":"2026-04-08T12:55:11.003806Z","end":"2026-04-08T12:55:11.10779Z","steps":["trace[77927619] 'agreement among raft nodes before linearized reading' (duration: 103.861941ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:11.116144Z","caller":"traceutil/trace.go:171","msg":"trace[1732863608] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"187.185211ms","start":"2026-04-08T12:55:10.928937Z","end":"2026-04-08T12:55:11.116123Z","steps":["trace[1732863608] 'process raft request' (duration: 178.263459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:12.061732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.265591ms","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-08T12:55:12.061826Z","caller":"traceutil/trace.go:171","msg":"trace[173405933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"132.370944ms","start":"2026-04-08T12:55:11.929434Z","end":"2026-04-08T12:55:12.061805Z","steps":["trace[173405933] 'range keys from in-memory index tree' (duration: 132.104267ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:12.248918Z","caller":"traceutil/trace.go:171","msg":"trace[2012298402] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"205.059594ms","start":"2026-04-08T12:55:12.043819Z","end":"2026-04-08T12:55:12.248879Z","steps":["trace[2012298402] 'process raft request' (duration: 127.221516ms)","trace[2012298402] 'compare' (duration: 77.319554ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:12.248965Z","caller":"traceutil/trace.go:171","msg":"trace[589704274] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"116.216122ms","start":"2026-04-08T12:55:12.132713Z","end":"2026-04-08T12:55:12.248929Z","steps":["trace[589704274] 'process raft request' (duration: 116.15032ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:12.249153Z","caller":"traceutil/trace.go:171","msg":"trace[1429266696] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"183.407855ms","start":"2026-04-08T12:55:12.065732Z","end":"2026-04-08T12:55:12.24914Z","steps":["trace[1429266696] 'process raft request' (duration: 183.039906ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:16.356354Z","caller":"traceutil/trace.go:171","msg":"trace[515226750] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"376.391148ms","start":"2026-04-08T12:55:15.979926Z","end":"2026-04-08T12:55:16.356318Z","steps":["trace[515226750] 'process raft request' (duration: 375.852354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:16.357284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:15.979899Z","time spent":"376.551821ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:55:17.037723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.911317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-08T12:55:17.037851Z","caller":"traceutil/trace.go:171","msg":"trace[627844913] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:589; }","duration":"555.544602ms","start":"2026-04-08T12:55:16.482286Z","end":"2026-04-08T12:55:17.03783Z","steps":["trace[627844913] 'read index received' (duration: 208.475781ms)","trace[627844913] 'applied index is now lower than readState.Index' (duration: 347.068061ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:17.037918Z","caller":"traceutil/trace.go:171","msg":"trace[342704781] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"605.569875ms","start":"2026-04-08T12:55:16.43233Z","end":"2026-04-08T12:55:17.0379Z","steps":["trace[342704781] 'process raft request' (duration: 605.464671ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:17.037996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:16.432311Z","time spent":"605.642557ms","remote":"127.0.0.1:54698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-08T12:55:17.038011Z","caller":"traceutil/trace.go:171","msg":"trace[28448227] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"677.734143ms","start":"2026-04-08T12:55:16.360251Z","end":"2026-04-08T12:55:17.037985Z","steps":["trace[28448227] 'process raft request' (duration: 330.439895ms)","trace[28448227] 'compare' (duration: 346.768034ms)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:55:17.038035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.751108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T12:55:17.038062Z","caller":"traceutil/trace.go:171","msg":"trace[184397352] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:567; }","duration":"555.81354ms","start":"2026-04-08T12:55:16.482239Z","end":"2026-04-08T12:55:17.038053Z","steps":["trace[184397352] 'agreement among raft nodes before linearized reading' (duration: 555.664196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:17.038089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:16.482211Z","time spent":"555.871431ms","remote":"127.0.0.1:54596","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-08T12:55:17.038088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:16.360229Z","time spent":"677.818955ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:55:17.889274Z","caller":"traceutil/trace.go:171","msg":"trace[804623065] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"436.413445ms","start":"2026-04-08T12:55:17.452827Z","end":"2026-04-08T12:55:17.889241Z","steps":["trace[804623065] 'process raft request' (duration: 435.911373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:17.88944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:17.452799Z","time spent":"436.565469ms","remote":"127.0.0.1:54398","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036257,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-08T12:55:18.485742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15342811122705396164,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-08T12:55:18.645759Z","caller":"traceutil/trace.go:171","msg":"trace[767450752] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"660.405892ms","start":"2026-04-08T12:55:17.98533Z","end":"2026-04-08T12:55:18.645735Z","steps":["trace[767450752] 'read index received' (duration: 660.252638ms)","trace[767450752] 'applied index is now lower than readState.Index' (duration: 152.214µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:18.64597Z","caller":"traceutil/trace.go:171","msg":"trace[350439151] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"698.965961ms","start":"2026-04-08T12:55:17.946993Z","end":"2026-04-08T12:55:18.645959Z","steps":["trace[350439151] 'process raft request' (duration: 698.640842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:18.646053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:17.946973Z","time spent":"699.025622ms","remote":"127.0.0.1:54708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-08T12:55:18.646499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.740175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:55:18.646571Z","caller":"traceutil/trace.go:171","msg":"trace[739893699] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"642.835388ms","start":"2026-04-08T12:55:18.003714Z","end":"2026-04-08T12:55:18.64655Z","steps":["trace[739893699] 'agreement among raft nodes before linearized reading' (duration: 642.722755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:18.646609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:18.003701Z","time spent":"642.900239ms","remote":"127.0.0.1:54288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:55:18.647049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.697775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kk4wc\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-08T12:55:18.647701Z","caller":"traceutil/trace.go:171","msg":"trace[1549890700] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kk4wc; range_end:; response_count:1; response_revision:576; }","duration":"662.392202ms","start":"2026-04-08T12:55:17.985292Z","end":"2026-04-08T12:55:18.647684Z","steps":["trace[1549890700] 'agreement among raft nodes before linearized reading' (duration: 660.966877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:18.6478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:17.985274Z","time spent":"662.511226ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-kk4wc\" "} {"level":"info","ts":"2026-04-08T12:55:19.019959Z","caller":"traceutil/trace.go:171","msg":"trace[350029357] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"355.08807ms","start":"2026-04-08T12:55:18.664851Z","end":"2026-04-08T12:55:19.019939Z","steps":["trace[350029357] 'process raft request' (duration: 354.966587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:19.020108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:18.664839Z","time spent":"355.218433ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:55:32.664768Z","caller":"traceutil/trace.go:171","msg":"trace[2050168724] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"153.780193ms","start":"2026-04-08T12:55:32.510963Z","end":"2026-04-08T12:55:32.664743Z","steps":["trace[2050168724] 'process raft request' (duration: 153.620929ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.503048Z","caller":"traceutil/trace.go:171","msg":"trace[2045574953] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"423.870754ms","start":"2026-04-08T12:55:35.07915Z","end":"2026-04-08T12:55:35.503021Z","steps":["trace[2045574953] 'read index received' (duration: 423.69985ms)","trace[2045574953] 'applied index is now lower than readState.Index' (duration: 169.184µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:55:35.503072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:35.058663Z","time spent":"444.400533ms","remote":"127.0.0.1:54340","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-08T12:55:35.504184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.150109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-08T12:55:35.503457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.897783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:55:35.5044Z","caller":"traceutil/trace.go:171","msg":"trace[1902719416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:737; }","duration":"371.256251ms","start":"2026-04-08T12:55:35.132988Z","end":"2026-04-08T12:55:35.504244Z","steps":["trace[1902719416] 'agreement among raft nodes before linearized reading' (duration: 370.183249ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:35.504419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.224191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tk2t\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-08T12:55:35.504508Z","caller":"traceutil/trace.go:171","msg":"trace[1801680553] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tk2t; range_end:; response_count:1; response_revision:737; }","duration":"425.386875ms","start":"2026-04-08T12:55:35.079097Z","end":"2026-04-08T12:55:35.504484Z","steps":["trace[1801680553] 'agreement among raft nodes before linearized reading' (duration: 424.049027ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.504529Z","caller":"traceutil/trace.go:171","msg":"trace[243702876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"334.006527ms","start":"2026-04-08T12:55:35.17049Z","end":"2026-04-08T12:55:35.504496Z","steps":["trace[243702876] 'agreement among raft nodes before linearized reading' (duration: 332.907264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:35.504843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.889635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T12:55:35.504953Z","caller":"traceutil/trace.go:171","msg":"trace[1378058316] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"349.004077ms","start":"2026-04-08T12:55:35.1559Z","end":"2026-04-08T12:55:35.504904Z","steps":["trace[1378058316] 'agreement among raft nodes before linearized reading' (duration: 347.524435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:55:35.504965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:35.132974Z","time spent":"371.972367ms","remote":"127.0.0.1:54596","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-04-08T12:55:35.505016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:35.155858Z","time spent":"349.14691ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T12:55:35.504589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:35.079075Z","time spent":"425.478859ms","remote":"127.0.0.1:54522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6tk2t\" "} {"level":"warn","ts":"2026-04-08T12:55:35.50469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:55:35.17047Z","time spent":"334.201091ms","remote":"127.0.0.1:54288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-08T12:55:35.627434Z","caller":"traceutil/trace.go:171","msg":"trace[1677248515] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"121.243557ms","start":"2026-04-08T12:55:35.506152Z","end":"2026-04-08T12:55:35.627396Z","steps":["trace[1677248515] 'process raft request' (duration: 118.312305ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.629292Z","caller":"traceutil/trace.go:171","msg":"trace[1987550738] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"118.650621ms","start":"2026-04-08T12:55:35.510607Z","end":"2026-04-08T12:55:35.629257Z","steps":["trace[1987550738] 'process raft request' (duration: 118.402856ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.629393Z","caller":"traceutil/trace.go:171","msg":"trace[942247292] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"115.351091ms","start":"2026-04-08T12:55:35.514013Z","end":"2026-04-08T12:55:35.629364Z","steps":["trace[942247292] 'process raft request' (duration: 115.26499ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.629457Z","caller":"traceutil/trace.go:171","msg":"trace[131076923] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"118.290954ms","start":"2026-04-08T12:55:35.511156Z","end":"2026-04-08T12:55:35.629447Z","steps":["trace[131076923] 'process raft request' (duration: 118.014538ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.629489Z","caller":"traceutil/trace.go:171","msg":"trace[1429703246] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"116.454844ms","start":"2026-04-08T12:55:35.513026Z","end":"2026-04-08T12:55:35.629481Z","steps":["trace[1429703246] 'process raft request' (duration: 116.177858ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:35.629546Z","caller":"traceutil/trace.go:171","msg":"trace[1324434772] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"116.450634ms","start":"2026-04-08T12:55:35.513077Z","end":"2026-04-08T12:55:35.629527Z","steps":["trace[1324434772] 'process raft request' (duration: 116.164128ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:55:42.958504Z","caller":"traceutil/trace.go:171","msg":"trace[1474491324] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"115.649709ms","start":"2026-04-08T12:55:42.842832Z","end":"2026-04-08T12:55:42.958482Z","steps":["trace[1474491324] 'process raft request' (duration: 94.029696ms)","trace[1474491324] 'compare' (duration: 21.522382ms)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:55:44.388509Z","caller":"traceutil/trace.go:171","msg":"trace[1143084504] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"196.486454ms","start":"2026-04-08T12:55:44.191996Z","end":"2026-04-08T12:55:44.388483Z","steps":["trace[1143084504] 'process raft request' (duration: 194.416943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.526164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.8199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-08T12:56:36.526255Z","caller":"traceutil/trace.go:171","msg":"trace[927113100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1072; }","duration":"409.975632ms","start":"2026-04-08T12:56:36.116262Z","end":"2026-04-08T12:56:36.526237Z","steps":["trace[927113100] 'range keys from in-memory index tree' (duration: 409.670889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.526294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.116245Z","time spent":"410.039983ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-08T12:56:36.52621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.126346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-08T12:56:36.526513Z","caller":"traceutil/trace.go:171","msg":"trace[2086907232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1072; }","duration":"522.463751ms","start":"2026-04-08T12:56:36.004041Z","end":"2026-04-08T12:56:36.526504Z","steps":["trace[2086907232] 'range keys from in-memory index tree' (duration: 522.059775ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.526531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.004027Z","time spent":"522.499231ms","remote":"127.0.0.1:54294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-08T12:56:36.526674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.329881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-08T12:56:36.526768Z","caller":"traceutil/trace.go:171","msg":"trace[436828965] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"184.088304ms","start":"2026-04-08T12:56:36.342672Z","end":"2026-04-08T12:56:36.526761Z","steps":["trace[436828965] 'process raft request' (duration: 182.211768ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:36.526769Z","caller":"traceutil/trace.go:171","msg":"trace[216446401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1072; }","duration":"416.451322ms","start":"2026-04-08T12:56:36.110295Z","end":"2026-04-08T12:56:36.526746Z","steps":["trace[216446401] 'range keys from in-memory index tree' (duration: 416.188628ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.526757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.001436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-08T12:56:36.526837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.11029Z","time spent":"416.519363ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-08T12:56:36.526839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.44273ms","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-08T12:56:36.526939Z","caller":"traceutil/trace.go:171","msg":"trace[1885299391] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1072; }","duration":"564.158808ms","start":"2026-04-08T12:56:35.962662Z","end":"2026-04-08T12:56:36.52682Z","steps":["trace[1885299391] 'range keys from in-memory index tree' (duration: 563.335506ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:56:36.526994Z","caller":"traceutil/trace.go:171","msg":"trace[2144621923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1072; }","duration":"410.646962ms","start":"2026-04-08T12:56:36.116326Z","end":"2026-04-08T12:56:36.526973Z","steps":["trace[2144621923] 'range keys from in-memory index tree' (duration: 409.655868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:56:36.52704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:35.962641Z","time spent":"564.388611ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-08T12:56:36.527044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:56:36.116305Z","time spent":"410.729613ms","remote":"127.0.0.1:54596","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-08T12:57:11.982774Z","caller":"traceutil/trace.go:171","msg":"trace[1121832087] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1249; }","duration":"107.733098ms","start":"2026-04-08T12:57:11.875021Z","end":"2026-04-08T12:57:11.982754Z","steps":["trace[1121832087] 'read index received' (duration: 107.563416ms)","trace[1121832087] 'applied index is now lower than readState.Index' (duration: 169.122µs)"],"step_count":2} {"level":"warn","ts":"2026-04-08T12:57:11.982921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.88119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-04-08T12:57:11.982943Z","caller":"traceutil/trace.go:171","msg":"trace[1837550946] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:1199; }","duration":"107.959451ms","start":"2026-04-08T12:57:11.874977Z","end":"2026-04-08T12:57:11.982937Z","steps":["trace[1837550946] 'agreement among raft nodes before linearized reading' (duration: 107.85355ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:11.982983Z","caller":"traceutil/trace.go:171","msg":"trace[1497335430] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"294.281505ms","start":"2026-04-08T12:57:11.688629Z","end":"2026-04-08T12:57:11.98291Z","steps":["trace[1497335430] 'process raft request' (duration: 294.011922ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:12.582934Z","caller":"traceutil/trace.go:171","msg":"trace[1446259503] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"195.475964ms","start":"2026-04-08T12:57:12.387431Z","end":"2026-04-08T12:57:12.582907Z","steps":["trace[1446259503] 'process raft request' (duration: 195.363582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:12.916869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.899486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T12:57:12.916978Z","caller":"traceutil/trace.go:171","msg":"trace[1151779894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"206.029147ms","start":"2026-04-08T12:57:12.710906Z","end":"2026-04-08T12:57:12.916935Z","steps":["trace[1151779894] 'range keys from in-memory index tree' (duration: 205.717174ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:12.917039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.392948ms","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-08T12:57:12.917176Z","caller":"traceutil/trace.go:171","msg":"trace[1952139956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"204.58149ms","start":"2026-04-08T12:57:12.71256Z","end":"2026-04-08T12:57:12.917141Z","steps":["trace[1952139956] 'range keys from in-memory index tree' (duration: 204.199886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:12.917239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.010655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T12:57:12.917305Z","caller":"traceutil/trace.go:171","msg":"trace[1390711351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"205.127846ms","start":"2026-04-08T12:57:12.712161Z","end":"2026-04-08T12:57:12.917288Z","steps":["trace[1390711351] 'range keys from in-memory index tree' (duration: 204.821543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:12.917315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.084991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-76cfb275-9752-42ec-ad48-b5fcc79eaacd\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-04-08T12:57:12.917373Z","caller":"traceutil/trace.go:171","msg":"trace[1946841361] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-76cfb275-9752-42ec-ad48-b5fcc79eaacd; range_end:; response_count:1; response_revision:1202; }","duration":"225.171421ms","start":"2026-04-08T12:57:12.692177Z","end":"2026-04-08T12:57:12.917349Z","steps":["trace[1946841361] 'range keys from in-memory index tree' (duration: 224.899078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:12.91747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.808035ms","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-08T12:57:12.917577Z","caller":"traceutil/trace.go:171","msg":"trace[226820972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"211.939567ms","start":"2026-04-08T12:57:12.705628Z","end":"2026-04-08T12:57:12.917567Z","steps":["trace[226820972] 'range keys from in-memory index tree' (duration: 211.627334ms)"],"step_count":1} {"level":"info","ts":"2026-04-08T12:57:13.580046Z","caller":"traceutil/trace.go:171","msg":"trace[1627235643] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"320.305725ms","start":"2026-04-08T12:57:13.259717Z","end":"2026-04-08T12:57:13.580023Z","steps":["trace[1627235643] 'process raft request' (duration: 320.184274ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:13.580185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-08T12:57:13.259701Z","time spent":"320.406956ms","remote":"127.0.0.1:54596","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-08T12:57:23.090051Z","caller":"traceutil/trace.go:171","msg":"trace[1027067319] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"121.477774ms","start":"2026-04-08T12:57:22.968547Z","end":"2026-04-08T12:57:23.090025Z","steps":["trace[1027067319] 'read index received' (duration: 121.294862ms)","trace[1027067319] 'applied index is now lower than readState.Index' (duration: 181.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-08T12:57:23.090173Z","caller":"traceutil/trace.go:171","msg":"trace[1234303685] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"122.129641ms","start":"2026-04-08T12:57:22.968031Z","end":"2026-04-08T12:57:23.090161Z","steps":["trace[1234303685] 'process raft request' (duration: 121.852818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.09033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.317132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-08T12:57:23.090408Z","caller":"traceutil/trace.go:171","msg":"trace[1857875872] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1258; }","duration":"106.418073ms","start":"2026-04-08T12:57:22.983971Z","end":"2026-04-08T12:57:23.090389Z","steps":["trace[1857875872] 'agreement among raft nodes before linearized reading' (duration: 106.247621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.090555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.003701ms","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-08T12:57:23.09059Z","caller":"traceutil/trace.go:171","msg":"trace[254496812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1258; }","duration":"122.058761ms","start":"2026-04-08T12:57:22.968522Z","end":"2026-04-08T12:57:23.090581Z","steps":["trace[254496812] 'agreement among raft nodes before linearized reading' (duration: 121.97669ms)"],"step_count":1} {"level":"warn","ts":"2026-04-08T12:57:23.090769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.196886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-08T12:57:23.090819Z","caller":"traceutil/trace.go:171","msg":"trace[1692037408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1258; }","duration":"101.278846ms","start":"2026-04-08T12:57:22.989528Z","end":"2026-04-08T12:57:23.090807Z","steps":["trace[1692037408] 'agreement among raft nodes before linearized reading' (duration: 101.128205ms)"],"step_count":1}