{"level":"warn","ts":"2026-09-15T22:17:23.569336Z","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-15T22:17:23.569448Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.189: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.189:2380","--initial-cluster=instance=https://162.253.55.189:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.189:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.189: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-15T22:17:23.569529Z","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-15T22:17:23.569541Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-09-15T22:17:23.569563Z","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-15T22:17:23.570246Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"]} {"level":"info","ts":"2026-09-15T22:17:23.57033Z","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.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.189: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-15T22:17:23.578109Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.518393ms"} {"level":"info","ts":"2026-09-15T22:17:23.585463Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f14c7d724df9ef94","cluster-id":"f7bde5cbb4d65447"} {"level":"info","ts":"2026-09-15T22:17:23.585541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=()"} {"level":"info","ts":"2026-09-15T22:17:23.585569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 0"} {"level":"info","ts":"2026-09-15T22:17:23.585581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f14c7d724df9ef94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-15T22:17:23.585591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became follower at term 1"} {"level":"info","ts":"2026-09-15T22:17:23.585634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"warn","ts":"2026-09-15T22:17:23.593898Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-15T22:17:23.59531Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-15T22:17:23.596554Z","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-15T22:17:23.598324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f14c7d724df9ef94","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-15T22:17:23.599702Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f14c7d724df9ef94","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-15T22:17:23.599855Z","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-15T22:17:23.603679Z","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-15T22:17:23.603914Z","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-15T22:17:23.605293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 switched to configuration voters=(17387410191258611604)"} {"level":"info","ts":"2026-09-15T22:17:23.605706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","added-peer-id":"f14c7d724df9ef94","added-peer-peer-urls":["https://162.253.55.189:2380"]} {"level":"info","ts":"2026-09-15T22:17:23.606099Z","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-15T22:17:23.606414Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f14c7d724df9ef94","initial-advertise-peer-urls":["https://162.253.55.189:2380"],"listen-peer-urls":["https://162.253.55.189:2380"],"advertise-client-urls":["https://162.253.55.189:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.189:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-15T22:17:23.60649Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-15T22:17:23.606679Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-09-15T22:17:23.606723Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.189:2380"} {"level":"info","ts":"2026-09-15T22:17:24.485795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 is starting a new election at term 1"} {"level":"info","ts":"2026-09-15T22:17:24.485865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-15T22:17:24.48591Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgPreVoteResp from f14c7d724df9ef94 at term 1"} {"level":"info","ts":"2026-09-15T22:17:24.485934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became candidate at term 2"} {"level":"info","ts":"2026-09-15T22:17:24.485945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 received MsgVoteResp from f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-09-15T22:17:24.485958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f14c7d724df9ef94 became leader at term 2"} {"level":"info","ts":"2026-09-15T22:17:24.485972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f14c7d724df9ef94 elected leader f14c7d724df9ef94 at term 2"} {"level":"info","ts":"2026-09-15T22:17:24.488508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:24.489476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f14c7d724df9ef94","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.189:2379]}","request-path":"/0/members/f14c7d724df9ef94/attributes","cluster-id":"f7bde5cbb4d65447","publish-timeout":"7s"} {"level":"info","ts":"2026-09-15T22:17:24.489489Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:17:24.489867Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-15T22:17:24.490599Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-15T22:17:24.490639Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-15T22:17:24.491569Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.189:2379"} {"level":"info","ts":"2026-09-15T22:17:24.491584Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f7bde5cbb4d65447","local-member-id":"f14c7d724df9ef94","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:24.491736Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:24.49178Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-15T22:17:24.493182Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-15T22:17:29.68443Z","caller":"traceutil/trace.go:171","msg":"trace[129912870] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"108.13932ms","start":"2026-09-15T22:17:29.576252Z","end":"2026-09-15T22:17:29.684391Z","steps":["trace[129912870] 'process raft request' (duration: 107.896861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.025499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.870531ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-09-15T22:17:30.025648Z","caller":"traceutil/trace.go:171","msg":"trace[1035404172] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"341.216648ms","start":"2026-09-15T22:17:29.68441Z","end":"2026-09-15T22:17:30.025626Z","steps":["trace[1035404172] 'read index received' (duration: 168.103028ms)","trace[1035404172] 'applied index is now lower than readState.Index' (duration: 173.11194ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:17:30.025728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.673616Z","time spent":"352.10279ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-09-15T22:17:30.02584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.207964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-09-15T22:17:30.025932Z","caller":"traceutil/trace.go:171","msg":"trace[244421139] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:225; }","duration":"352.36963ms","start":"2026-09-15T22:17:29.67354Z","end":"2026-09-15T22:17:30.02591Z","steps":["trace[244421139] 'agreement among raft nodes before linearized reading' (duration: 352.175982ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.025966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.673515Z","time spent":"352.442292ms","remote":"127.0.0.1:55728","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-09-15T22:17:30.025925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.197683ms","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-09-15T22:17:30.026053Z","caller":"traceutil/trace.go:171","msg":"trace[1845236617] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:225; }","duration":"357.349039ms","start":"2026-09-15T22:17:29.668666Z","end":"2026-09-15T22:17:30.026015Z","steps":["trace[1845236617] 'agreement among raft nodes before linearized reading' (duration: 357.062858ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.026112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.668598Z","time spent":"357.499825ms","remote":"127.0.0.1:55612","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-09-15T22:17:30.097995Z","caller":"traceutil/trace.go:171","msg":"trace[429376785] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"409.390288ms","start":"2026-09-15T22:17:29.688562Z","end":"2026-09-15T22:17:30.097952Z","steps":["trace[429376785] 'process raft request' (duration: 409.061175ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098128Z","caller":"traceutil/trace.go:171","msg":"trace[582197523] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"408.159101ms","start":"2026-09-15T22:17:29.689916Z","end":"2026-09-15T22:17:30.098075Z","steps":["trace[582197523] 'process raft request' (duration: 407.961764ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098175Z","caller":"traceutil/trace.go:171","msg":"trace[871479555] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"332.167057ms","start":"2026-09-15T22:17:29.765962Z","end":"2026-09-15T22:17:30.098129Z","steps":["trace[871479555] 'process raft request' (duration: 331.98006ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098367Z","caller":"traceutil/trace.go:171","msg":"trace[175656929] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"312.129929ms","start":"2026-09-15T22:17:29.786222Z","end":"2026-09-15T22:17:30.098352Z","steps":["trace[175656929] 'process raft request' (duration: 311.993544ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098351Z","caller":"traceutil/trace.go:171","msg":"trace[1590709241] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"313.006912ms","start":"2026-09-15T22:17:29.785321Z","end":"2026-09-15T22:17:30.098328Z","steps":["trace[1590709241] 'process raft request' (duration: 312.728971ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098407Z","caller":"traceutil/trace.go:171","msg":"trace[1909976318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:227; }","duration":"313.49815ms","start":"2026-09-15T22:17:29.784897Z","end":"2026-09-15T22:17:30.098395Z","steps":["trace[1909976318] 'process raft request' (duration: 313.103426ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098453Z","caller":"traceutil/trace.go:171","msg":"trace[1327041644] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"312.829445ms","start":"2026-09-15T22:17:29.785603Z","end":"2026-09-15T22:17:30.098432Z","steps":["trace[1327041644] 'process raft request' (duration: 312.561385ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.098429Z","caller":"traceutil/trace.go:171","msg":"trace[374081200] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"312.978931ms","start":"2026-09-15T22:17:29.785409Z","end":"2026-09-15T22:17:30.098388Z","steps":["trace[374081200] 'process raft request' (duration: 312.699251ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.098513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.511761ms","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-09-15T22:17:30.09858Z","caller":"traceutil/trace.go:171","msg":"trace[1207129592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:231; }","duration":"106.594334ms","start":"2026-09-15T22:17:29.99197Z","end":"2026-09-15T22:17:30.098565Z","steps":["trace[1207129592] 'agreement among raft nodes before linearized reading' (duration: 106.425977ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.098637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.112143ms","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-09-15T22:17:30.098682Z","caller":"traceutil/trace.go:171","msg":"trace[1249043479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:231; }","duration":"107.222148ms","start":"2026-09-15T22:17:29.991448Z","end":"2026-09-15T22:17:30.09867Z","steps":["trace[1249043479] 'agreement among raft nodes before linearized reading' (duration: 107.051511ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.098822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.688537Z","time spent":"409.591445ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:17:30.098849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.785404Z","time spent":"313.160258ms","remote":"127.0.0.1:55540","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-09-15T22:17:30.098824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.785443Z","time spent":"313.038393ms","remote":"127.0.0.1:55540","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-09-15T22:17:30.098894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.689902Z","time spent":"408.310948ms","remote":"127.0.0.1:55682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":373,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:17:30.099017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.76593Z","time spent":"332.350584ms","remote":"127.0.0.1:55528","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-09-15T22:17:30.099124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.786214Z","time spent":"312.192801ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3453,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:17:30.099238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.785277Z","time spent":"313.142687ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-15T22:17:30.099289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:29.784863Z","time spent":"313.614964ms","remote":"127.0.0.1:55540","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-09-15T22:17:30.363639Z","caller":"traceutil/trace.go:171","msg":"trace[691286848] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"336.16745ms","start":"2026-09-15T22:17:30.027408Z","end":"2026-09-15T22:17:30.363575Z","steps":["trace[691286848] 'process raft request' (duration: 321.268342ms)","trace[691286848] 'compare' (duration: 14.286365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:17:30.364272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:30.027378Z","time spent":"336.762872ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-15T22:17:30.363703Z","caller":"traceutil/trace.go:171","msg":"trace[2091296493] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"265.387161ms","start":"2026-09-15T22:17:30.098267Z","end":"2026-09-15T22:17:30.363654Z","steps":["trace[2091296493] 'read index received' (duration: 250.452222ms)","trace[2091296493] 'applied index is now lower than readState.Index' (duration: 14.932209ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:17:30.36389Z","caller":"traceutil/trace.go:171","msg":"trace[2117560584] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"257.997705ms","start":"2026-09-15T22:17:30.105862Z","end":"2026-09-15T22:17:30.36386Z","steps":["trace[2117560584] 'process raft request' (duration: 257.565149ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.363882Z","caller":"traceutil/trace.go:171","msg":"trace[756330395] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"257.489106ms","start":"2026-09-15T22:17:30.106364Z","end":"2026-09-15T22:17:30.363853Z","steps":["trace[756330395] 'process raft request' (duration: 257.429734ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.363963Z","caller":"traceutil/trace.go:171","msg":"trace[2100503410] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"258.067758ms","start":"2026-09-15T22:17:30.105885Z","end":"2026-09-15T22:17:30.363953Z","steps":["trace[2100503410] 'process raft request' (duration: 257.728805ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.364002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.910365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-09-15T22:17:30.365008Z","caller":"traceutil/trace.go:171","msg":"trace[193657674] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:236; }","duration":"334.959885ms","start":"2026-09-15T22:17:30.030034Z","end":"2026-09-15T22:17:30.364994Z","steps":["trace[193657674] 'agreement among raft nodes before linearized reading' (duration: 333.802922ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.365015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.415698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:17:30.365045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.361566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-15T22:17:30.365116Z","caller":"traceutil/trace.go:171","msg":"trace[1046543090] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:236; }","duration":"259.45202ms","start":"2026-09-15T22:17:30.105649Z","end":"2026-09-15T22:17:30.365101Z","steps":["trace[1046543090] 'agreement among raft nodes before linearized reading' (duration: 259.334995ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.365115Z","caller":"traceutil/trace.go:171","msg":"trace[1119378153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:236; }","duration":"259.546523ms","start":"2026-09-15T22:17:30.105547Z","end":"2026-09-15T22:17:30.365093Z","steps":["trace[1119378153] 'agreement among raft nodes before linearized reading' (duration: 259.375746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.365148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.636365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4017"} {"level":"info","ts":"2026-09-15T22:17:30.365178Z","caller":"traceutil/trace.go:171","msg":"trace[1588019032] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:236; }","duration":"263.703868ms","start":"2026-09-15T22:17:30.101466Z","end":"2026-09-15T22:17:30.36517Z","steps":["trace[1588019032] 'agreement among raft nodes before linearized reading' (duration: 263.633576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.365074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:30.030022Z","time spent":"335.021827ms","remote":"127.0.0.1:55528","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-09-15T22:17:30.365082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.137572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-15T22:17:30.363899Z","caller":"traceutil/trace.go:171","msg":"trace[771035071] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"257.909342ms","start":"2026-09-15T22:17:30.105971Z","end":"2026-09-15T22:17:30.36388Z","steps":["trace[771035071] 'process raft request' (duration: 257.725135ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.365345Z","caller":"traceutil/trace.go:171","msg":"trace[1540807686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:236; }","duration":"265.709703ms","start":"2026-09-15T22:17:30.099621Z","end":"2026-09-15T22:17:30.36533Z","steps":["trace[1540807686] 'agreement among raft nodes before linearized reading' (duration: 265.154433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.365209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.411449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"warn","ts":"2026-09-15T22:17:30.365532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.112013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-09-15T22:17:30.365604Z","caller":"traceutil/trace.go:171","msg":"trace[241246050] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:236; }","duration":"264.221938ms","start":"2026-09-15T22:17:30.101361Z","end":"2026-09-15T22:17:30.365582Z","steps":["trace[241246050] 'agreement among raft nodes before linearized reading' (duration: 264.097093ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:17:30.365637Z","caller":"traceutil/trace.go:171","msg":"trace[1005556017] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:236; }","duration":"336.995311ms","start":"2026-09-15T22:17:30.028479Z","end":"2026-09-15T22:17:30.365474Z","steps":["trace[1005556017] 'agreement among raft nodes before linearized reading' (duration: 335.863808ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:17:30.365807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:17:30.028465Z","time spent":"337.312383ms","remote":"127.0.0.1:55728","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-09-15T22:18:06.008048Z","caller":"traceutil/trace.go:171","msg":"trace[537071496] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"159.107495ms","start":"2026-09-15T22:18:05.848909Z","end":"2026-09-15T22:18:06.008016Z","steps":["trace[537071496] 'process raft request' (duration: 159.059514ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:06.008118Z","caller":"traceutil/trace.go:171","msg":"trace[743736390] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"159.28001ms","start":"2026-09-15T22:18:05.848798Z","end":"2026-09-15T22:18:06.008078Z","steps":["trace[743736390] 'process raft request' (duration: 106.161656ms)","trace[743736390] 'compare' (duration: 52.803095ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:07.872051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.653069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-09-15T22:18:07.872161Z","caller":"traceutil/trace.go:171","msg":"trace[785131421] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:501; }","duration":"122.832544ms","start":"2026-09-15T22:18:07.749303Z","end":"2026-09-15T22:18:07.872136Z","steps":["trace[785131421] 'range keys from in-memory index tree' (duration: 122.512186ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:14.681054Z","caller":"traceutil/trace.go:171","msg":"trace[1801877834] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"261.074524ms","start":"2026-09-15T22:18:14.419941Z","end":"2026-09-15T22:18:14.681015Z","steps":["trace[1801877834] 'process raft request' (duration: 260.334936ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:15.546839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.128213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-15T22:18:15.547063Z","caller":"traceutil/trace.go:171","msg":"trace[851101097] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"840.251681ms","start":"2026-09-15T22:18:14.706782Z","end":"2026-09-15T22:18:15.547034Z","steps":["trace[851101097] 'process raft request' (duration: 324.552354ms)","trace[851101097] 'compare' (duration: 514.881817ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:15.547192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:14.70677Z","time spent":"840.350594ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-15T22:18:15.547239Z","caller":"traceutil/trace.go:171","msg":"trace[1451926471] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:629; }","duration":"513.542304ms","start":"2026-09-15T22:18:15.033673Z","end":"2026-09-15T22:18:15.547216Z","steps":["trace[1451926471] 'read index received' (duration: 496.469066ms)","trace[1451926471] 'applied index is now lower than readState.Index' (duration: 17.071558ms)"],"step_count":2} {"level":"info","ts":"2026-09-15T22:18:15.547243Z","caller":"traceutil/trace.go:171","msg":"trace[1283362379] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"836.230102ms","start":"2026-09-15T22:18:14.710967Z","end":"2026-09-15T22:18:15.547197Z","steps":["trace[1283362379] 'process raft request' (duration: 836.021267ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:15.547365Z","caller":"traceutil/trace.go:171","msg":"trace[2110202743] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"834.800237ms","start":"2026-09-15T22:18:14.712553Z","end":"2026-09-15T22:18:15.547353Z","steps":["trace[2110202743] 'process raft request' (duration: 834.53331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:15.547414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:14.71095Z","time spent":"836.375085ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:18:15.54749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:14.712444Z","time spent":"834.966301ms","remote":"127.0.0.1:55612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-15T22:18:15.547421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.75908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xv28f\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-15T22:18:15.547635Z","caller":"traceutil/trace.go:171","msg":"trace[163840776] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xv28f; range_end:; response_count:1; response_revision:612; }","duration":"514.018066ms","start":"2026-09-15T22:18:15.033604Z","end":"2026-09-15T22:18:15.547623Z","steps":["trace[163840776] 'agreement among raft nodes before linearized reading' (duration: 513.77142ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:15.547564Z","caller":"traceutil/trace.go:171","msg":"trace[1091676949] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"781.592244ms","start":"2026-09-15T22:18:14.765944Z","end":"2026-09-15T22:18:15.547536Z","steps":["trace[1091676949] 'process raft request' (duration: 781.212634ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:15.547664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:15.033577Z","time spent":"514.079577ms","remote":"127.0.0.1:55540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-xv28f\" "} {"level":"warn","ts":"2026-09-15T22:18:15.54768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.455616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-15T22:18:15.547721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:14.765908Z","time spent":"781.763368ms","remote":"127.0.0.1:55856","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-15T22:18:15.547755Z","caller":"traceutil/trace.go:171","msg":"trace[1446379616] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"465.538208ms","start":"2026-09-15T22:18:15.082195Z","end":"2026-09-15T22:18:15.547733Z","steps":["trace[1446379616] 'agreement among raft nodes before linearized reading' (duration: 465.395555ms)"],"step_count":1} {"level":"warn","ts":"2026-09-15T22:18:15.547788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-15T22:18:15.082102Z","time spent":"465.680223ms","remote":"127.0.0.1:55344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-15T22:18:17.369328Z","caller":"traceutil/trace.go:171","msg":"trace[879127538] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"107.314406ms","start":"2026-09-15T22:18:17.261967Z","end":"2026-09-15T22:18:17.369281Z","steps":["trace[879127538] 'process raft request' (duration: 106.937746ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:32.991268Z","caller":"traceutil/trace.go:171","msg":"trace[787043676] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"162.137552ms","start":"2026-09-15T22:18:32.829085Z","end":"2026-09-15T22:18:32.991223Z","steps":["trace[787043676] 'read index received' (duration: 75.22686ms)","trace[787043676] 'applied index is now lower than readState.Index' (duration: 86.908302ms)"],"step_count":2} {"level":"warn","ts":"2026-09-15T22:18:32.991765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.626013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-15T22:18:32.991825Z","caller":"traceutil/trace.go:171","msg":"trace[1821318267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:776; }","duration":"162.786846ms","start":"2026-09-15T22:18:32.82902Z","end":"2026-09-15T22:18:32.991807Z","steps":["trace[1821318267] 'agreement among raft nodes before linearized reading' (duration: 162.367917ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:55.930999Z","caller":"traceutil/trace.go:171","msg":"trace[917148534] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"144.565352ms","start":"2026-09-15T22:18:55.786413Z","end":"2026-09-15T22:18:55.930978Z","steps":["trace[917148534] 'process raft request' (duration: 144.41674ms)"],"step_count":1} {"level":"info","ts":"2026-09-15T22:18:55.937689Z","caller":"traceutil/trace.go:171","msg":"trace[765874519] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"151.009632ms","start":"2026-09-15T22:18:55.786655Z","end":"2026-09-15T22:18:55.937665Z","steps":["trace[765874519] 'process raft request' (duration: 150.90048ms)"],"step_count":1}