{"level":"warn","ts":"2026-01-30T16:18:46.205019Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:18:46.2051Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.186: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.186:2380","--initial-cluster=instance=https://162.253.55.186:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.186:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.186: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-01-30T16:18:46.205198Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-30T16:18:46.205258Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-01-30T16:18:46.205279Z","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-01-30T16:18:46.206608Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"]} {"level":"info","ts":"2026-01-30T16:18:46.206739Z","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.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.186: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-01-30T16:18:46.21255Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.491298ms"} {"level":"info","ts":"2026-01-30T16:18:46.2215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"612d5169b08bb96b","cluster-id":"20ec8eafba602517"} {"level":"info","ts":"2026-01-30T16:18:46.221676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=()"} {"level":"info","ts":"2026-01-30T16:18:46.2217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 0"} {"level":"info","ts":"2026-01-30T16:18:46.221758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 612d5169b08bb96b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-30T16:18:46.221802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became follower at term 1"} {"level":"info","ts":"2026-01-30T16:18:46.223193Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"warn","ts":"2026-01-30T16:18:46.233775Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-30T16:18:46.2633Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-30T16:18:46.28672Z","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-01-30T16:18:46.292351Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"612d5169b08bb96b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-30T16:18:46.300265Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"612d5169b08bb96b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-30T16:18:46.300493Z","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-01-30T16:18:46.300586Z","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-01-30T16:18:46.300624Z","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-01-30T16:18:46.310602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b switched to configuration voters=(7002342510006352235)"} {"level":"info","ts":"2026-01-30T16:18:46.310701Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","added-peer-id":"612d5169b08bb96b","added-peer-peer-urls":["https://162.253.55.186:2380"]} {"level":"info","ts":"2026-01-30T16:18:46.311763Z","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-01-30T16:18:46.311926Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"612d5169b08bb96b","initial-advertise-peer-urls":["https://162.253.55.186:2380"],"listen-peer-urls":["https://162.253.55.186:2380"],"advertise-client-urls":["https://162.253.55.186:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.186:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-30T16:18:46.31196Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-30T16:18:46.312004Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-01-30T16:18:46.312011Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.186:2380"} {"level":"info","ts":"2026-01-30T16:18:46.9234Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b is starting a new election at term 1"} {"level":"info","ts":"2026-01-30T16:18:46.923439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became pre-candidate at term 1"} {"level":"info","ts":"2026-01-30T16:18:46.923452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgPreVoteResp from 612d5169b08bb96b at term 1"} {"level":"info","ts":"2026-01-30T16:18:46.923556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became candidate at term 2"} {"level":"info","ts":"2026-01-30T16:18:46.923569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b received MsgVoteResp from 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-01-30T16:18:46.923577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"612d5169b08bb96b became leader at term 2"} {"level":"info","ts":"2026-01-30T16:18:46.923584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 612d5169b08bb96b elected leader 612d5169b08bb96b at term 2"} {"level":"info","ts":"2026-01-30T16:18:46.945742Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:18:46.949438Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"612d5169b08bb96b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.186:2379]}","request-path":"/0/members/612d5169b08bb96b/attributes","cluster-id":"20ec8eafba602517","publish-timeout":"7s"} {"level":"info","ts":"2026-01-30T16:18:46.949649Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:18:46.949796Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-30T16:18:46.949833Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-30T16:18:46.950002Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"20ec8eafba602517","local-member-id":"612d5169b08bb96b","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:18:46.950086Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-30T16:18:46.950213Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:18:46.950268Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-30T16:18:46.951822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.186:2379"} {"level":"info","ts":"2026-01-30T16:18:46.952378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-30T16:19:11.802058Z","caller":"traceutil/trace.go:171","msg":"trace[1774263093] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"420.034244ms","start":"2026-01-30T16:19:11.382003Z","end":"2026-01-30T16:19:11.802037Z","steps":["trace[1774263093] 'process raft request' (duration: 419.544091ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:11.802652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:11.381977Z","time spent":"420.158384ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:19:15.260255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944310769111830,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-30T16:19:15.640404Z","caller":"traceutil/trace.go:171","msg":"trace[2135723037] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"926.916766ms","start":"2026-01-30T16:19:14.713207Z","end":"2026-01-30T16:19:15.640124Z","steps":["trace[2135723037] 'process raft request' (duration: 926.756855ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:15.640525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:14.713188Z","time spent":"927.274887ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:19:15.641756Z","caller":"traceutil/trace.go:171","msg":"trace[2085702517] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"926.360602ms","start":"2026-01-30T16:19:14.715363Z","end":"2026-01-30T16:19:15.641724Z","steps":["trace[2085702517] 'process raft request' (duration: 926.121211ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:15.641859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:14.715352Z","time spent":"926.447483ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:19:15.65668Z","caller":"traceutil/trace.go:171","msg":"trace[1738555518] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"897.225009ms","start":"2026-01-30T16:19:14.759438Z","end":"2026-01-30T16:19:15.656663Z","steps":["trace[1738555518] 'read index received' (duration: 880.734012ms)","trace[1738555518] 'applied index is now lower than readState.Index' (duration: 16.489807ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:19:15.656927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.46654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T16:19:15.656968Z","caller":"traceutil/trace.go:171","msg":"trace[520684986] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:466; }","duration":"897.54812ms","start":"2026-01-30T16:19:14.759408Z","end":"2026-01-30T16:19:15.656956Z","steps":["trace[520684986] 'agreement among raft nodes before linearized reading' (duration: 897.362989ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:15.656999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:14.759395Z","time spent":"897.59608ms","remote":"127.0.0.1:59812","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-01-30T16:19:15.657106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.458878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-01-30T16:19:15.657131Z","caller":"traceutil/trace.go:171","msg":"trace[967359850] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:466; }","duration":"605.522048ms","start":"2026-01-30T16:19:15.051601Z","end":"2026-01-30T16:19:15.657123Z","steps":["trace[967359850] 'agreement among raft nodes before linearized reading' (duration: 605.477138ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:15.65718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:15.051588Z","time spent":"605.586098ms","remote":"127.0.0.1:59732","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-01-30T16:19:15.657371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.680312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:19:15.657401Z","caller":"traceutil/trace.go:171","msg":"trace[1762406969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:466; }","duration":"413.740242ms","start":"2026-01-30T16:19:15.243652Z","end":"2026-01-30T16:19:15.657392Z","steps":["trace[1762406969] 'agreement among raft nodes before linearized reading' (duration: 413.696441ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:15.657452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:15.243634Z","time spent":"413.812492ms","remote":"127.0.0.1:59542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:19:20.897796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24218875s","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-01-30T16:19:20.897848Z","caller":"traceutil/trace.go:171","msg":"trace[814716789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"1.24224739s","start":"2026-01-30T16:19:19.655587Z","end":"2026-01-30T16:19:20.897835Z","steps":["trace[814716789] 'range keys from in-memory index tree' (duration: 1.242078378s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:20.897873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:19.655565Z","time spent":"1.242302181s","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T16:19:20.898007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242487562s","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-01-30T16:19:20.898025Z","caller":"traceutil/trace.go:171","msg":"trace[802550571] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"1.242539232s","start":"2026-01-30T16:19:19.655479Z","end":"2026-01-30T16:19:20.898018Z","steps":["trace[802550571] 'range keys from in-memory index tree' (duration: 1.242336881s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:20.89804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:19.655461Z","time spent":"1.242576173s","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T16:19:20.898136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211935897s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T16:19:20.898153Z","caller":"traceutil/trace.go:171","msg":"trace[1006393392] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"1.211985097s","start":"2026-01-30T16:19:19.686161Z","end":"2026-01-30T16:19:20.898146Z","steps":["trace[1006393392] 'range keys from in-memory index tree' (duration: 1.211847395s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:20.898167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:19.686142Z","time spent":"1.212022047s","remote":"127.0.0.1:59812","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-01-30T16:19:20.898388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065162569s","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-01-30T16:19:20.898406Z","caller":"traceutil/trace.go:171","msg":"trace[1703322048] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"1.06522297s","start":"2026-01-30T16:19:19.833177Z","end":"2026-01-30T16:19:20.8984Z","steps":["trace[1703322048] 'range keys from in-memory index tree' (duration: 1.065068208s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:20.898424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:19.833157Z","time spent":"1.06526385s","remote":"127.0.0.1:59812","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-01-30T16:19:20.8986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"891.761969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.186\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-30T16:19:20.898617Z","caller":"traceutil/trace.go:171","msg":"trace[1061360075] range","detail":"{range_begin:/registry/masterleases/162.253.55.186; range_end:; response_count:1; response_revision:496; }","duration":"891.809709ms","start":"2026-01-30T16:19:20.006803Z","end":"2026-01-30T16:19:20.898612Z","steps":["trace[1061360075] 'range keys from in-memory index tree' (duration: 891.693188ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:20.898633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:20.006784Z","time spent":"891.844629ms","remote":"127.0.0.1:59598","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.186\" "} {"level":"info","ts":"2026-01-30T16:19:22.604696Z","caller":"traceutil/trace.go:171","msg":"trace[819532098] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"354.732097ms","start":"2026-01-30T16:19:22.249949Z","end":"2026-01-30T16:19:22.604681Z","steps":["trace[819532098] 'process raft request' (duration: 354.643401ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:22.604817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:22.249937Z","time spent":"354.831503ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-30T16:19:23.134395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.085968ms","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-01-30T16:19:23.134453Z","caller":"traceutil/trace.go:171","msg":"trace[2082515847] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:502; }","duration":"218.153411ms","start":"2026-01-30T16:19:22.91628Z","end":"2026-01-30T16:19:23.134433Z","steps":["trace[2082515847] 'range keys from in-memory index tree' (duration: 217.997933ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:23.134704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.061253ms","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-01-30T16:19:23.134719Z","caller":"traceutil/trace.go:171","msg":"trace[1311845945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"223.097515ms","start":"2026-01-30T16:19:22.911616Z","end":"2026-01-30T16:19:23.134714Z","steps":["trace[1311845945] 'range keys from in-memory index tree' (duration: 222.99857ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:23.134792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.097001ms","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-01-30T16:19:23.134804Z","caller":"traceutil/trace.go:171","msg":"trace[2045529848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"219.133884ms","start":"2026-01-30T16:19:22.915667Z","end":"2026-01-30T16:19:23.1348Z","steps":["trace[2045529848] 'range keys from in-memory index tree' (duration: 219.039048ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:23.135037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.673229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T16:19:23.135052Z","caller":"traceutil/trace.go:171","msg":"trace[1036575190] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:502; }","duration":"218.7117ms","start":"2026-01-30T16:19:22.916335Z","end":"2026-01-30T16:19:23.135047Z","steps":["trace[1036575190] 'range keys from in-memory index tree' (duration: 218.611596ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:19:23.241599Z","caller":"traceutil/trace.go:171","msg":"trace[2073653009] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"100.589684ms","start":"2026-01-30T16:19:23.140985Z","end":"2026-01-30T16:19:23.241575Z","steps":["trace[2073653009] 'process raft request' (duration: 99.58545ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:25.671109Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944310769111925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:19:26.172229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13360944310769111925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-30T16:19:26.457465Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.665820084s","expected-duration":"1s"} {"level":"info","ts":"2026-01-30T16:19:26.45776Z","caller":"traceutil/trace.go:171","msg":"trace[637388820] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"2.666182813s","start":"2026-01-30T16:19:23.791559Z","end":"2026-01-30T16:19:26.457742Z","steps":["trace[637388820] 'process raft request' (duration: 2.666087028s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.457847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:23.791528Z","time spent":"2.666264667s","remote":"127.0.0.1:59746","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-30T16:19:26.93421Z","caller":"traceutil/trace.go:171","msg":"trace[1985883011] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:525; }","duration":"1.76366966s","start":"2026-01-30T16:19:25.170525Z","end":"2026-01-30T16:19:26.934195Z","steps":["trace[1985883011] 'read index received' (duration: 1.287700484s)","trace[1985883011] 'applied index is now lower than readState.Index' (duration: 475.968176ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:19:26.934379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763838139s","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-01-30T16:19:26.934402Z","caller":"traceutil/trace.go:171","msg":"trace[730826551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"1.763895881s","start":"2026-01-30T16:19:25.1705Z","end":"2026-01-30T16:19:26.934396Z","steps":["trace[730826551] 'agreement among raft nodes before linearized reading' (duration: 1.763786346s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.934421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:25.170486Z","time spent":"1.763931023s","remote":"127.0.0.1:59812","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-01-30T16:19:26.934586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.69216547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:19:26.934602Z","caller":"traceutil/trace.go:171","msg":"trace[721914931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"1.692204652s","start":"2026-01-30T16:19:25.242394Z","end":"2026-01-30T16:19:26.934599Z","steps":["trace[721914931] 'agreement among raft nodes before linearized reading' (duration: 1.69217641s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.934614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:25.242382Z","time spent":"1.692229413s","remote":"127.0.0.1:59542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-30T16:19:26.934726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683191538s","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-01-30T16:19:26.934745Z","caller":"traceutil/trace.go:171","msg":"trace[716824416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.683229761s","start":"2026-01-30T16:19:25.25151Z","end":"2026-01-30T16:19:26.93474Z","steps":["trace[716824416] 'agreement among raft nodes before linearized reading' (duration: 1.683178808s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.934758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:25.251503Z","time spent":"1.683252171s","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-30T16:19:26.934907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683908455s","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-01-30T16:19:26.934924Z","caller":"traceutil/trace.go:171","msg":"trace[208928586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"1.683940277s","start":"2026-01-30T16:19:25.25098Z","end":"2026-01-30T16:19:26.93492Z","steps":["trace[208928586] 'agreement among raft nodes before linearized reading' (duration: 1.683895945s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.934937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:25.250972Z","time spent":"1.683961398s","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-30T16:19:26.935011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.689445689s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-30T16:19:26.935027Z","caller":"traceutil/trace.go:171","msg":"trace[1180656312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"1.689471281s","start":"2026-01-30T16:19:25.245552Z","end":"2026-01-30T16:19:26.935024Z","steps":["trace[1180656312] 'agreement among raft nodes before linearized reading' (duration: 1.689434629s)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:26.935042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:25.245547Z","time spent":"1.689489632s","remote":"127.0.0.1:59812","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":"info","ts":"2026-01-30T16:19:29.596369Z","caller":"traceutil/trace.go:171","msg":"trace[1515699931] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"509.752304ms","start":"2026-01-30T16:19:29.0866Z","end":"2026-01-30T16:19:29.596353Z","steps":["trace[1515699931] 'process raft request' (duration: 509.640698ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:29.596468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:29.086592Z","time spent":"509.825007ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-30T16:19:29.596617Z","caller":"traceutil/trace.go:171","msg":"trace[1923118701] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:546; }","duration":"355.215496ms","start":"2026-01-30T16:19:29.241395Z","end":"2026-01-30T16:19:29.596611Z","steps":["trace[1923118701] 'read index received' (duration: 355.213086ms)","trace[1923118701] 'applied index is now lower than readState.Index' (duration: 2µs)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:19:29.596667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.267149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-30T16:19:29.596681Z","caller":"traceutil/trace.go:171","msg":"trace[807831321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"355.3116ms","start":"2026-01-30T16:19:29.241365Z","end":"2026-01-30T16:19:29.596677Z","steps":["trace[807831321] 'agreement among raft nodes before linearized reading' (duration: 355.266838ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:29.596701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-30T16:19:29.241351Z","time spent":"355.347583ms","remote":"127.0.0.1:59542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-30T16:19:31.21394Z","caller":"traceutil/trace.go:171","msg":"trace[1184209426] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"140.375818ms","start":"2026-01-30T16:19:31.073545Z","end":"2026-01-30T16:19:31.213921Z","steps":["trace[1184209426] 'process raft request' (duration: 63.600619ms)","trace[1184209426] 'compare' (duration: 76.59131ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:19:31.214336Z","caller":"traceutil/trace.go:171","msg":"trace[1176817414] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"120.390886ms","start":"2026-01-30T16:19:31.09394Z","end":"2026-01-30T16:19:31.214331Z","steps":["trace[1176817414] 'process raft request' (duration: 120.303482ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:19:34.135507Z","caller":"traceutil/trace.go:171","msg":"trace[1963783888] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"102.180904ms","start":"2026-01-30T16:19:34.033293Z","end":"2026-01-30T16:19:34.135473Z","steps":["trace[1963783888] 'process raft request' (duration: 49.767817ms)","trace[1963783888] 'compare' (duration: 52.088842ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:19:34.136312Z","caller":"traceutil/trace.go:171","msg":"trace[2085822935] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"102.480568ms","start":"2026-01-30T16:19:34.033819Z","end":"2026-01-30T16:19:34.1363Z","steps":["trace[2085822935] 'process raft request' (duration: 101.513553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-30T16:19:34.398196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.284294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-30T16:19:34.398297Z","caller":"traceutil/trace.go:171","msg":"trace[1162277071] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:619; }","duration":"195.760612ms","start":"2026-01-30T16:19:34.202525Z","end":"2026-01-30T16:19:34.398286Z","steps":["trace[1162277071] 'read index received' (duration: 70.179664ms)","trace[1162277071] 'applied index is now lower than readState.Index' (duration: 125.580068ms)"],"step_count":2} {"level":"warn","ts":"2026-01-30T16:19:34.398454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.92536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-01-30T16:19:34.398478Z","caller":"traceutil/trace.go:171","msg":"trace[1285400300] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:603; }","duration":"195.978082ms","start":"2026-01-30T16:19:34.202493Z","end":"2026-01-30T16:19:34.398471Z","steps":["trace[1285400300] 'agreement among raft nodes before linearized reading' (duration: 195.827425ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:19:34.398691Z","caller":"traceutil/trace.go:171","msg":"trace[949581473] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"251.966393ms","start":"2026-01-30T16:19:34.146715Z","end":"2026-01-30T16:19:34.398681Z","steps":["trace[949581473] 'process raft request' (duration: 126.05056ms)","trace[949581473] 'compare' (duration: 125.123157ms)"],"step_count":2} {"level":"info","ts":"2026-01-30T16:19:34.555671Z","caller":"traceutil/trace.go:171","msg":"trace[725157525] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"142.555857ms","start":"2026-01-30T16:19:34.412951Z","end":"2026-01-30T16:19:34.555507Z","steps":["trace[725157525] 'process raft request' (duration: 142.479664ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:19:34.556887Z","caller":"traceutil/trace.go:171","msg":"trace[1209428283] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"144.008844ms","start":"2026-01-30T16:19:34.412866Z","end":"2026-01-30T16:19:34.556875Z","steps":["trace[1209428283] 'process raft request' (duration: 142.123368ms)"],"step_count":1} {"level":"info","ts":"2026-01-30T16:19:34.558373Z","caller":"traceutil/trace.go:171","msg":"trace[1352298956] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"145.427749ms","start":"2026-01-30T16:19:34.412926Z","end":"2026-01-30T16:19:34.558354Z","steps":["trace[1352298956] 'process raft request' (duration: 142.40359ms)"],"step_count":1}