{"level":"warn","ts":"2026-02-08T22:09:49.560491Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T22:09:49.560598Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.227: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.227:2380","--initial-cluster=instance=https://162.253.55.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.227: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-02-08T22:09:49.560962Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-08T22:09:49.560978Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-08T22:09:49.561031Z","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-02-08T22:09:49.56163Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"]} {"level":"info","ts":"2026-02-08T22:09:49.561765Z","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":2,"max-cpu-available":2,"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.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.227: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-02-08T22:09:49.569092Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.022513ms"} {"level":"info","ts":"2026-02-08T22:09:49.580481Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9bd37ed2859641e3","cluster-id":"47d0f4f0a1c3b0a9"} {"level":"info","ts":"2026-02-08T22:09:49.584627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T22:09:49.584666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 0"} {"level":"info","ts":"2026-02-08T22:09:49.584675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9bd37ed2859641e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T22:09:49.584684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became follower at term 1"} {"level":"info","ts":"2026-02-08T22:09:49.584718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"warn","ts":"2026-02-08T22:09:49.596588Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T22:09:49.600208Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T22:09:49.601686Z","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-02-08T22:09:49.607692Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9bd37ed2859641e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T22:09:49.61752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9bd37ed2859641e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T22:09:49.617747Z","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-02-08T22:09:49.617777Z","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-02-08T22:09:49.617833Z","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-02-08T22:09:49.619857Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 switched to configuration voters=(11228457738614227427)"} {"level":"info","ts":"2026-02-08T22:09:49.626779Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","added-peer-id":"9bd37ed2859641e3","added-peer-peer-urls":["https://162.253.55.227:2380"]} {"level":"info","ts":"2026-02-08T22:09:49.629325Z","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-02-08T22:09:49.629493Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-08T22:09:49.629523Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.227:2380"} {"level":"info","ts":"2026-02-08T22:09:49.629702Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9bd37ed2859641e3","initial-advertise-peer-urls":["https://162.253.55.227:2380"],"listen-peer-urls":["https://162.253.55.227:2380"],"advertise-client-urls":["https://162.253.55.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T22:09:49.629747Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T22:09:49.685208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T22:09:49.685303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T22:09:49.685374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgPreVoteResp from 9bd37ed2859641e3 at term 1"} {"level":"info","ts":"2026-02-08T22:09:49.685438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became candidate at term 2"} {"level":"info","ts":"2026-02-08T22:09:49.685461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 received MsgVoteResp from 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-08T22:09:49.68548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9bd37ed2859641e3 became leader at term 2"} {"level":"info","ts":"2026-02-08T22:09:49.685531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9bd37ed2859641e3 elected leader 9bd37ed2859641e3 at term 2"} {"level":"info","ts":"2026-02-08T22:09:49.687159Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9bd37ed2859641e3","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.227:2379]}","request-path":"/0/members/9bd37ed2859641e3/attributes","cluster-id":"47d0f4f0a1c3b0a9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T22:09:49.687456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:49.687845Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:49.688013Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T22:09:49.688795Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T22:09:49.688812Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T22:09:49.696528Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.227:2379"} {"level":"info","ts":"2026-02-08T22:09:49.698136Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"47d0f4f0a1c3b0a9","local-member-id":"9bd37ed2859641e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:49.698241Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:49.699364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T22:09:49.705079Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-08T22:10:01.150222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.828836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-02-08T22:10:01.150297Z","caller":"traceutil/trace.go:171","msg":"trace[1989396742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:360; }","duration":"177.971097ms","start":"2026-02-08T22:10:00.97231Z","end":"2026-02-08T22:10:01.150281Z","steps":["trace[1989396742] 'range keys from in-memory index tree' (duration: 177.722196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:01.150461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.83968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-08T22:10:01.150513Z","caller":"traceutil/trace.go:171","msg":"trace[1209466778] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:360; }","duration":"127.94052ms","start":"2026-02-08T22:10:01.02256Z","end":"2026-02-08T22:10:01.1505Z","steps":["trace[1209466778] 'range keys from in-memory index tree' (duration: 127.764479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:06.976023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810227880858566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:07.353102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.807209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T22:10:07.353162Z","caller":"traceutil/trace.go:171","msg":"trace[1481723306] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"877.626077ms","start":"2026-02-08T22:10:06.475525Z","end":"2026-02-08T22:10:07.353151Z","steps":["trace[1481723306] 'read index received' (duration: 647.474426ms)","trace[1481723306] 'applied index is now lower than readState.Index' (duration: 230.150991ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:07.353202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.684617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:07.353214Z","caller":"traceutil/trace.go:171","msg":"trace[827487866] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"877.732558ms","start":"2026-02-08T22:10:06.475477Z","end":"2026-02-08T22:10:07.35321Z","steps":["trace[827487866] 'agreement among raft nodes before linearized reading' (duration: 877.699727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:07.353253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:06.475448Z","time spent":"877.778878ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:10:07.353397Z","caller":"traceutil/trace.go:171","msg":"trace[603219078] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"948.688842ms","start":"2026-02-08T22:10:06.404701Z","end":"2026-02-08T22:10:07.35339Z","steps":["trace[603219078] 'process raft request' (duration: 718.317769ms)","trace[603219078] 'compare' (duration: 229.521527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:07.353425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:06.404677Z","time spent":"948.732992ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:18.391923Z","caller":"traceutil/trace.go:171","msg":"trace[2123095065] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"284.09818ms","start":"2026-02-08T22:10:18.107806Z","end":"2026-02-08T22:10:18.391904Z","steps":["trace[2123095065] 'process raft request' (duration: 284.00319ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.657346Z","caller":"traceutil/trace.go:171","msg":"trace[831724234] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"214.020435ms","start":"2026-02-08T22:10:18.443309Z","end":"2026-02-08T22:10:18.657329Z","steps":["trace[831724234] 'process raft request' (duration: 213.940385ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:18.657635Z","caller":"traceutil/trace.go:171","msg":"trace[266298402] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:522; }","duration":"191.890364ms","start":"2026-02-08T22:10:18.465737Z","end":"2026-02-08T22:10:18.657627Z","steps":["trace[266298402] 'read index received' (duration: 191.888394ms)","trace[266298402] 'applied index is now lower than readState.Index' (duration: 1.64µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:18.657714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.967674ms","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-02-08T22:10:18.65773Z","caller":"traceutil/trace.go:171","msg":"trace[776323443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"192.020404ms","start":"2026-02-08T22:10:18.465703Z","end":"2026-02-08T22:10:18.657724Z","steps":["trace[776323443] 'agreement among raft nodes before linearized reading' (duration: 191.951964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:18.663149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.151868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:18.663176Z","caller":"traceutil/trace.go:171","msg":"trace[42276111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"189.196239ms","start":"2026-02-08T22:10:18.473973Z","end":"2026-02-08T22:10:18.663169Z","steps":["trace[42276111] 'agreement among raft nodes before linearized reading' (duration: 189.145928ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:19.751808Z","caller":"traceutil/trace.go:171","msg":"trace[889671074] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"338.661781ms","start":"2026-02-08T22:10:19.413121Z","end":"2026-02-08T22:10:19.751783Z","steps":["trace[889671074] 'process raft request' (duration: 338.46151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:19.75194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:19.41311Z","time spent":"338.761722ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:23.973516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810227880858762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:24.474309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810227880858762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:24.97485Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810227880858762,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T22:10:24.983385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.29684472s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T22:10:25.076394Z","caller":"traceutil/trace.go:171","msg":"trace[1286363896] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"2.389898452s","start":"2026-02-08T22:10:22.686477Z","end":"2026-02-08T22:10:25.076376Z","steps":["trace[1286363896] 'process raft request' (duration: 2.297132552s)","trace[1286363896] 'compare' (duration: 92.153036ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:25.076488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:22.686452Z","time spent":"2.389986782s","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:25.076486Z","caller":"traceutil/trace.go:171","msg":"trace[1264716252] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:554; }","duration":"1.603469042s","start":"2026-02-08T22:10:23.472997Z","end":"2026-02-08T22:10:25.076466Z","steps":["trace[1264716252] 'read index received' (duration: 1.510627963s)","trace[1264716252] 'applied index is now lower than readState.Index' (duration: 92.840149ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:25.076607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603610893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:25.076627Z","caller":"traceutil/trace.go:171","msg":"trace[1569825085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.603652743s","start":"2026-02-08T22:10:23.472968Z","end":"2026-02-08T22:10:25.076621Z","steps":["trace[1569825085] 'agreement among raft nodes before linearized reading' (duration: 1.603623183s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.076641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:23.472954Z","time spent":"1.603684973s","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-08T22:10:25.076683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484314426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.227\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-08T22:10:25.076712Z","caller":"traceutil/trace.go:171","msg":"trace[227703682] range","detail":"{range_begin:/registry/masterleases/162.253.55.227; range_end:; response_count:1; response_revision:545; }","duration":"1.484352577s","start":"2026-02-08T22:10:23.592349Z","end":"2026-02-08T22:10:25.076702Z","steps":["trace[227703682] 'agreement among raft nodes before linearized reading' (duration: 1.484292706s)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:25.076709Z","caller":"traceutil/trace.go:171","msg":"trace[118284776] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"2.275141068s","start":"2026-02-08T22:10:22.801562Z","end":"2026-02-08T22:10:25.076703Z","steps":["trace[118284776] 'process raft request' (duration: 2.274741115s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.076736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:23.592306Z","time spent":"1.484425667s","remote":"127.0.0.1:57644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.227\" "} {"level":"warn","ts":"2026-02-08T22:10:25.076753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:22.801545Z","time spent":"2.275191038s","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:25.076919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.64495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-08T22:10:25.076952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.955717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-08T22:10:25.076928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31139121s","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-02-08T22:10:25.076971Z","caller":"traceutil/trace.go:171","msg":"trace[1850900408] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"669.996347ms","start":"2026-02-08T22:10:24.406969Z","end":"2026-02-08T22:10:25.076966Z","steps":["trace[1850900408] 'agreement among raft nodes before linearized reading' (duration: 669.968307ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:25.076977Z","caller":"traceutil/trace.go:171","msg":"trace[28621869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"1.31147125s","start":"2026-02-08T22:10:23.765498Z","end":"2026-02-08T22:10:25.07697Z","steps":["trace[28621869] 'agreement among raft nodes before linearized reading' (duration: 1.31141014s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.076984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.406956Z","time spent":"670.024957ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T22:10:25.076997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:23.765483Z","time spent":"1.311508491s","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-08T22:10:25.076952Z","caller":"traceutil/trace.go:171","msg":"trace[227352683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"401.71237ms","start":"2026-02-08T22:10:24.67523Z","end":"2026-02-08T22:10:25.076942Z","steps":["trace[227352683] 'agreement among raft nodes before linearized reading' (duration: 401.66076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:25.077048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:24.67521Z","time spent":"401.833761ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-08T22:10:27.974431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4747810227880858794,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-08T22:10:28.282119Z","caller":"traceutil/trace.go:171","msg":"trace[936150800] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:569; }","duration":"808.105491ms","start":"2026-02-08T22:10:27.47393Z","end":"2026-02-08T22:10:28.282035Z","steps":["trace[936150800] 'read index received' (duration: 807.95795ms)","trace[936150800] 'applied index is now lower than readState.Index' (duration: 146.751µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:28.282229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.287182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:28.282251Z","caller":"traceutil/trace.go:171","msg":"trace[584476051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"808.352532ms","start":"2026-02-08T22:10:27.473891Z","end":"2026-02-08T22:10:28.282243Z","steps":["trace[584476051] 'agreement among raft nodes before linearized reading' (duration: 808.280652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:28.282276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:27.473869Z","time spent":"808.400562ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:10:28.282544Z","caller":"traceutil/trace.go:171","msg":"trace[1829486568] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"842.096963ms","start":"2026-02-08T22:10:27.440437Z","end":"2026-02-08T22:10:28.282534Z","steps":["trace[1829486568] 'process raft request' (duration: 841.50722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:28.282608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:27.440423Z","time spent":"842.143363ms","remote":"127.0.0.1:57802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T22:10:28.942054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:28.341196Z","time spent":"600.850832ms","remote":"127.0.0.1:43750","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-08T22:10:29.110557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.482201ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-02-08T22:10:30.215963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.679698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:30.216032Z","caller":"traceutil/trace.go:171","msg":"trace[1611684824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"742.772778ms","start":"2026-02-08T22:10:29.473238Z","end":"2026-02-08T22:10:30.216011Z","steps":["trace[1611684824] 'range keys from in-memory index tree' (duration: 742.618177ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:30.21606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:29.473223Z","time spent":"742.830738ms","remote":"127.0.0.1:57628","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-08T22:10:31.609797Z","caller":"traceutil/trace.go:171","msg":"trace[1491644236] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"161.260264ms","start":"2026-02-08T22:10:31.4485Z","end":"2026-02-08T22:10:31.60976Z","steps":["trace[1491644236] 'process raft request' (duration: 161.179693ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:31.611909Z","caller":"traceutil/trace.go:171","msg":"trace[1231016359] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"138.613172ms","start":"2026-02-08T22:10:31.473288Z","end":"2026-02-08T22:10:31.611901Z","steps":["trace[1231016359] 'read index received' (duration: 136.469698ms)","trace[1231016359] 'applied index is now lower than readState.Index' (duration: 2.142624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T22:10:31.611967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.668152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T22:10:31.611982Z","caller":"traceutil/trace.go:171","msg":"trace[753060701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"138.695383ms","start":"2026-02-08T22:10:31.473282Z","end":"2026-02-08T22:10:31.611978Z","steps":["trace[753060701] 'agreement among raft nodes before linearized reading' (duration: 138.645922ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:31.612143Z","caller":"traceutil/trace.go:171","msg":"trace[684528068] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"162.019962ms","start":"2026-02-08T22:10:31.450118Z","end":"2026-02-08T22:10:31.612138Z","steps":["trace[684528068] 'process raft request' (duration: 161.725989ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:35.807723Z","caller":"traceutil/trace.go:171","msg":"trace[636003468] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"166.52142ms","start":"2026-02-08T22:10:35.64118Z","end":"2026-02-08T22:10:35.807701Z","steps":["trace[636003468] 'process raft request' (duration: 166.384232ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:57.125744Z","caller":"traceutil/trace.go:171","msg":"trace[839043745] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"551.816804ms","start":"2026-02-08T22:10:56.573554Z","end":"2026-02-08T22:10:57.125371Z","steps":["trace[839043745] 'process raft request' (duration: 551.661068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T22:10:57.12587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T22:10:56.573526Z","time spent":"552.272303ms","remote":"127.0.0.1:57876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T22:10:57.172454Z","caller":"traceutil/trace.go:171","msg":"trace[2017679305] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"186.675653ms","start":"2026-02-08T22:10:56.985752Z","end":"2026-02-08T22:10:57.172427Z","steps":["trace[2017679305] 'process raft request' (duration: 186.486985ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:10:57.552536Z","caller":"traceutil/trace.go:171","msg":"trace[508119872] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"181.093849ms","start":"2026-02-08T22:10:57.371422Z","end":"2026-02-08T22:10:57.552516Z","steps":["trace[508119872] 'process raft request' (duration: 176.838547ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T22:11:57.898518Z","caller":"traceutil/trace.go:171","msg":"trace[1690827315] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"101.854682ms","start":"2026-02-08T22:11:57.796647Z","end":"2026-02-08T22:11:57.898501Z","steps":["trace[1690827315] 'process raft request' (duration: 92.213689ms)"],"step_count":1}