{"level":"warn","ts":"2026-09-22T01:48:37.140548Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:48:37.14066Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.91: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://162.253.55.91:2380","--initial-cluster=instance=https://162.253.55.91:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.91:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.91: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-09-22T01:48:37.140748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-22T01:48:37.140764Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-22T01:48:37.140785Z","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-09-22T01:48:37.141553Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"]} {"level":"info","ts":"2026-09-22T01:48:37.141627Z","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://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.91: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-09-22T01:48:37.14832Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.217685ms"} {"level":"info","ts":"2026-09-22T01:48:37.159173Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a5b61ed9120d6b3","cluster-id":"9e0c1e8055c016ad"} {"level":"info","ts":"2026-09-22T01:48:37.159248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=()"} {"level":"info","ts":"2026-09-22T01:48:37.159264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 0"} {"level":"info","ts":"2026-09-22T01:48:37.159272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a5b61ed9120d6b3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-22T01:48:37.159281Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became follower at term 1"} {"level":"info","ts":"2026-09-22T01:48:37.159349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"warn","ts":"2026-09-22T01:48:37.169174Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-22T01:48:37.170495Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-22T01:48:37.175354Z","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-09-22T01:48:37.177503Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a5b61ed9120d6b3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-22T01:48:37.177734Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a5b61ed9120d6b3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-22T01:48:37.17784Z","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-09-22T01:48:37.177892Z","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-09-22T01:48:37.177909Z","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-09-22T01:48:37.181069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 switched to configuration voters=(9969669873084716723)"} {"level":"info","ts":"2026-09-22T01:48:37.181709Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","added-peer-id":"8a5b61ed9120d6b3","added-peer-peer-urls":["https://162.253.55.91:2380"]} {"level":"info","ts":"2026-09-22T01:48:37.18361Z","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-09-22T01:48:37.183902Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-22T01:48:37.183949Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.91:2380"} {"level":"info","ts":"2026-09-22T01:48:37.184726Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a5b61ed9120d6b3","initial-advertise-peer-urls":["https://162.253.55.91:2380"],"listen-peer-urls":["https://162.253.55.91:2380"],"advertise-client-urls":["https://162.253.55.91:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.91:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-22T01:48:37.184767Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-22T01:48:37.960356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 is starting a new election at term 1"} {"level":"info","ts":"2026-09-22T01:48:37.960454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-22T01:48:37.96048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgPreVoteResp from 8a5b61ed9120d6b3 at term 1"} {"level":"info","ts":"2026-09-22T01:48:37.960509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became candidate at term 2"} {"level":"info","ts":"2026-09-22T01:48:37.960521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 received MsgVoteResp from 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-22T01:48:37.960536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a5b61ed9120d6b3 became leader at term 2"} {"level":"info","ts":"2026-09-22T01:48:37.96055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a5b61ed9120d6b3 elected leader 8a5b61ed9120d6b3 at term 2"} {"level":"info","ts":"2026-09-22T01:48:37.961779Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a5b61ed9120d6b3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.91:2379]}","request-path":"/0/members/8a5b61ed9120d6b3/attributes","cluster-id":"9e0c1e8055c016ad","publish-timeout":"7s"} {"level":"info","ts":"2026-09-22T01:48:37.961826Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:48:37.962328Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:37.962514Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-22T01:48:37.962672Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-22T01:48:37.962747Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-22T01:48:37.963147Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9e0c1e8055c016ad","local-member-id":"8a5b61ed9120d6b3","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:37.963248Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:37.963281Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-22T01:48:37.965976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-22T01:48:37.973118Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.91:2379"} {"level":"info","ts":"2026-09-22T01:48:39.704964Z","caller":"traceutil/trace.go:171","msg":"trace[1437131624] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"154.421435ms","start":"2026-09-22T01:48:39.550513Z","end":"2026-09-22T01:48:39.704934Z","steps":["trace[1437131624] 'process raft request' (duration: 154.132331ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.010713Z","caller":"traceutil/trace.go:171","msg":"trace[1870715558] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"448.97046ms","start":"2026-09-22T01:48:39.561721Z","end":"2026-09-22T01:48:40.010692Z","steps":["trace[1870715558] 'read index received' (duration: 142.925744ms)","trace[1870715558] 'applied index is now lower than readState.Index' (duration: 306.043546ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:40.010795Z","caller":"traceutil/trace.go:171","msg":"trace[1083601823] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"460.135215ms","start":"2026-09-22T01:48:39.550614Z","end":"2026-09-22T01:48:40.010749Z","steps":["trace[1083601823] 'process raft request' (duration: 459.66498ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.011084Z","caller":"traceutil/trace.go:171","msg":"trace[2005994208] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"395.451901ms","start":"2026-09-22T01:48:39.615592Z","end":"2026-09-22T01:48:40.011044Z","steps":["trace[2005994208] 'process raft request' (duration: 394.986145ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.011763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.840526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-09-22T01:48:40.011787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.158921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.011821Z","caller":"traceutil/trace.go:171","msg":"trace[2003115642] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"411.195851ms","start":"2026-09-22T01:48:39.600613Z","end":"2026-09-22T01:48:40.011809Z","steps":["trace[2003115642] 'agreement among raft nodes before linearized reading' (duration: 411.136091ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.011809Z","caller":"traceutil/trace.go:171","msg":"trace[979534174] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"409.923876ms","start":"2026-09-22T01:48:39.60187Z","end":"2026-09-22T01:48:40.011794Z","steps":["trace[979534174] 'agreement among raft nodes before linearized reading' (duration: 409.875166ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.011811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.5506Z","time spent":"460.294818ms","remote":"127.0.0.1:42230","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-22T01:48:40.011882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.601843Z","time spent":"410.029217ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-22T01:48:40.011878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.600603Z","time spent":"411.266823ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-22T01:48:40.012059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.615575Z","time spent":"396.20481ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:48:40.011213Z","caller":"traceutil/trace.go:171","msg":"trace[626118591] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"395.718204ms","start":"2026-09-22T01:48:39.615456Z","end":"2026-09-22T01:48:40.011174Z","steps":["trace[626118591] 'process raft request' (duration: 394.981225ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.013086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.61544Z","time spent":"397.562307ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:48:40.011253Z","caller":"traceutil/trace.go:171","msg":"trace[311131176] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"398.975623ms","start":"2026-09-22T01:48:39.61224Z","end":"2026-09-22T01:48:40.011215Z","steps":["trace[311131176] 'process raft request' (duration: 398.149483ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.013484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.612223Z","time spent":"401.161689ms","remote":"127.0.0.1:42576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:48:40.011247Z","caller":"traceutil/trace.go:171","msg":"trace[1473836791] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"381.583262ms","start":"2026-09-22T01:48:39.629608Z","end":"2026-09-22T01:48:40.011191Z","steps":["trace[1473836791] 'process raft request' (duration: 381.037555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.013877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.629594Z","time spent":"384.123953ms","remote":"127.0.0.1:42352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:48:40.011458Z","caller":"traceutil/trace.go:171","msg":"trace[1010124768] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"395.591613ms","start":"2026-09-22T01:48:39.61577Z","end":"2026-09-22T01:48:40.011362Z","steps":["trace[1010124768] 'process raft request' (duration: 394.746683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.014199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.615753Z","time spent":"398.339616ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:48:40.011518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.936383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.014323Z","caller":"traceutil/trace.go:171","msg":"trace[1098400348] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"420.750838ms","start":"2026-09-22T01:48:39.59355Z","end":"2026-09-22T01:48:40.014301Z","steps":["trace[1098400348] 'agreement among raft nodes before linearized reading' (duration: 417.843142ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.014458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.593543Z","time spent":"420.869119ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-22T01:48:40.011569Z","caller":"traceutil/trace.go:171","msg":"trace[1772332862] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"395.857555ms","start":"2026-09-22T01:48:39.6157Z","end":"2026-09-22T01:48:40.011558Z","steps":["trace[1772332862] 'process raft request' (duration: 394.788032ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.014843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.61567Z","time spent":"399.016694ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:48:40.011599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.877701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.015003Z","caller":"traceutil/trace.go:171","msg":"trace[315581147] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"453.294253ms","start":"2026-09-22T01:48:39.561691Z","end":"2026-09-22T01:48:40.014986Z","steps":["trace[315581147] 'agreement among raft nodes before linearized reading' (duration: 449.889332ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.015254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.561683Z","time spent":"453.416614ms","remote":"127.0.0.1:42548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-09-22T01:48:40.011633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.509821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.015365Z","caller":"traceutil/trace.go:171","msg":"trace[1179702136] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"352.295367ms","start":"2026-09-22T01:48:39.663051Z","end":"2026-09-22T01:48:40.015346Z","steps":["trace[1179702136] 'agreement among raft nodes before linearized reading' (duration: 348.562972ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.015428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.66294Z","time spent":"352.472559ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-09-22T01:48:40.011671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.38357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.015536Z","caller":"traceutil/trace.go:171","msg":"trace[206655942] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"399.247527ms","start":"2026-09-22T01:48:39.616242Z","end":"2026-09-22T01:48:40.01549Z","steps":["trace[206655942] 'agreement among raft nodes before linearized reading' (duration: 395.40578ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.015588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.616226Z","time spent":"399.348628ms","remote":"127.0.0.1:42268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-09-22T01:48:40.011704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.03765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.91\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.015755Z","caller":"traceutil/trace.go:171","msg":"trace[495008248] range","detail":"{range_begin:/registry/masterleases/162.253.55.91; range_end:; response_count:0; response_revision:11; }","duration":"401.103129ms","start":"2026-09-22T01:48:39.614628Z","end":"2026-09-22T01:48:40.015731Z","steps":["trace[495008248] 'agreement among raft nodes before linearized reading' (duration: 397.05938ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.015851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.614609Z","time spent":"401.201091ms","remote":"127.0.0.1:42194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/162.253.55.91\" "} {"level":"info","ts":"2026-09-22T01:48:40.011697Z","caller":"traceutil/trace.go:171","msg":"trace[1089551812] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"383.307613ms","start":"2026-09-22T01:48:39.628366Z","end":"2026-09-22T01:48:40.011673Z","steps":["trace[1089551812] 'process raft request' (duration: 382.23772ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.016196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.628349Z","time spent":"387.746537ms","remote":"127.0.0.1:42436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-22T01:48:40.0117Z","caller":"traceutil/trace.go:171","msg":"trace[1190620509] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"395.875816ms","start":"2026-09-22T01:48:39.615788Z","end":"2026-09-22T01:48:40.011664Z","steps":["trace[1190620509] 'process raft request' (duration: 394.758582ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.016402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.615746Z","time spent":"400.595623ms","remote":"127.0.0.1:42646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-22T01:48:40.011732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.369178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.016479Z","caller":"traceutil/trace.go:171","msg":"trace[953955253] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"404.119076ms","start":"2026-09-22T01:48:39.612339Z","end":"2026-09-22T01:48:40.016458Z","steps":["trace[953955253] 'agreement among raft nodes before linearized reading' (duration: 399.377238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.016679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:48:39.612327Z","time spent":"404.238157ms","remote":"127.0.0.1:42286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-09-22T01:48:40.242236Z","caller":"traceutil/trace.go:171","msg":"trace[667285111] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"223.017545ms","start":"2026-09-22T01:48:40.019184Z","end":"2026-09-22T01:48:40.242202Z","steps":["trace[667285111] 'read index received' (duration: 217.490237ms)","trace[667285111] 'applied index is now lower than readState.Index' (duration: 5.510218ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:48:40.242294Z","caller":"traceutil/trace.go:171","msg":"trace[90600341] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"223.221068ms","start":"2026-09-22T01:48:40.019034Z","end":"2026-09-22T01:48:40.242255Z","steps":["trace[90600341] 'process raft request' (duration: 217.624289ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.242737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.506251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-22T01:48:40.242805Z","caller":"traceutil/trace.go:171","msg":"trace[358375837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"223.609522ms","start":"2026-09-22T01:48:40.019178Z","end":"2026-09-22T01:48:40.242788Z","steps":["trace[358375837] 'agreement among raft nodes before linearized reading' (duration: 223.167097ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.243005Z","caller":"traceutil/trace.go:171","msg":"trace[586909756] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"222.114324ms","start":"2026-09-22T01:48:40.020786Z","end":"2026-09-22T01:48:40.2429Z","steps":["trace[586909756] 'process raft request' (duration: 221.83785ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.243055Z","caller":"traceutil/trace.go:171","msg":"trace[86172490] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"221.951032ms","start":"2026-09-22T01:48:40.021054Z","end":"2026-09-22T01:48:40.243005Z","steps":["trace[86172490] 'process raft request' (duration: 221.709379ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.243801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.157997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-09-22T01:48:40.244001Z","caller":"traceutil/trace.go:171","msg":"trace[1634241096] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"223.38652ms","start":"2026-09-22T01:48:40.02059Z","end":"2026-09-22T01:48:40.243976Z","steps":["trace[1634241096] 'agreement among raft nodes before linearized reading' (duration: 223.108166ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.244143Z","caller":"traceutil/trace.go:171","msg":"trace[230633660] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"222.507648ms","start":"2026-09-22T01:48:40.021625Z","end":"2026-09-22T01:48:40.244133Z","steps":["trace[230633660] 'process raft request' (duration: 221.364475ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.244368Z","caller":"traceutil/trace.go:171","msg":"trace[989009817] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"222.021792ms","start":"2026-09-22T01:48:40.022336Z","end":"2026-09-22T01:48:40.244357Z","steps":["trace[989009817] 'process raft request' (duration: 220.724746ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.244492Z","caller":"traceutil/trace.go:171","msg":"trace[1963488949] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"222.131004ms","start":"2026-09-22T01:48:40.022352Z","end":"2026-09-22T01:48:40.244483Z","steps":["trace[1963488949] 'process raft request' (duration: 220.744587ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.244532Z","caller":"traceutil/trace.go:171","msg":"trace[557778914] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"215.379572ms","start":"2026-09-22T01:48:40.029131Z","end":"2026-09-22T01:48:40.24451Z","steps":["trace[557778914] 'process raft request' (duration: 214.199208ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.244637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.389728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-d5fdx\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-09-22T01:48:40.244756Z","caller":"traceutil/trace.go:171","msg":"trace[1644364015] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-d5fdx; range_end:; response_count:1; response_revision:19; }","duration":"222.498989ms","start":"2026-09-22T01:48:40.022219Z","end":"2026-09-22T01:48:40.244718Z","steps":["trace[1644364015] 'agreement among raft nodes before linearized reading' (duration: 222.300556ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.244107Z","caller":"traceutil/trace.go:171","msg":"trace[803506122] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"222.875103ms","start":"2026-09-22T01:48:40.02122Z","end":"2026-09-22T01:48:40.244095Z","steps":["trace[803506122] 'process raft request' (duration: 221.679849ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:48:40.243885Z","caller":"traceutil/trace.go:171","msg":"trace[1331674284] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"221.085721ms","start":"2026-09-22T01:48:40.022763Z","end":"2026-09-22T01:48:40.243849Z","steps":["trace[1331674284] 'process raft request' (duration: 220.485494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:48:40.244498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.651114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-09-22T01:48:40.245332Z","caller":"traceutil/trace.go:171","msg":"trace[1869722463] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"165.526675ms","start":"2026-09-22T01:48:40.079774Z","end":"2026-09-22T01:48:40.245301Z","steps":["trace[1869722463] 'agreement among raft nodes before linearized reading' (duration: 164.587323ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:49:27.377094Z","caller":"traceutil/trace.go:171","msg":"trace[171998174] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"148.889761ms","start":"2026-09-22T01:49:27.228169Z","end":"2026-09-22T01:49:27.377059Z","steps":["trace[171998174] 'process raft request' (duration: 148.704809ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:49:27.575984Z","caller":"traceutil/trace.go:171","msg":"trace[1023963371] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"116.899706ms","start":"2026-09-22T01:49:27.459024Z","end":"2026-09-22T01:49:27.575924Z","steps":["trace[1023963371] 'read index received' (duration: 57.539885ms)","trace[1023963371] 'applied index is now lower than readState.Index' (duration: 59.357611ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:49:27.579497Z","caller":"traceutil/trace.go:171","msg":"trace[2042086154] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"272.02087ms","start":"2026-09-22T01:49:27.307353Z","end":"2026-09-22T01:49:27.579373Z","steps":["trace[2042086154] 'process raft request' (duration: 209.541308ms)","trace[2042086154] 'compare' (duration: 58.63486ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:49:27.579602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.546464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-22T01:49:27.579752Z","caller":"traceutil/trace.go:171","msg":"trace[70418597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"120.737736ms","start":"2026-09-22T01:49:27.458986Z","end":"2026-09-22T01:49:27.579723Z","steps":["trace[70418597] 'agreement among raft nodes before linearized reading' (duration: 120.442942ms)"],"step_count":1} {"level":"info","ts":"2026-09-22T01:49:28.134713Z","caller":"traceutil/trace.go:171","msg":"trace[1903041614] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"326.105992ms","start":"2026-09-22T01:49:27.808579Z","end":"2026-09-22T01:49:28.134685Z","steps":["trace[1903041614] 'read index received' (duration: 282.652863ms)","trace[1903041614] 'applied index is now lower than readState.Index' (duration: 43.451319ms)"],"step_count":2} {"level":"info","ts":"2026-09-22T01:49:28.1348Z","caller":"traceutil/trace.go:171","msg":"trace[673748371] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"478.710492ms","start":"2026-09-22T01:49:27.656055Z","end":"2026-09-22T01:49:28.134766Z","steps":["trace[673748371] 'process raft request' (duration: 435.075711ms)","trace[673748371] 'compare' (duration: 43.390728ms)"],"step_count":2} {"level":"warn","ts":"2026-09-22T01:49:28.135011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.423356ms","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-09-22T01:49:28.135076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:49:27.656006Z","time spent":"478.851254ms","remote":"127.0.0.1:42624","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-09-22T01:49:28.135098Z","caller":"traceutil/trace.go:171","msg":"trace[1033698766] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"326.573148ms","start":"2026-09-22T01:49:27.808511Z","end":"2026-09-22T01:49:28.135084Z","steps":["trace[1033698766] 'agreement among raft nodes before linearized reading' (duration: 326.340275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-22T01:49:28.135395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-22T01:49:27.808487Z","time spent":"326.862602ms","remote":"127.0.0.1:42438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-22T01:51:54.857552Z","caller":"traceutil/trace.go:171","msg":"trace[1572702660] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"115.333903ms","start":"2026-09-22T01:51:54.742193Z","end":"2026-09-22T01:51:54.857527Z","steps":["trace[1572702660] 'process raft request' (duration: 115.18335ms)"],"step_count":1}