{"level":"warn","ts":"2026-02-08T21:37:14.802485Z","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-08T21:37:14.80258Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-08T21:37:14.802655Z","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-08T21:37:14.802663Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-08T21:37:14.802698Z","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-08T21:37:14.80336Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-02-08T21:37:14.803485Z","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://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-08T21:37:14.807884Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.033843ms"} {"level":"info","ts":"2026-02-08T21:37:14.820247Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-02-08T21:37:14.820401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-02-08T21:37:14.820422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-02-08T21:37:14.8205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-08T21:37:14.820568Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-02-08T21:37:14.82071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-02-08T21:37:14.82807Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-08T21:37:14.831912Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-08T21:37:14.835328Z","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-08T21:37:14.848297Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-08T21:37:14.84865Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-08T21:37:14.848753Z","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-08T21:37:14.848774Z","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-08T21:37:14.848806Z","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-08T21:37:14.851547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-02-08T21:37:14.852156Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-02-08T21:37:14.862319Z","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-08T21:37:14.862621Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-08T21:37:14.862634Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-02-08T21:37:14.867797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-08T21:37:14.867781Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-08T21:37:15.32212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-02-08T21:37:15.32216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-08T21:37:15.322189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-02-08T21:37:15.322201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-02-08T21:37:15.322386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-08T21:37:15.322397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-02-08T21:37:15.322404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-02-08T21:37:15.358202Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:37:15.389357Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-02-08T21:37:15.389495Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:37:15.389801Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-08T21:37:15.39145Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-08T21:37:15.391558Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-08T21:37:15.391709Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-08T21:37:15.391861Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:37:15.392014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:37:15.392093Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-08T21:37:15.457976Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-02-08T21:37:19.094647Z","caller":"traceutil/trace.go:171","msg":"trace[2034627924] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"103.264764ms","start":"2026-02-08T21:37:18.991341Z","end":"2026-02-08T21:37:19.094606Z","steps":["trace[2034627924] 'process raft request' (duration: 67.437742ms)","trace[2034627924] 'compare' (duration: 35.411298ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:26.751134Z","caller":"traceutil/trace.go:171","msg":"trace[420978573] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"170.332615ms","start":"2026-02-08T21:37:26.580749Z","end":"2026-02-08T21:37:26.751082Z","steps":["trace[420978573] 'process raft request' (duration: 170.031383ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:32.521652Z","caller":"traceutil/trace.go:171","msg":"trace[156737138] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"205.025699ms","start":"2026-02-08T21:37:32.316605Z","end":"2026-02-08T21:37:32.521631Z","steps":["trace[156737138] 'process raft request' (duration: 201.261382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:33.546824Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018309358929827,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:37:33.760883Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.231944614s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-08T21:37:33.978857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.022002ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-08T21:37:33.979049Z","caller":"traceutil/trace.go:171","msg":"trace[1527441900] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"1.450168254s","start":"2026-02-08T21:37:32.528866Z","end":"2026-02-08T21:37:33.979034Z","steps":["trace[1527441900] 'process raft request' (duration: 1.232168225s)","trace[1527441900] 'compare' (duration: 215.28452ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:33.979115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:32.528855Z","time spent":"1.450226374s","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5245,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-08T21:37:33.978994Z","caller":"traceutil/trace.go:171","msg":"trace[732328488] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"932.922977ms","start":"2026-02-08T21:37:33.046054Z","end":"2026-02-08T21:37:33.978977Z","steps":["trace[732328488] 'read index received' (duration: 714.926989ms)","trace[732328488] 'applied index is now lower than readState.Index' (duration: 217.994518ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:33.979516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.446381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T21:37:33.979549Z","caller":"traceutil/trace.go:171","msg":"trace[2088611323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:418; }","duration":"933.528241ms","start":"2026-02-08T21:37:33.046011Z","end":"2026-02-08T21:37:33.979539Z","steps":["trace[2088611323] 'agreement among raft nodes before linearized reading' (duration: 933.448031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:33.97958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:33.04599Z","time spent":"933.582861ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:37:33.98278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.020826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4034"} {"level":"info","ts":"2026-02-08T21:37:33.982809Z","caller":"traceutil/trace.go:171","msg":"trace[600738614] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:418; }","duration":"591.076077ms","start":"2026-02-08T21:37:33.391726Z","end":"2026-02-08T21:37:33.982802Z","steps":["trace[600738614] 'agreement among raft nodes before linearized reading' (duration: 591.023786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:33.982827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:33.391711Z","time spent":"591.112697ms","remote":"127.0.0.1:56320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4056,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-08T21:37:34.15668Z","caller":"traceutil/trace.go:171","msg":"trace[474459825] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"165.975287ms","start":"2026-02-08T21:37:33.99069Z","end":"2026-02-08T21:37:34.156666Z","steps":["trace[474459825] 'process raft request' (duration: 151.485867ms)","trace[474459825] 'compare' (duration: 13.722445ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:34.157462Z","caller":"traceutil/trace.go:171","msg":"trace[1127502820] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"165.291383ms","start":"2026-02-08T21:37:33.990739Z","end":"2026-02-08T21:37:34.156031Z","steps":["trace[1127502820] 'read index received' (duration: 151.532608ms)","trace[1127502820] 'applied index is now lower than readState.Index' (duration: 13.757735ms)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:34.157588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.833194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-08T21:37:34.157791Z","caller":"traceutil/trace.go:171","msg":"trace[1752773574] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:419; }","duration":"167.046956ms","start":"2026-02-08T21:37:33.990735Z","end":"2026-02-08T21:37:34.157782Z","steps":["trace[1752773574] 'agreement among raft nodes before linearized reading' (duration: 166.794834ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:34.25305Z","caller":"traceutil/trace.go:171","msg":"trace[1956228786] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"257.107298ms","start":"2026-02-08T21:37:33.995923Z","end":"2026-02-08T21:37:34.25303Z","steps":["trace[1956228786] 'process raft request' (duration: 257.060587ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:34.253629Z","caller":"traceutil/trace.go:171","msg":"trace[506931062] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"260.4578ms","start":"2026-02-08T21:37:33.99316Z","end":"2026-02-08T21:37:34.253618Z","steps":["trace[506931062] 'process raft request' (duration: 259.688895ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:34.253865Z","caller":"traceutil/trace.go:171","msg":"trace[1836049685] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"260.658942ms","start":"2026-02-08T21:37:33.993195Z","end":"2026-02-08T21:37:34.253854Z","steps":["trace[1836049685] 'process raft request' (duration: 259.748676ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:34.276389Z","caller":"traceutil/trace.go:171","msg":"trace[1185973739] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"113.257592ms","start":"2026-02-08T21:37:34.163111Z","end":"2026-02-08T21:37:34.276369Z","steps":["trace[1185973739] 'process raft request' (duration: 110.14799ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:54.65159Z","caller":"traceutil/trace.go:171","msg":"trace[942687193] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"216.151535ms","start":"2026-02-08T21:37:54.435424Z","end":"2026-02-08T21:37:54.651575Z","steps":["trace[942687193] 'read index received' (duration: 215.970324ms)","trace[942687193] 'applied index is now lower than readState.Index' (duration: 180.411µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:54.651694Z","caller":"traceutil/trace.go:171","msg":"trace[139991726] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"400.623544ms","start":"2026-02-08T21:37:54.251063Z","end":"2026-02-08T21:37:54.651687Z","steps":["trace[139991726] 'process raft request' (duration: 400.416372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:54.651768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:54.251053Z","time spent":"400.658695ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:54.651819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.626431ms","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-08T21:37:54.651873Z","caller":"traceutil/trace.go:171","msg":"trace[2121834058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"158.729131ms","start":"2026-02-08T21:37:54.493131Z","end":"2026-02-08T21:37:54.65186Z","steps":["trace[2121834058] 'agreement among raft nodes before linearized reading' (duration: 158.634001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:54.651982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.558819ms","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-08T21:37:54.651998Z","caller":"traceutil/trace.go:171","msg":"trace[1868692335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"216.610709ms","start":"2026-02-08T21:37:54.435382Z","end":"2026-02-08T21:37:54.651993Z","steps":["trace[1868692335] 'agreement among raft nodes before linearized reading' (duration: 216.582779ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:55.61166Z","caller":"traceutil/trace.go:171","msg":"trace[1043619254] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:574; }","duration":"911.933526ms","start":"2026-02-08T21:37:54.699709Z","end":"2026-02-08T21:37:55.611643Z","steps":["trace[1043619254] 'read index received' (duration: 46.284545ms)","trace[1043619254] 'applied index is now lower than readState.Index' (duration: 865.648301ms)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:55.611731Z","caller":"traceutil/trace.go:171","msg":"trace[620136205] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"954.500899ms","start":"2026-02-08T21:37:54.657224Z","end":"2026-02-08T21:37:55.611725Z","steps":["trace[620136205] 'process raft request' (duration: 954.395859ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:55.611735Z","caller":"traceutil/trace.go:171","msg":"trace[401937663] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"955.967068ms","start":"2026-02-08T21:37:54.655728Z","end":"2026-02-08T21:37:55.611695Z","steps":["trace[401937663] 'process raft request' (duration: 955.795887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:55.611804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:54.657209Z","time spent":"954.54067ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:55.611931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:54.655723Z","time spent":"956.138609ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:55.612075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.370128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-xmjpj\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-08T21:37:55.612099Z","caller":"traceutil/trace.go:171","msg":"trace[200200058] range","detail":"{range_begin:/registry/pods/kube-system/cilium-xmjpj; range_end:; response_count:1; response_revision:559; }","duration":"912.420349ms","start":"2026-02-08T21:37:54.699672Z","end":"2026-02-08T21:37:55.612092Z","steps":["trace[200200058] 'agreement among raft nodes before linearized reading' (duration: 912.305439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:55.612118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:54.699649Z","time spent":"912.46597ms","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16674,"request content":"key:\"/registry/pods/kube-system/cilium-xmjpj\" "} {"level":"warn","ts":"2026-02-08T21:37:55.659059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.0785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:55.659114Z","caller":"traceutil/trace.go:171","msg":"trace[1113347131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"473.1735ms","start":"2026-02-08T21:37:55.185928Z","end":"2026-02-08T21:37:55.659101Z","steps":["trace[1113347131] 'agreement among raft nodes before linearized reading' (duration: 473.084399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:55.659143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:55.185914Z","time spent":"473.2224ms","remote":"127.0.0.1:55868","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-02-08T21:37:56.160099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018309358930105,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:37:56.661133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018309358930105,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:37:57.161642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757018309358930105,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-08T21:37:57.338141Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.708110075s","expected-duration":"1s"} {"level":"info","ts":"2026-02-08T21:37:57.338399Z","caller":"traceutil/trace.go:171","msg":"trace[237078492] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"1.678498113s","start":"2026-02-08T21:37:55.659885Z","end":"2026-02-08T21:37:57.338383Z","steps":["trace[237078492] 'read index received' (duration: 1.678360172s)","trace[237078492] 'applied index is now lower than readState.Index' (duration: 137.201µs)"],"step_count":2} {"level":"info","ts":"2026-02-08T21:37:57.338647Z","caller":"traceutil/trace.go:171","msg":"trace[1274421707] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"1.714897496s","start":"2026-02-08T21:37:55.623742Z","end":"2026-02-08T21:37:57.33864Z","steps":["trace[1274421707] 'process raft request' (duration: 1.714544994s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:57.338722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:55.623717Z","time spent":"1.714954306s","remote":"127.0.0.1:56024","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-08T21:37:57.338844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678956636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:57.33887Z","caller":"traceutil/trace.go:171","msg":"trace[686983406] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"1.678999706s","start":"2026-02-08T21:37:55.659862Z","end":"2026-02-08T21:37:57.338862Z","steps":["trace[686983406] 'agreement among raft nodes before linearized reading' (duration: 1.678964106s)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:57.338887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:55.659851Z","time spent":"1.679032586s","remote":"127.0.0.1:55882","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-02-08T21:37:57.339023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.080152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-08T21:37:57.339045Z","caller":"traceutil/trace.go:171","msg":"trace[1045367622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"684.133772ms","start":"2026-02-08T21:37:56.654907Z","end":"2026-02-08T21:37:57.33904Z","steps":["trace[1045367622] 'agreement among raft nodes before linearized reading' (duration: 684.058551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:57.339062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:56.654887Z","time spent":"684.170902ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-08T21:37:57.33928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.415558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-08T21:37:57.339303Z","caller":"traceutil/trace.go:171","msg":"trace[146645101] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"590.462319ms","start":"2026-02-08T21:37:56.748836Z","end":"2026-02-08T21:37:57.339298Z","steps":["trace[146645101] 'agreement among raft nodes before linearized reading' (duration: 590.395499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-08T21:37:57.33932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-08T21:37:56.748823Z","time spent":"590.492919ms","remote":"127.0.0.1:56110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-08T21:37:59.309827Z","caller":"traceutil/trace.go:171","msg":"trace[1769189569] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"123.004385ms","start":"2026-02-08T21:37:59.186803Z","end":"2026-02-08T21:37:59.309808Z","steps":["trace[1769189569] 'read index received' (duration: 122.859234ms)","trace[1769189569] 'applied index is now lower than readState.Index' (duration: 144.301µs)"],"step_count":2} {"level":"warn","ts":"2026-02-08T21:37:59.309972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.143026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-08T21:37:59.31001Z","caller":"traceutil/trace.go:171","msg":"trace[457634384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"123.240176ms","start":"2026-02-08T21:37:59.18676Z","end":"2026-02-08T21:37:59.31Z","steps":["trace[457634384] 'agreement among raft nodes before linearized reading' (duration: 123.146965ms)"],"step_count":1} {"level":"info","ts":"2026-02-08T21:37:59.310302Z","caller":"traceutil/trace.go:171","msg":"trace[697961996] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"273.624038ms","start":"2026-02-08T21:37:59.036667Z","end":"2026-02-08T21:37:59.310291Z","steps":["trace[697961996] 'process raft request' (duration: 273.041523ms)"],"step_count":1}