{"level":"warn","ts":"2026-02-06T00:35:52.795559Z","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-06T00:35:52.795909Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.250: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.250:2380","--initial-cluster=instance=https://199.204.45.250:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.250:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.250: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-06T00:35:52.796297Z","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-06T00:35:52.796366Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.250:2380"]}
{"level":"info","ts":"2026-02-06T00:35:52.796449Z","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-06T00:35:52.802332Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"]}
{"level":"info","ts":"2026-02-06T00:35:52.802541Z","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.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.250: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-06T00:35:52.816469Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"13.615003ms"}
{"level":"info","ts":"2026-02-06T00:35:52.853618Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d98d5f2c752cc52d","cluster-id":"c785b4aaf4bd3b11"}
{"level":"info","ts":"2026-02-06T00:35:52.853681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=()"}
{"level":"info","ts":"2026-02-06T00:35:52.853702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 0"}
{"level":"info","ts":"2026-02-06T00:35:52.853713Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d98d5f2c752cc52d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"}
{"level":"info","ts":"2026-02-06T00:35:52.853721Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became follower at term 1"}
{"level":"info","ts":"2026-02-06T00:35:52.853756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"}
{"level":"warn","ts":"2026-02-06T00:35:52.998213Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"}
{"level":"info","ts":"2026-02-06T00:35:53.01294Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1}
{"level":"info","ts":"2026-02-06T00:35:53.074319Z","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-06T00:35:53.10179Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d98d5f2c752cc52d","local-server-version":"3.5.12","cluster-version":"to_be_decided"}
{"level":"info","ts":"2026-02-06T00:35:53.11515Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d98d5f2c752cc52d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"}
{"level":"info","ts":"2026-02-06T00:35:53.115286Z","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-06T00:35:53.115317Z","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-06T00:35:53.115327Z","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-06T00:35:53.120005Z","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-06T00:35:53.120128Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d98d5f2c752cc52d","initial-advertise-peer-urls":["https://199.204.45.250:2380"],"listen-peer-urls":["https://199.204.45.250:2380"],"advertise-client-urls":["https://199.204.45.250:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.250:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]}
{"level":"info","ts":"2026-02-06T00:35:53.120151Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"}
{"level":"info","ts":"2026-02-06T00:35:53.12022Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.250:2380"}
{"level":"info","ts":"2026-02-06T00:35:53.120232Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.250:2380"}
{"level":"info","ts":"2026-02-06T00:35:53.1274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d switched to configuration voters=(15676290522495632685)"}
{"level":"info","ts":"2026-02-06T00:35:53.127466Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","added-peer-id":"d98d5f2c752cc52d","added-peer-peer-urls":["https://199.204.45.250:2380"]}
{"level":"info","ts":"2026-02-06T00:35:53.953973Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d is starting a new election at term 1"}
{"level":"info","ts":"2026-02-06T00:35:53.954021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became pre-candidate at term 1"}
{"level":"info","ts":"2026-02-06T00:35:53.954042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgPreVoteResp from d98d5f2c752cc52d at term 1"}
{"level":"info","ts":"2026-02-06T00:35:53.954054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became candidate at term 2"}
{"level":"info","ts":"2026-02-06T00:35:53.954063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d received MsgVoteResp from d98d5f2c752cc52d at term 2"}
{"level":"info","ts":"2026-02-06T00:35:53.954071Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d98d5f2c752cc52d became leader at term 2"}
{"level":"info","ts":"2026-02-06T00:35:53.954081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d98d5f2c752cc52d elected leader d98d5f2c752cc52d at term 2"}
{"level":"info","ts":"2026-02-06T00:35:53.956818Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-06T00:35:53.957343Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d98d5f2c752cc52d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.250:2379]}","request-path":"/0/members/d98d5f2c752cc52d/attributes","cluster-id":"c785b4aaf4bd3b11","publish-timeout":"7s"}
{"level":"info","ts":"2026-02-06T00:35:53.958104Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-06T00:35:53.958417Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"}
{"level":"info","ts":"2026-02-06T00:35:53.958552Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c785b4aaf4bd3b11","local-member-id":"d98d5f2c752cc52d","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-06T00:35:53.958613Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-06T00:35:53.958634Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"}
{"level":"info","ts":"2026-02-06T00:35:53.959869Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.250:2379"}
{"level":"info","ts":"2026-02-06T00:35:53.96098Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"}
{"level":"info","ts":"2026-02-06T00:35:53.961002Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"}
{"level":"info","ts":"2026-02-06T00:35:53.962299Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"}
{"level":"info","ts":"2026-02-06T00:35:58.089833Z","caller":"traceutil/trace.go:171","msg":"trace[600982322] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"170.318954ms","start":"2026-02-06T00:35:57.919491Z","end":"2026-02-06T00:35:58.08981Z","steps":["trace[600982322] 'process raft request'  (duration: 170.184523ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:35:58.437565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.65651ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203209 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" mod_revision:0 > success:<request_put:<key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-bootstrap\" value_size:463 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-06T00:35:58.437941Z","caller":"traceutil/trace.go:171","msg":"trace[1673767219] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"189.356116ms","start":"2026-02-06T00:35:58.248495Z","end":"2026-02-06T00:35:58.437851Z","steps":["trace[1673767219] 'process raft request'  (duration: 66.917207ms)","trace[1673767219] 'compare'  (duration: 121.528768ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:35:58.790129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.561749ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203232 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" value_size:129 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-06T00:35:58.79022Z","caller":"traceutil/trace.go:171","msg":"trace[1258612808] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"134.265778ms","start":"2026-02-06T00:35:58.655938Z","end":"2026-02-06T00:35:58.790203Z","steps":["trace[1258612808] 'process raft request'  (duration: 23.572957ms)","trace[1258612808] 'compare'  (duration: 110.367767ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:35:59.457294Z","caller":"traceutil/trace.go:171","msg":"trace[250697666] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:307; }","duration":"139.457758ms","start":"2026-02-06T00:35:59.31782Z","end":"2026-02-06T00:35:59.457278Z","steps":["trace[250697666] 'read index received'  (duration: 79.416782ms)","trace[250697666] 'applied index is now lower than readState.Index'  (duration: 60.040216ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:35:59.457671Z","caller":"traceutil/trace.go:171","msg":"trace[1030999457] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"151.970314ms","start":"2026-02-06T00:35:59.305685Z","end":"2026-02-06T00:35:59.457655Z","steps":["trace[1030999457] 'process raft request'  (duration: 151.364766ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:35:59.457554Z","caller":"traceutil/trace.go:171","msg":"trace[81344135] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"151.464977ms","start":"2026-02-06T00:35:59.305637Z","end":"2026-02-06T00:35:59.457102Z","steps":["trace[81344135] 'process raft request'  (duration: 91.591632ms)","trace[81344135] 'compare'  (duration: 59.727083ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:35:59.458755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.916915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pvc-protection-controller\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:35:59.459599Z","caller":"traceutil/trace.go:171","msg":"trace[44449805] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pvc-protection-controller; range_end:; response_count:0; response_revision:301; }","duration":"141.003896ms","start":"2026-02-06T00:35:59.317788Z","end":"2026-02-06T00:35:59.458792Z","steps":["trace[44449805] 'agreement among raft nodes before linearized reading'  (duration: 140.894045ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:35:59.461041Z","caller":"traceutil/trace.go:171","msg":"trace[813150144] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"147.718215ms","start":"2026-02-06T00:35:59.313304Z","end":"2026-02-06T00:35:59.461022Z","steps":["trace[813150144] 'process raft request'  (duration: 143.923441ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:00.513799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.983779ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203335 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" mod_revision:0 > success:<request_put:<key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" value_size:127 >> failure:<>>","response":"size:16"}
{"level":"info","ts":"2026-02-06T00:36:00.51396Z","caller":"traceutil/trace.go:171","msg":"trace[495116998] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"194.040193ms","start":"2026-02-06T00:36:00.319886Z","end":"2026-02-06T00:36:00.513926Z","steps":["trace[495116998] 'process raft request'  (duration: 29.870922ms)","trace[495116998] 'compare'  (duration: 163.854447ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:00.543701Z","caller":"traceutil/trace.go:171","msg":"trace[1884270303] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"220.069861ms","start":"2026-02-06T00:36:00.323615Z","end":"2026-02-06T00:36:00.543685Z","steps":["trace[1884270303] 'process raft request'  (duration: 219.772648ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:00.748491Z","caller":"traceutil/trace.go:171","msg":"trace[1505042981] linearizableReadLoop","detail":"{readStateIndex:330; appliedIndex:329; }","duration":"168.735014ms","start":"2026-02-06T00:36:00.57973Z","end":"2026-02-06T00:36:00.748465Z","steps":["trace[1505042981] 'read index received'  (duration: 111.151724ms)","trace[1505042981] 'applied index is now lower than readState.Index'  (duration: 57.58227ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:00.748968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.21132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:00.749127Z","caller":"traceutil/trace.go:171","msg":"trace[112187527] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:320; }","duration":"169.383911ms","start":"2026-02-06T00:36:00.579688Z","end":"2026-02-06T00:36:00.749072Z","steps":["trace[112187527] 'agreement among raft nodes before linearized reading'  (duration: 169.054937ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:00.748991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.236778ms","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-02-06T00:36:00.749576Z","caller":"traceutil/trace.go:171","msg":"trace[1446212707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:320; }","duration":"168.686303ms","start":"2026-02-06T00:36:00.580716Z","end":"2026-02-06T00:36:00.749403Z","steps":["trace[1446212707] 'agreement among raft nodes before linearized reading'  (duration: 168.217708ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:00.750119Z","caller":"traceutil/trace.go:171","msg":"trace[569334141] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"194.54329ms","start":"2026-02-06T00:36:00.555556Z","end":"2026-02-06T00:36:00.750099Z","steps":["trace[569334141] 'process raft request'  (duration: 135.324721ms)","trace[569334141] 'compare'  (duration: 57.484499ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:06.806667Z","caller":"traceutil/trace.go:171","msg":"trace[413295506] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"157.714597ms","start":"2026-02-06T00:36:06.648936Z","end":"2026-02-06T00:36:06.80665Z","steps":["trace[413295506] 'read index received'  (duration: 157.467784ms)","trace[413295506] 'applied index is now lower than readState.Index'  (duration: 246.073µs)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:06.806791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.842718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.250\" ","response":"range_response_count:1 size:135"}
{"level":"info","ts":"2026-02-06T00:36:06.806807Z","caller":"traceutil/trace.go:171","msg":"trace[2060221026] range","detail":"{range_begin:/registry/masterleases/199.204.45.250; range_end:; response_count:1; response_revision:406; }","duration":"157.959089ms","start":"2026-02-06T00:36:06.648842Z","end":"2026-02-06T00:36:06.806801Z","steps":["trace[2060221026] 'agreement among raft nodes before linearized reading'  (duration: 157.873328ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:06.806987Z","caller":"traceutil/trace.go:171","msg":"trace[31040696] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"660.214679ms","start":"2026-02-06T00:36:06.146767Z","end":"2026-02-06T00:36:06.806982Z","steps":["trace[31040696] 'process raft request'  (duration: 659.711884ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:06.807133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:06.146743Z","time spent":"660.255989ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/kube-apiserver-instance\" mod_revision:405 > success:<request_put:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" value_size:6972 >> failure:<request_range:<key:\"/registry/pods/kube-system/kube-apiserver-instance\" > >"}
{"level":"warn","ts":"2026-02-06T00:36:07.092216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.51528ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203522 username:\"kube-apiserver-etcd-client\" auth_revision:1 > lease_grant:<ttl:15-second id:452d9c3060719881>","response":"size:41"}
{"level":"info","ts":"2026-02-06T00:36:07.092492Z","caller":"traceutil/trace.go:171","msg":"trace[1066794613] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"281.667812ms","start":"2026-02-06T00:36:06.810808Z","end":"2026-02-06T00:36:07.092475Z","steps":["trace[1066794613] 'read index received'  (duration: 144.792339ms)","trace[1066794613] 'applied index is now lower than readState.Index'  (duration: 136.873973ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:07.092754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.932355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"}
{"level":"info","ts":"2026-02-06T00:36:07.093012Z","caller":"traceutil/trace.go:171","msg":"trace[1834640260] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:406; }","duration":"282.167517ms","start":"2026-02-06T00:36:06.810793Z","end":"2026-02-06T00:36:07.09296Z","steps":["trace[1834640260] 'agreement among raft nodes before linearized reading'  (duration: 281.897114ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:07.093068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.679266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:07.093489Z","caller":"traceutil/trace.go:171","msg":"trace[823678263] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"245.107611ms","start":"2026-02-06T00:36:06.848361Z","end":"2026-02-06T00:36:07.093468Z","steps":["trace[823678263] 'agreement among raft nodes before linearized reading'  (duration: 244.672466ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:07.093134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.825174ms","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-02-06T00:36:07.093789Z","caller":"traceutil/trace.go:171","msg":"trace[538185917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:406; }","duration":"239.487191ms","start":"2026-02-06T00:36:06.854284Z","end":"2026-02-06T00:36:07.093772Z","steps":["trace[538185917] 'agreement among raft nodes before linearized reading'  (duration: 238.815394ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:11.98441Z","caller":"traceutil/trace.go:171","msg":"trace[2115461535] linearizableReadLoop","detail":"{readStateIndex:431; appliedIndex:430; }","duration":"355.823463ms","start":"2026-02-06T00:36:11.628565Z","end":"2026-02-06T00:36:11.984389Z","steps":["trace[2115461535] 'read index received'  (duration: 355.652241ms)","trace[2115461535] 'applied index is now lower than readState.Index'  (duration: 170.182µs)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:11.984687Z","caller":"traceutil/trace.go:171","msg":"trace[814832038] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"371.27889ms","start":"2026-02-06T00:36:11.613397Z","end":"2026-02-06T00:36:11.984676Z","steps":["trace[814832038] 'process raft request'  (duration: 370.874746ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:11.984781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.613385Z","time spent":"371.33111ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:415 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:413 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-02-06T00:36:11.984994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.43846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-02-06T00:36:11.985028Z","caller":"traceutil/trace.go:171","msg":"trace[1832778856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"356.50881ms","start":"2026-02-06T00:36:11.62851Z","end":"2026-02-06T00:36:11.985018Z","steps":["trace[1832778856] 'agreement among raft nodes before linearized reading'  (duration: 356.44956ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:11.985056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.628487Z","time spent":"356.563071ms","remote":"127.0.0.1:36256","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":"warn","ts":"2026-02-06T00:36:11.985198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.55789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-06T00:36:11.985226Z","caller":"traceutil/trace.go:171","msg":"trace[2082017946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"355.603021ms","start":"2026-02-06T00:36:11.629613Z","end":"2026-02-06T00:36:11.985216Z","steps":["trace[2082017946] 'agreement among raft nodes before linearized reading'  (duration: 355.561911ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:11.985251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.629599Z","time spent":"355.644772ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-06T00:36:11.985416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.296234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:11.985441Z","caller":"traceutil/trace.go:171","msg":"trace[342982965] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"136.346265ms","start":"2026-02-06T00:36:11.849087Z","end":"2026-02-06T00:36:11.985433Z","steps":["trace[342982965] 'agreement among raft nodes before linearized reading'  (duration: 136.309544ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:12.491423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184131014203556,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-06T00:36:12.876819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.579935ms","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203557 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:417 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-06T00:36:12.876929Z","caller":"traceutil/trace.go:171","msg":"trace[1345815134] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"885.957491ms","start":"2026-02-06T00:36:11.990957Z","end":"2026-02-06T00:36:12.876915Z","steps":["trace[1345815134] 'process raft request'  (duration: 705.206154ms)","trace[1345815134] 'compare'  (duration: 180.476174ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:12.876971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.990936Z","time spent":"886.016632ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:417 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:368 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-02-06T00:36:13.29303Z","caller":"traceutil/trace.go:171","msg":"trace[995639071] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.30124849s","start":"2026-02-06T00:36:11.991756Z","end":"2026-02-06T00:36:13.293005Z","steps":["trace[995639071] 'process raft request'  (duration: 1.282446413s)","trace[995639071] 'compare'  (duration: 18.522654ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:13.293159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.991744Z","time spent":"1.301343421s","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-svcs-lock\" mod_revision:416 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" value_size:370 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-svcs-lock\" > >"}
{"level":"info","ts":"2026-02-06T00:36:13.309412Z","caller":"traceutil/trace.go:171","msg":"trace[1230529589] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:431; }","duration":"1.318157799s","start":"2026-02-06T00:36:11.991231Z","end":"2026-02-06T00:36:13.309389Z","steps":["trace[1230529589] 'read index received'  (duration: 704.939302ms)","trace[1230529589] 'applied index is now lower than readState.Index'  (duration: 613.217407ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:13.309532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31828815s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:13.309923Z","caller":"traceutil/trace.go:171","msg":"trace[977286801] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"1.318705154s","start":"2026-02-06T00:36:11.991203Z","end":"2026-02-06T00:36:13.309908Z","steps":["trace[977286801] 'agreement among raft nodes before linearized reading'  (duration: 1.31829547s)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:13.310127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:11.991194Z","time spent":"1.318873256s","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-02-06T00:36:13.310222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.497467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4069"}
{"level":"info","ts":"2026-02-06T00:36:13.310431Z","caller":"traceutil/trace.go:171","msg":"trace[688020205] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:421; }","duration":"722.762779ms","start":"2026-02-06T00:36:12.587655Z","end":"2026-02-06T00:36:13.310418Z","steps":["trace[688020205] 'agreement among raft nodes before linearized reading'  (duration: 722.508207ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:13.310468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:12.587637Z","time spent":"722.82222ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4093,"request content":"key:\"/registry/minions/instance\" "}
{"level":"warn","ts":"2026-02-06T00:36:13.310256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.091335ms","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-06T00:36:13.310656Z","caller":"traceutil/trace.go:171","msg":"trace[1901184951] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"174.521619ms","start":"2026-02-06T00:36:13.136127Z","end":"2026-02-06T00:36:13.310648Z","steps":["trace[1901184951] 'agreement among raft nodes before linearized reading'  (duration: 174.112505ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:29.637514Z","caller":"traceutil/trace.go:171","msg":"trace[203751412] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"632.258749ms","start":"2026-02-06T00:36:29.005218Z","end":"2026-02-06T00:36:29.637477Z","steps":["trace[203751412] 'process raft request'  (duration: 631.848565ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:29.637669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:29.005201Z","time spent":"632.37764ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":416,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/plndr-cp-lock\" mod_revision:484 > success:<request_put:<key:\"/registry/leases/kube-system/plndr-cp-lock\" value_size:366 >> failure:<request_range:<key:\"/registry/leases/kube-system/plndr-cp-lock\" > >"}
{"level":"info","ts":"2026-02-06T00:36:29.65483Z","caller":"traceutil/trace.go:171","msg":"trace[1767701207] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"288.494237ms","start":"2026-02-06T00:36:29.366297Z","end":"2026-02-06T00:36:29.654792Z","steps":["trace[1767701207] 'read index received'  (duration: 271.012273ms)","trace[1767701207] 'applied index is now lower than readState.Index'  (duration: 17.481374ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:29.65492Z","caller":"traceutil/trace.go:171","msg":"trace[1406275358] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"645.338694ms","start":"2026-02-06T00:36:29.009575Z","end":"2026-02-06T00:36:29.654913Z","steps":["trace[1406275358] 'process raft request'  (duration: 645.088282ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:29.65499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:29.009561Z","time spent":"645.377925ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4256,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/minions/instance\" mod_revision:423 > success:<request_put:<key:\"/registry/minions/instance\" value_size:4222 >> failure:<request_range:<key:\"/registry/minions/instance\" > >"}
{"level":"warn","ts":"2026-02-06T00:36:29.655185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.886511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-02-06T00:36:29.655207Z","caller":"traceutil/trace.go:171","msg":"trace[436891329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:524; }","duration":"288.942951ms","start":"2026-02-06T00:36:29.366257Z","end":"2026-02-06T00:36:29.6552Z","steps":["trace[436891329] 'agreement among raft nodes before linearized reading'  (duration: 288.87008ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:29.655304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.132643ms","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-02-06T00:36:29.655322Z","caller":"traceutil/trace.go:171","msg":"trace[772954680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:524; }","duration":"264.194083ms","start":"2026-02-06T00:36:29.391121Z","end":"2026-02-06T00:36:29.655315Z","steps":["trace[772954680] 'agreement among raft nodes before linearized reading'  (duration: 264.164723ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:34.239152Z","caller":"traceutil/trace.go:171","msg":"trace[269798041] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"159.29547ms","start":"2026-02-06T00:36:34.079838Z","end":"2026-02-06T00:36:34.239133Z","steps":["trace[269798041] 'process raft request'  (duration: 159.191189ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:35.799732Z","caller":"traceutil/trace.go:171","msg":"trace[1460469007] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"108.215278ms","start":"2026-02-06T00:36:35.691502Z","end":"2026-02-06T00:36:35.799718Z","steps":["trace[1460469007] 'read index received'  (duration: 108.096417ms)","trace[1460469007] 'applied index is now lower than readState.Index'  (duration: 118.321µs)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:35.799917Z","caller":"traceutil/trace.go:171","msg":"trace[447373049] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"140.857086ms","start":"2026-02-06T00:36:35.659054Z","end":"2026-02-06T00:36:35.799911Z","steps":["trace[447373049] 'process raft request'  (duration: 140.582194ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:35.800054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.542111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-02-06T00:36:35.800069Z","caller":"traceutil/trace.go:171","msg":"trace[1384815661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:562; }","duration":"108.592342ms","start":"2026-02-06T00:36:35.691473Z","end":"2026-02-06T00:36:35.800065Z","steps":["trace[1384815661] 'agreement among raft nodes before linearized reading'  (duration: 108.524332ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:35.972367Z","caller":"traceutil/trace.go:171","msg":"trace[1968562628] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:579; }","duration":"126.0703ms","start":"2026-02-06T00:36:35.846283Z","end":"2026-02-06T00:36:35.972353Z","steps":["trace[1968562628] 'read index received'  (duration: 124.594778ms)","trace[1968562628] 'applied index is now lower than readState.Index'  (duration: 1.474952ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:35.972454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.169101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:35.972478Z","caller":"traceutil/trace.go:171","msg":"trace[1743057469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"126.235701ms","start":"2026-02-06T00:36:35.846229Z","end":"2026-02-06T00:36:35.972465Z","steps":["trace[1743057469] 'agreement among raft nodes before linearized reading'  (duration: 126.177601ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:35.97263Z","caller":"traceutil/trace.go:171","msg":"trace[1564525495] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"167.933606ms","start":"2026-02-06T00:36:35.804691Z","end":"2026-02-06T00:36:35.972625Z","steps":["trace[1564525495] 'process raft request'  (duration: 166.223311ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:35.97281Z","caller":"traceutil/trace.go:171","msg":"trace[545900260] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"115.5042ms","start":"2026-02-06T00:36:35.85727Z","end":"2026-02-06T00:36:35.972774Z","steps":["trace[545900260] 'process raft request'  (duration: 115.061776ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:36:35.973146Z","caller":"traceutil/trace.go:171","msg":"trace[449192101] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"166.629984ms","start":"2026-02-06T00:36:35.806502Z","end":"2026-02-06T00:36:35.973132Z","steps":["trace[449192101] 'process raft request'  (duration: 165.802797ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:36.670648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.678032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8f6vd\" ","response":"range_response_count:1 size:16656"}
{"level":"info","ts":"2026-02-06T00:36:36.670696Z","caller":"traceutil/trace.go:171","msg":"trace[324445941] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8f6vd; range_end:; response_count:1; response_revision:565; }","duration":"464.758404ms","start":"2026-02-06T00:36:36.205926Z","end":"2026-02-06T00:36:36.670685Z","steps":["trace[324445941] 'range keys from in-memory index tree'  (duration: 464.622212ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:36.670718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:36.205912Z","time spent":"464.800984ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-8f6vd\" "}
{"level":"warn","ts":"2026-02-06T00:36:36.670861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.668379ms","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-06T00:36:36.670883Z","caller":"traceutil/trace.go:171","msg":"trace[305784079] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"428.711369ms","start":"2026-02-06T00:36:36.242162Z","end":"2026-02-06T00:36:36.670873Z","steps":["trace[305784079] 'range keys from in-memory index tree'  (duration: 428.578318ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:36.670899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:36.242147Z","time spent":"428.748409ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "}
{"level":"warn","ts":"2026-02-06T00:36:38.306809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14208184131014203847,"retry-timeout":"500ms"}
{"level":"warn","ts":"2026-02-06T00:36:38.624185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843682276s","expected-duration":"100ms","prefix":"","request":"header:<ID:14208184131014203850 username:\"kube-apiserver-etcd-client\" auth_revision:1 > txn:<compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:558 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >>","response":"size:16"}
{"level":"info","ts":"2026-02-06T00:36:38.624325Z","caller":"traceutil/trace.go:171","msg":"trace[1392553522] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.941375981s","start":"2026-02-06T00:36:36.682939Z","end":"2026-02-06T00:36:38.624315Z","steps":["trace[1392553522] 'process raft request'  (duration: 1.94131142s)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.624356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:36.682928Z","time spent":"1.941413941s","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/pods/kube-system/cilium-8f6vd\" mod_revision:516 > success:<request_put:<key:\"/registry/pods/kube-system/cilium-8f6vd\" value_size:16754 >> failure:<request_range:<key:\"/registry/pods/kube-system/cilium-8f6vd\" > >"}
{"level":"info","ts":"2026-02-06T00:36:38.624374Z","caller":"traceutil/trace.go:171","msg":"trace[1671069152] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.946661705s","start":"2026-02-06T00:36:36.677686Z","end":"2026-02-06T00:36:38.624347Z","steps":["trace[1671069152] 'process raft request'  (duration: 102.764187ms)","trace[1671069152] 'compare'  (duration: 1.843381234s)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:36:38.624497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:36.677675Z","time spent":"1.946754927s","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/leases/kube-system/kube-scheduler\" mod_revision:558 > success:<request_put:<key:\"/registry/leases/kube-system/kube-scheduler\" value_size:412 >> failure:<request_range:<key:\"/registry/leases/kube-system/kube-scheduler\" > >"}
{"level":"warn","ts":"2026-02-06T00:36:38.624611Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.84420686s","expected-duration":"1s"}
{"level":"info","ts":"2026-02-06T00:36:38.624707Z","caller":"traceutil/trace.go:171","msg":"trace[491339088] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"818.514697ms","start":"2026-02-06T00:36:37.806186Z","end":"2026-02-06T00:36:38.624701Z","steps":["trace[491339088] 'read index received'  (duration: 818.474277ms)","trace[491339088] 'applied index is now lower than readState.Index'  (duration: 40.04µs)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:38.624736Z","caller":"traceutil/trace.go:171","msg":"trace[2099435450] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.941211309s","start":"2026-02-06T00:36:36.683521Z","end":"2026-02-06T00:36:38.624732Z","steps":["trace[2099435450] 'process raft request'  (duration: 1.941153859s)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.624762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:36.683515Z","time spent":"1.941231109s","remote":"127.0.0.1:36030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.250\" mod_revision:483 > success:<request_put:<key:\"/registry/masterleases/199.204.45.250\" value_size:67 lease:4984812094159428040 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.250\" > >"}
{"level":"warn","ts":"2026-02-06T00:36:38.624845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.917294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:36:38.624921Z","caller":"traceutil/trace.go:171","msg":"trace[432060688] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"773.006085ms","start":"2026-02-06T00:36:37.851903Z","end":"2026-02-06T00:36:38.624909Z","steps":["trace[432060688] 'agreement among raft nodes before linearized reading'  (duration: 772.884404ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.624952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:37.851895Z","time spent":"773.049875ms","remote":"127.0.0.1:36000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}
{"level":"warn","ts":"2026-02-06T00:36:38.624975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.417443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-02-06T00:36:38.624993Z","caller":"traceutil/trace.go:171","msg":"trace[779944531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"645.444743ms","start":"2026-02-06T00:36:37.979543Z","end":"2026-02-06T00:36:38.624988Z","steps":["trace[779944531] 'agreement among raft nodes before linearized reading'  (duration: 645.362003ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.625008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:37.979538Z","time spent":"645.467374ms","remote":"127.0.0.1:36256","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":"warn","ts":"2026-02-06T00:36:38.625084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.90386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-06T00:36:38.625095Z","caller":"traceutil/trace.go:171","msg":"trace[1156092397] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"818.94315ms","start":"2026-02-06T00:36:37.806149Z","end":"2026-02-06T00:36:38.625092Z","steps":["trace[1156092397] 'agreement among raft nodes before linearized reading'  (duration: 818.90759ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.625108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:37.806126Z","time spent":"818.977801ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}
{"level":"warn","ts":"2026-02-06T00:36:38.625187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.242689ms","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-02-06T00:36:38.625223Z","caller":"traceutil/trace.go:171","msg":"trace[917865067] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:568; }","duration":"647.310019ms","start":"2026-02-06T00:36:37.977904Z","end":"2026-02-06T00:36:38.625214Z","steps":["trace[917865067] 'agreement among raft nodes before linearized reading'  (duration: 647.195178ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:38.625251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:36:37.977886Z","time spent":"647.35814ms","remote":"127.0.0.1:36256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}
{"level":"info","ts":"2026-02-06T00:36:40.825521Z","caller":"traceutil/trace.go:171","msg":"trace[2011033115] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"162.913698ms","start":"2026-02-06T00:36:40.662592Z","end":"2026-02-06T00:36:40.825505Z","steps":["trace[2011033115] 'read index received'  (duration: 162.789927ms)","trace[2011033115] 'applied index is now lower than readState.Index'  (duration: 123.331µs)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:36:40.825729Z","caller":"traceutil/trace.go:171","msg":"trace[396620096] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"186.880076ms","start":"2026-02-06T00:36:40.638844Z","end":"2026-02-06T00:36:40.825724Z","steps":["trace[396620096] 'process raft request'  (duration: 186.578024ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:40.825873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.269751ms","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-02-06T00:36:40.825889Z","caller":"traceutil/trace.go:171","msg":"trace[1980835914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"163.318701ms","start":"2026-02-06T00:36:40.662565Z","end":"2026-02-06T00:36:40.825884Z","steps":["trace[1980835914] 'agreement among raft nodes before linearized reading'  (duration: 163.24623ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:40.825969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.17759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"}
{"level":"info","ts":"2026-02-06T00:36:40.825981Z","caller":"traceutil/trace.go:171","msg":"trace[2098344358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"128.208341ms","start":"2026-02-06T00:36:40.697769Z","end":"2026-02-06T00:36:40.825978Z","steps":["trace[2098344358] 'agreement among raft nodes before linearized reading'  (duration: 128.17132ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:36:40.826076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.217541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"}
{"level":"info","ts":"2026-02-06T00:36:40.826088Z","caller":"traceutil/trace.go:171","msg":"trace[369484164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"128.236491ms","start":"2026-02-06T00:36:40.697848Z","end":"2026-02-06T00:36:40.826085Z","steps":["trace[369484164] 'agreement among raft nodes before linearized reading'  (duration: 128.201621ms)"],"step_count":1}
{"level":"info","ts":"2026-02-06T00:38:07.219904Z","caller":"traceutil/trace.go:171","msg":"trace[1092569820] linearizableReadLoop","detail":"{readStateIndex:1052; appliedIndex:1051; }","duration":"337.885834ms","start":"2026-02-06T00:38:06.881998Z","end":"2026-02-06T00:38:07.219884Z","steps":["trace[1092569820] 'read index received'  (duration: 298.142822ms)","trace[1092569820] 'applied index is now lower than readState.Index'  (duration: 39.741822ms)"],"step_count":2}
{"level":"info","ts":"2026-02-06T00:38:07.220083Z","caller":"traceutil/trace.go:171","msg":"trace[1404935938] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"338.417135ms","start":"2026-02-06T00:38:06.881656Z","end":"2026-02-06T00:38:07.220073Z","steps":["trace[1404935938] 'process raft request'  (duration: 298.53113ms)","trace[1404935938] 'compare'  (duration: 39.430965ms)"],"step_count":2}
{"level":"warn","ts":"2026-02-06T00:38:07.220175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:38:06.881637Z","time spent":"338.484077ms","remote":"127.0.0.1:36030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare:<target:MOD key:\"/registry/masterleases/199.204.45.250\" mod_revision:975 > success:<request_put:<key:\"/registry/masterleases/199.204.45.250\" value_size:67 lease:4984812094159429102 >> failure:<request_range:<key:\"/registry/masterleases/199.204.45.250\" > >"}
{"level":"warn","ts":"2026-02-06T00:38:07.220397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.396495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"}
{"level":"info","ts":"2026-02-06T00:38:07.220636Z","caller":"traceutil/trace.go:171","msg":"trace[205231348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"338.660071ms","start":"2026-02-06T00:38:06.881967Z","end":"2026-02-06T00:38:07.220627Z","steps":["trace[205231348] 'agreement among raft nodes before linearized reading'  (duration: 338.382805ms)"],"step_count":1}
{"level":"warn","ts":"2026-02-06T00:38:07.220702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-06T00:38:06.881879Z","time spent":"338.814463ms","remote":"127.0.0.1:36006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "}