{"level":"warn","ts":"2026-04-06T21:02:14.867707Z","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-06T21:02:14.867806Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122: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.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122: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-06T21:02:14.867872Z","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-06T21:02:14.867887Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-06T21:02:14.867903Z","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-06T21:02:14.868779Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-04-06T21:02:14.868857Z","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.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122: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-06T21:02:14.873056Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.988117ms"} {"level":"info","ts":"2026-04-06T21:02:14.878428Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-04-06T21:02:14.878496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T21:02:14.878535Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-04-06T21:02:14.878552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T21:02:14.878559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-04-06T21:02:14.878589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-04-06T21:02:14.885221Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T21:02:14.886358Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T21:02:14.88791Z","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-06T21:02:14.890376Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T21:02:14.89194Z","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-06T21:02:14.892177Z","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-06T21:02:14.892257Z","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-06T21:02:14.891118Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T21:02:14.893066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-04-06T21:02:14.894202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-04-06T21:02:14.896159Z","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-06T21:02:14.896441Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T21:02:14.89649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T21:02:14.897222Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-06T21:02:14.897264Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-04-06T21:02:14.979528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T21:02:14.979594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T21:02:14.979619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-04-06T21:02:14.979635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-04-06T21:02:14.979646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-06T21:02:14.979658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-04-06T21:02:14.979669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-04-06T21:02:14.980263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:02:14.981177Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:02:14.981183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T21:02:14.981224Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T21:02:14.981569Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T21:02:14.981632Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T21:02:14.982646Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:02:14.982765Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:02:14.9828Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T21:02:14.985704Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T21:02:14.986605Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"warn","ts":"2026-04-06T21:02:18.668747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.462648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:02:18.66891Z","caller":"traceutil/trace.go:171","msg":"trace[2061291797] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:185; }","duration":"132.671534ms","start":"2026-04-06T21:02:18.536206Z","end":"2026-04-06T21:02:18.668877Z","steps":["trace[2061291797] 'range keys from in-memory index tree' (duration: 132.396766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:20.666002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.294028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:02:20.6662Z","caller":"traceutil/trace.go:171","msg":"trace[1118774086] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"370.378164ms","start":"2026-04-06T21:02:20.295789Z","end":"2026-04-06T21:02:20.666167Z","steps":["trace[1118774086] 'process raft request' (duration: 154.934435ms)","trace[1118774086] 'compare' (duration: 214.049192ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:02:20.666289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.295762Z","time spent":"370.486927ms","remote":"127.0.0.1:36364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":400,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:02:20.69915Z","caller":"traceutil/trace.go:171","msg":"trace[2140094660] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"400.098377ms","start":"2026-04-06T21:02:20.299019Z","end":"2026-04-06T21:02:20.699117Z","steps":["trace[2140094660] 'process raft request' (duration: 399.854ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:20.699225Z","caller":"traceutil/trace.go:171","msg":"trace[927619293] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:267; }","duration":"358.145494ms","start":"2026-04-06T21:02:20.341046Z","end":"2026-04-06T21:02:20.699191Z","steps":["trace[927619293] 'read index received' (duration: 109.773345ms)","trace[927619293] 'applied index is now lower than readState.Index' (duration: 248.370869ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:20.699239Z","caller":"traceutil/trace.go:171","msg":"trace[1555854136] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"358.222096ms","start":"2026-04-06T21:02:20.340968Z","end":"2026-04-06T21:02:20.69919Z","steps":["trace[1555854136] 'process raft request' (duration: 358.076532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:20.699317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.299003Z","time spent":"400.244561ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:02:20.699359Z","caller":"traceutil/trace.go:171","msg":"trace[1497989116] transaction","detail":"{read_only:false; number_of_response:0; response_revision:264; }","duration":"258.281086ms","start":"2026-04-06T21:02:20.441067Z","end":"2026-04-06T21:02:20.699348Z","steps":["trace[1497989116] 'process raft request' (duration: 258.06687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:20.699413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.340938Z","time spent":"358.386541ms","remote":"127.0.0.1:36084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:20.699808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.7325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-06T21:02:20.699954Z","caller":"traceutil/trace.go:171","msg":"trace[277058848] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:264; }","duration":"358.890764ms","start":"2026-04-06T21:02:20.341031Z","end":"2026-04-06T21:02:20.699921Z","steps":["trace[277058848] 'agreement among raft nodes before linearized reading' (duration: 358.547674ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:20.700057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.340976Z","time spent":"359.067769ms","remote":"127.0.0.1:36394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-04-06T21:02:21.019814Z","caller":"traceutil/trace.go:171","msg":"trace[1169451406] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"562.841563ms","start":"2026-04-06T21:02:20.456938Z","end":"2026-04-06T21:02:21.01978Z","steps":["trace[1169451406] 'process raft request' (duration: 562.716529ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:21.019893Z","caller":"traceutil/trace.go:171","msg":"trace[1704038855] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"563.735257ms","start":"2026-04-06T21:02:20.456099Z","end":"2026-04-06T21:02:21.019834Z","steps":["trace[1704038855] 'process raft request' (duration: 563.489341ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:21.019893Z","caller":"traceutil/trace.go:171","msg":"trace[720259815] transaction","detail":"{read_only:false; number_of_response:0; response_revision:266; }","duration":"563.09458ms","start":"2026-04-06T21:02:20.456772Z","end":"2026-04-06T21:02:21.019867Z","steps":["trace[720259815] 'process raft request' (duration: 562.858914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.01998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.456924Z","time spent":"562.980217ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:21.020031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.456741Z","time spent":"563.226814ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:21.020054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.456084Z","time spent":"563.880801ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:02:21.020171Z","caller":"traceutil/trace.go:171","msg":"trace[1232937060] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"564.20935ms","start":"2026-04-06T21:02:20.455953Z","end":"2026-04-06T21:02:21.020162Z","steps":["trace[1232937060] 'process raft request' (duration: 563.541462ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:21.020181Z","caller":"traceutil/trace.go:171","msg":"trace[785894506] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"347.99864ms","start":"2026-04-06T21:02:20.672169Z","end":"2026-04-06T21:02:21.020168Z","steps":["trace[785894506] 'process raft request' (duration: 347.525817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.020202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.455925Z","time spent":"564.261372ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:21.02023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.672125Z","time spent":"348.079542ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:02:21.020272Z","caller":"traceutil/trace.go:171","msg":"trace[82067388] linearizableReadLoop","detail":"{readStateIndex:277; appliedIndex:271; }","duration":"320.880908ms","start":"2026-04-06T21:02:20.699305Z","end":"2026-04-06T21:02:21.020186Z","steps":["trace[82067388] 'read index received' (duration: 319.877151ms)","trace[82067388] 'applied index is now lower than readState.Index' (duration: 996.246µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:02:21.020443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.35157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:02:21.02049Z","caller":"traceutil/trace.go:171","msg":"trace[531854723] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:268; }","duration":"679.446632ms","start":"2026-04-06T21:02:20.341031Z","end":"2026-04-06T21:02:21.020478Z","steps":["trace[531854723] 'agreement among raft nodes before linearized reading' (duration: 679.325299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.020523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.341004Z","time spent":"679.511265ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-04-06T21:02:21.019787Z","caller":"traceutil/trace.go:171","msg":"trace[957452835] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"563.811019ms","start":"2026-04-06T21:02:20.455945Z","end":"2026-04-06T21:02:21.019756Z","steps":["trace[957452835] 'process raft request' (duration: 563.279865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.020831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.45591Z","time spent":"564.805676ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:21.020992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.310922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-04-06T21:02:21.021046Z","caller":"traceutil/trace.go:171","msg":"trace[1011254236] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:269; }","duration":"320.425765ms","start":"2026-04-06T21:02:20.700605Z","end":"2026-04-06T21:02:21.021031Z","steps":["trace[1011254236] 'agreement among raft nodes before linearized reading' (duration: 320.291632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.021074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.700573Z","time spent":"320.494927ms","remote":"127.0.0.1:36204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-06T21:02:21.021165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.588559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-06T21:02:21.021227Z","caller":"traceutil/trace.go:171","msg":"trace[547954539] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:269; }","duration":"317.689341ms","start":"2026-04-06T21:02:20.703517Z","end":"2026-04-06T21:02:21.021207Z","steps":["trace[547954539] 'agreement among raft nodes before linearized reading' (duration: 317.566208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.02129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.703499Z","time spent":"317.783694ms","remote":"127.0.0.1:36394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-06T21:02:21.02129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.932448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:02:21.021344Z","caller":"traceutil/trace.go:171","msg":"trace[800148302] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:269; }","duration":"318.04143ms","start":"2026-04-06T21:02:20.703286Z","end":"2026-04-06T21:02:21.021327Z","steps":["trace[800148302] 'agreement among raft nodes before linearized reading' (duration: 317.917337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.021381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.703279Z","time spent":"318.094402ms","remote":"127.0.0.1:36226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-06T21:02:21.022916Z","caller":"traceutil/trace.go:171","msg":"trace[812120724] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"319.391347ms","start":"2026-04-06T21:02:20.703486Z","end":"2026-04-06T21:02:21.022877Z","steps":["trace[812120724] 'process raft request' (duration: 317.313591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:21.023269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:20.70347Z","time spent":"319.597132ms","remote":"127.0.0.1:36084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T21:02:52.473764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.08073ms","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-06T21:02:52.473908Z","caller":"traceutil/trace.go:171","msg":"trace[763650053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"281.291256ms","start":"2026-04-06T21:02:52.192582Z","end":"2026-04-06T21:02:52.473873Z","steps":["trace[763650053] 'range keys from in-memory index tree' (duration: 280.772152ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:52.496127Z","caller":"traceutil/trace.go:171","msg":"trace[202862441] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"114.977654ms","start":"2026-04-06T21:02:52.381106Z","end":"2026-04-06T21:02:52.496084Z","steps":["trace[202862441] 'process raft request' (duration: 109.193267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:52.992428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.686692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:02:52.992702Z","caller":"traceutil/trace.go:171","msg":"trace[678659492] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"131.08755ms","start":"2026-04-06T21:02:52.86159Z","end":"2026-04-06T21:02:52.992678Z","steps":["trace[678659492] 'read index received' (duration: 81.330143ms)","trace[678659492] 'applied index is now lower than readState.Index' (duration: 49.756057ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:02:52.992806Z","caller":"traceutil/trace.go:171","msg":"trace[122836322] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"474.391363ms","start":"2026-04-06T21:02:52.518375Z","end":"2026-04-06T21:02:52.992767Z","steps":["trace[122836322] 'process raft request' (duration: 300.053864ms)","trace[122836322] 'compare' (duration: 173.2758ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:02:52.993346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:52.518279Z","time spent":"474.981398ms","remote":"127.0.0.1:36214","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:02:52.992883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.298085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T21:02:52.993508Z","caller":"traceutil/trace.go:171","msg":"trace[923542535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"131.965993ms","start":"2026-04-06T21:02:52.861527Z","end":"2026-04-06T21:02:52.993493Z","steps":["trace[923542535] 'agreement among raft nodes before linearized reading' (duration: 131.219472ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:02:52.99293Z","caller":"traceutil/trace.go:171","msg":"trace[1827592322] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"439.609111ms","start":"2026-04-06T21:02:52.553308Z","end":"2026-04-06T21:02:52.992917Z","steps":["trace[1827592322] 'process raft request' (duration: 439.270362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:02:52.993641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:02:52.553278Z","time spent":"440.341721ms","remote":"127.0.0.1:36484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T21:03:41.785883Z","caller":"traceutil/trace.go:171","msg":"trace[1621489516] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"179.592274ms","start":"2026-04-06T21:03:41.60625Z","end":"2026-04-06T21:03:41.785842Z","steps":["trace[1621489516] 'process raft request' (duration: 179.368438ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:03:41.855609Z","caller":"traceutil/trace.go:171","msg":"trace[1969247658] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"246.212656ms","start":"2026-04-06T21:03:41.609368Z","end":"2026-04-06T21:03:41.855581Z","steps":["trace[1969247658] 'process raft request' (duration: 237.700728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:07.219987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.052803ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:04:07.220115Z","caller":"traceutil/trace.go:171","msg":"trace[260672730] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"233.959157ms","start":"2026-04-06T21:04:06.986137Z","end":"2026-04-06T21:04:07.220096Z","steps":["trace[260672730] 'process raft request' (duration: 104.714539ms)","trace[260672730] 'compare' (duration: 128.9611ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:37.228694Z","caller":"traceutil/trace.go:171","msg":"trace[436133400] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"111.414244ms","start":"2026-04-06T21:04:37.117256Z","end":"2026-04-06T21:04:37.22867Z","steps":["trace[436133400] 'process raft request' (duration: 111.26557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:38.284062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.849286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:04:38.284178Z","caller":"traceutil/trace.go:171","msg":"trace[1791991746] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"291.265088ms","start":"2026-04-06T21:04:37.992893Z","end":"2026-04-06T21:04:38.284158Z","steps":["trace[1791991746] 'process raft request' (duration: 123.256338ms)","trace[1791991746] 'compare' (duration: 167.742453ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T21:04:38.29682Z","caller":"traceutil/trace.go:171","msg":"trace[2014815464] linearizableReadLoop","detail":"{readStateIndex:1294; appliedIndex:1293; }","duration":"162.796217ms","start":"2026-04-06T21:04:38.133994Z","end":"2026-04-06T21:04:38.29679Z","steps":["trace[2014815464] 'read index received' (duration: 162.728465ms)","trace[2014815464] 'applied index is now lower than readState.Index' (duration: 66.702µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:04:38.297039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.038604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-06T21:04:38.297109Z","caller":"traceutil/trace.go:171","msg":"trace[185526082] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1248; }","duration":"163.163297ms","start":"2026-04-06T21:04:38.133935Z","end":"2026-04-06T21:04:38.297098Z","steps":["trace[185526082] 'agreement among raft nodes before linearized reading' (duration: 162.948871ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:38.296851Z","caller":"traceutil/trace.go:171","msg":"trace[1226976359] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"268.093594ms","start":"2026-04-06T21:04:38.02873Z","end":"2026-04-06T21:04:38.296824Z","steps":["trace[1226976359] 'process raft request' (duration: 267.94251ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:38.303503Z","caller":"traceutil/trace.go:171","msg":"trace[774816422] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"119.469944ms","start":"2026-04-06T21:04:38.184023Z","end":"2026-04-06T21:04:38.303492Z","steps":["trace[774816422] 'process raft request' (duration: 119.353221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:52.845361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.842486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T21:04:52.84549Z","caller":"traceutil/trace.go:171","msg":"trace[362447895] linearizableReadLoop","detail":"{readStateIndex:1394; appliedIndex:1393; }","duration":"476.633262ms","start":"2026-04-06T21:04:52.368834Z","end":"2026-04-06T21:04:52.845467Z","steps":["trace[362447895] 'read index received' (duration: 267.410236ms)","trace[362447895] 'applied index is now lower than readState.Index' (duration: 209.221126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:04:52.845891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.207062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-06T21:04:52.845948Z","caller":"traceutil/trace.go:171","msg":"trace[180258626] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"495.23984ms","start":"2026-04-06T21:04:52.350646Z","end":"2026-04-06T21:04:52.845886Z","steps":["trace[180258626] 'process raft request' (duration: 285.747426ms)","trace[180258626] 'compare' (duration: 208.726003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T21:04:52.846026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.321155ms","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-06T21:04:52.846038Z","caller":"traceutil/trace.go:171","msg":"trace[1470550995] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1345; }","duration":"438.399937ms","start":"2026-04-06T21:04:52.407616Z","end":"2026-04-06T21:04:52.846015Z","steps":["trace[1470550995] 'agreement among raft nodes before linearized reading' (duration: 438.105739ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T21:04:52.846077Z","caller":"traceutil/trace.go:171","msg":"trace[433978177] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1345; }","duration":"438.409828ms","start":"2026-04-06T21:04:52.407652Z","end":"2026-04-06T21:04:52.846062Z","steps":["trace[433978177] 'agreement among raft nodes before linearized reading' (duration: 438.241913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:52.846087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:52.407596Z","time spent":"438.47964ms","remote":"127.0.0.1:36292","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-06T21:04:52.846125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:52.407629Z","time spent":"438.48733ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-06T21:04:52.845908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.062694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T21:04:52.846243Z","caller":"traceutil/trace.go:171","msg":"trace[1050229351] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1345; }","duration":"477.439064ms","start":"2026-04-06T21:04:52.368785Z","end":"2026-04-06T21:04:52.846225Z","steps":["trace[1050229351] 'agreement among raft nodes before linearized reading' (duration: 477.064974ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T21:04:52.846203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:52.350617Z","time spent":"495.452176ms","remote":"127.0.0.1:36292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T21:04:52.846309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T21:04:52.368764Z","time spent":"477.525297ms","remote":"127.0.0.1:36454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "}