{"level":"warn","ts":"2026-01-29T19:35:59.730366Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:35:59.731655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.84:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.84:2380","--initial-cluster=instance=https://162.253.55.84:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.84:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.84:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-01-29T19:35:59.737349Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-29T19:35:59.737375Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-29T19:35:59.737408Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T19:35:59.738071Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"]} {"level":"info","ts":"2026-01-29T19:35:59.738144Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.84:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-01-29T19:35:59.744029Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.680154ms"} {"level":"info","ts":"2026-01-29T19:35:59.751701Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ca4c710a8d5349d7","cluster-id":"8e28a51debf1c8ab"} {"level":"info","ts":"2026-01-29T19:35:59.751767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=()"} {"level":"info","ts":"2026-01-29T19:35:59.751787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 0"} {"level":"info","ts":"2026-01-29T19:35:59.751796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ca4c710a8d5349d7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-29T19:35:59.751803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became follower at term 1"} {"level":"info","ts":"2026-01-29T19:35:59.751833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"warn","ts":"2026-01-29T19:35:59.760012Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-29T19:35:59.766926Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-29T19:35:59.772888Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-01-29T19:35:59.780089Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ca4c710a8d5349d7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-29T19:35:59.799541Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ca4c710a8d5349d7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-29T19:35:59.799775Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T19:35:59.79999Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T19:35:59.800082Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-29T19:35:59.800132Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.84:2380"} {"level":"info","ts":"2026-01-29T19:35:59.800078Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-01-29T19:35:59.799951Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-01-29T19:35:59.800475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 switched to configuration voters=(14577150384026110423)"} {"level":"info","ts":"2026-01-29T19:35:59.800735Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","added-peer-id":"ca4c710a8d5349d7","added-peer-peer-urls":["https://162.253.55.84:2380"]} {"level":"info","ts":"2026-01-29T19:35:59.801363Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ca4c710a8d5349d7","initial-advertise-peer-urls":["https://162.253.55.84:2380"],"listen-peer-urls":["https://162.253.55.84:2380"],"advertise-client-urls":["https://162.253.55.84:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.84:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-29T19:35:59.801391Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-29T19:35:59.953317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 is starting a new election at term 1"} {"level":"info","ts":"2026-01-29T19:35:59.953364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became pre-candidate at term 1"} {"level":"info","ts":"2026-01-29T19:35:59.953388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgPreVoteResp from ca4c710a8d5349d7 at term 1"} {"level":"info","ts":"2026-01-29T19:35:59.953399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became candidate at term 2"} {"level":"info","ts":"2026-01-29T19:35:59.953409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 received MsgVoteResp from ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-29T19:35:59.953418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ca4c710a8d5349d7 became leader at term 2"} {"level":"info","ts":"2026-01-29T19:35:59.953429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ca4c710a8d5349d7 elected leader ca4c710a8d5349d7 at term 2"} {"level":"info","ts":"2026-01-29T19:35:59.957697Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:35:59.959461Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ca4c710a8d5349d7","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.84:2379]}","request-path":"/0/members/ca4c710a8d5349d7/attributes","cluster-id":"8e28a51debf1c8ab","publish-timeout":"7s"} {"level":"info","ts":"2026-01-29T19:35:59.959476Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:35:59.961295Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-29T19:35:59.965066Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-29T19:35:59.965134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-29T19:35:59.961597Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e28a51debf1c8ab","local-member-id":"ca4c710a8d5349d7","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:35:59.965234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:35:59.965308Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-29T19:35:59.966669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-29T19:35:59.966997Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.84:2379"} {"level":"info","ts":"2026-01-29T19:36:10.696884Z","caller":"traceutil/trace.go:171","msg":"trace[1347144024] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:365; }","duration":"419.637277ms","start":"2026-01-29T19:36:10.277235Z","end":"2026-01-29T19:36:10.696872Z","steps":["trace[1347144024] 'read index received' (duration: 419.283254ms)","trace[1347144024] 'applied index is now lower than readState.Index' (duration: 353.303µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:10.697131Z","caller":"traceutil/trace.go:171","msg":"trace[255189913] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"454.361056ms","start":"2026-01-29T19:36:10.242759Z","end":"2026-01-29T19:36:10.69712Z","steps":["trace[255189913] 'process raft request' (duration: 454.022784ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.698974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.242754Z","time spent":"454.413177ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:36:10.699448Z","caller":"traceutil/trace.go:171","msg":"trace[983692363] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"452.978957ms","start":"2026-01-29T19:36:10.246455Z","end":"2026-01-29T19:36:10.699434Z","steps":["trace[983692363] 'process raft request' (duration: 450.379839ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.699698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.246449Z","time spent":"453.036718ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:10.700104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.858919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-01-29T19:36:10.700161Z","caller":"traceutil/trace.go:171","msg":"trace[2041392680] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:353; }","duration":"422.96304ms","start":"2026-01-29T19:36:10.277185Z","end":"2026-01-29T19:36:10.700148Z","steps":["trace[2041392680] 'agreement among raft nodes before linearized reading' (duration: 422.87071ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.700431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.277174Z","time spent":"423.153621ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":227,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-01-29T19:36:10.700842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.430708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-01-29T19:36:10.701029Z","caller":"traceutil/trace.go:171","msg":"trace[1634394545] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:353; }","duration":"373.502008ms","start":"2026-01-29T19:36:10.327372Z","end":"2026-01-29T19:36:10.700874Z","steps":["trace[1634394545] 'agreement among raft nodes before linearized reading' (duration: 373.430938ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.701161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.327358Z","time spent":"373.70732ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":242,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"warn","ts":"2026-01-29T19:36:10.702282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.691106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-29T19:36:10.702327Z","caller":"traceutil/trace.go:171","msg":"trace[539607899] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:353; }","duration":"174.760176ms","start":"2026-01-29T19:36:10.527556Z","end":"2026-01-29T19:36:10.702316Z","steps":["trace[539607899] 'agreement among raft nodes before linearized reading' (duration: 174.681226ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.703165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.215018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-01-29T19:36:10.703352Z","caller":"traceutil/trace.go:171","msg":"trace[833305854] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:353; }","duration":"224.305298ms","start":"2026-01-29T19:36:10.478892Z","end":"2026-01-29T19:36:10.703198Z","steps":["trace[833305854] 'agreement among raft nodes before linearized reading' (duration: 224.214248ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.703861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.178376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-01-29T19:36:10.703934Z","caller":"traceutil/trace.go:171","msg":"trace[809854234] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:353; }","duration":"276.292897ms","start":"2026-01-29T19:36:10.427631Z","end":"2026-01-29T19:36:10.703924Z","steps":["trace[809854234] 'agreement among raft nodes before linearized reading' (duration: 276.133916ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.704543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.602594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-01-29T19:36:10.704597Z","caller":"traceutil/trace.go:171","msg":"trace[1629452950] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:353; }","duration":"326.671695ms","start":"2026-01-29T19:36:10.377915Z","end":"2026-01-29T19:36:10.704587Z","steps":["trace[1629452950] 'agreement among raft nodes before linearized reading' (duration: 326.526354ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.704698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.377869Z","time spent":"326.822516ms","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-01-29T19:36:10.706215Z","caller":"traceutil/trace.go:171","msg":"trace[1245142324] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"454.134105ms","start":"2026-01-29T19:36:10.242589Z","end":"2026-01-29T19:36:10.696723Z","steps":["trace[1245142324] 'process raft request' (duration: 453.722702ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:10.706285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:10.242579Z","time spent":"463.68008ms","remote":"127.0.0.1:36114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:11.814363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.747187ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-01-29T19:36:15.489396Z","caller":"traceutil/trace.go:171","msg":"trace[7291175] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"132.647102ms","start":"2026-01-29T19:36:15.356725Z","end":"2026-01-29T19:36:15.489372Z","steps":["trace[7291175] 'read index received' (duration: 75.591668ms)","trace[7291175] 'applied index is now lower than readState.Index' (duration: 57.054384ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:15.489567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.818543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-01-29T19:36:15.489608Z","caller":"traceutil/trace.go:171","msg":"trace[1701927665] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:412; }","duration":"132.894555ms","start":"2026-01-29T19:36:15.356694Z","end":"2026-01-29T19:36:15.489588Z","steps":["trace[1701927665] 'agreement among raft nodes before linearized reading' (duration: 132.764934ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:36:15.48987Z","caller":"traceutil/trace.go:171","msg":"trace[1862838375] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"137.895367ms","start":"2026-01-29T19:36:15.351945Z","end":"2026-01-29T19:36:15.489841Z","steps":["trace[1862838375] 'process raft request' (duration: 80.41085ms)","trace[1862838375] 'compare' (duration: 56.887383ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:18.515349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.535503629s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:36:18.515507Z","caller":"traceutil/trace.go:171","msg":"trace[529571756] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:428; }","duration":"2.564729182s","start":"2026-01-29T19:36:15.950754Z","end":"2026-01-29T19:36:18.515483Z","steps":["trace[529571756] 'read index received' (duration: 29.027841ms)","trace[529571756] 'applied index is now lower than readState.Index' (duration: 2.535700741s)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:18.515736Z","caller":"traceutil/trace.go:171","msg":"trace[1577116361] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"3.018189728s","start":"2026-01-29T19:36:15.497538Z","end":"2026-01-29T19:36:18.515728Z","steps":["trace[1577116361] 'process raft request' (duration: 482.271956ms)","trace[1577116361] 'compare' (duration: 2.523612013s)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:18.515797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:15.497524Z","time spent":"3.018253398s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6879,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:18.51591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.565159235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T19:36:18.515949Z","caller":"traceutil/trace.go:171","msg":"trace[392773588] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:413; }","duration":"2.565232665s","start":"2026-01-29T19:36:15.95071Z","end":"2026-01-29T19:36:18.515943Z","steps":["trace[392773588] 'agreement among raft nodes before linearized reading' (duration: 2.565179375s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.515976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:15.95069Z","time spent":"2.565281045s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:36:18.516082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.41470428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-29T19:36:18.516123Z","caller":"traceutil/trace.go:171","msg":"trace[428337541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"2.41477056s","start":"2026-01-29T19:36:16.101348Z","end":"2026-01-29T19:36:18.516119Z","steps":["trace[428337541] 'agreement among raft nodes before linearized reading' (duration: 2.41471798s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.516149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:16.101333Z","time spent":"2.41481093s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-01-29T19:36:18.516286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.680711ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:18.516342Z","caller":"traceutil/trace.go:171","msg":"trace[588858033] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:413; }","duration":"439.736532ms","start":"2026-01-29T19:36:18.0766Z","end":"2026-01-29T19:36:18.516336Z","steps":["trace[588858033] 'agreement among raft nodes before linearized reading' (duration: 439.675541ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.516547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023232114s","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-01-29T19:36:18.516598Z","caller":"traceutil/trace.go:171","msg":"trace[1274779655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:413; }","duration":"1.023348714s","start":"2026-01-29T19:36:17.493242Z","end":"2026-01-29T19:36:18.516591Z","steps":["trace[1274779655] 'agreement among raft nodes before linearized reading' (duration: 1.023265904s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.51667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:17.493224Z","time spent":"1.023400075s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T19:36:18.516776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39982049s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:18.51683Z","caller":"traceutil/trace.go:171","msg":"trace[637051433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"1.399908682s","start":"2026-01-29T19:36:17.116914Z","end":"2026-01-29T19:36:18.516823Z","steps":["trace[637051433] 'agreement among raft nodes before linearized reading' (duration: 1.399847331s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.516862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:17.116896Z","time spent":"1.399960662s","remote":"127.0.0.1:35762","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-01-29T19:36:18.516983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725480381s","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-01-29T19:36:18.517014Z","caller":"traceutil/trace.go:171","msg":"trace[2012738734] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:413; }","duration":"1.725538771s","start":"2026-01-29T19:36:16.79147Z","end":"2026-01-29T19:36:18.517009Z","steps":["trace[2012738734] 'agreement among raft nodes before linearized reading' (duration: 1.725480781s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.517037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:16.791456Z","time spent":"1.725576981s","remote":"127.0.0.1:36228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4058,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-01-29T19:36:18.51715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.415473625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-29T19:36:18.517181Z","caller":"traceutil/trace.go:171","msg":"trace[142091221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:413; }","duration":"2.415522255s","start":"2026-01-29T19:36:16.101654Z","end":"2026-01-29T19:36:18.517176Z","steps":["trace[142091221] 'agreement among raft nodes before linearized reading' (duration: 2.415462515s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:18.51722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:16.101646Z","time spent":"2.415567955s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-29T19:36:32.87723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.876177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:36:32.877315Z","caller":"traceutil/trace.go:171","msg":"trace[398391670] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:535; }","duration":"263.706962ms","start":"2026-01-29T19:36:32.613597Z","end":"2026-01-29T19:36:32.877304Z","steps":["trace[398391670] 'read index received' (duration: 39.522219ms)","trace[398391670] 'applied index is now lower than readState.Index' (duration: 224.184153ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:32.877427Z","caller":"traceutil/trace.go:171","msg":"trace[1504769544] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"272.169992ms","start":"2026-01-29T19:36:32.605251Z","end":"2026-01-29T19:36:32.877421Z","steps":["trace[1504769544] 'process raft request' (duration: 171.039784ms)","trace[1504769544] 'compare' (duration: 100.797456ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:32.877564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.966434ms","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-01-29T19:36:32.877583Z","caller":"traceutil/trace.go:171","msg":"trace[1233446897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:514; }","duration":"264.001824ms","start":"2026-01-29T19:36:32.613572Z","end":"2026-01-29T19:36:32.877574Z","steps":["trace[1233446897] 'agreement among raft nodes before linearized reading' (duration: 263.941434ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:32.87767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.219766ms","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-01-29T19:36:32.877682Z","caller":"traceutil/trace.go:171","msg":"trace[1942732661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:514; }","duration":"257.253066ms","start":"2026-01-29T19:36:32.620425Z","end":"2026-01-29T19:36:32.877678Z","steps":["trace[1942732661] 'agreement among raft nodes before linearized reading' (duration: 257.214875ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:34.26019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"789.566404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:36:34.260306Z","caller":"traceutil/trace.go:171","msg":"trace[1172528634] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"843.221904ms","start":"2026-01-29T19:36:33.417066Z","end":"2026-01-29T19:36:34.260288Z","steps":["trace[1172528634] 'process raft request' (duration: 53.462349ms)","trace[1172528634] 'compare' (duration: 789.460643ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:34.260364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:33.417042Z","time spent":"843.296334ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:35.349372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.401608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-01-29T19:36:35.349438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.682733ms","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-01-29T19:36:35.349485Z","caller":"traceutil/trace.go:171","msg":"trace[334360986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"456.761543ms","start":"2026-01-29T19:36:34.89271Z","end":"2026-01-29T19:36:35.349471Z","steps":["trace[334360986] 'range keys from in-memory index tree' (duration: 456.572252ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.349483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.653716ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-29T19:36:35.349522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:34.892694Z","time spent":"456.819023ms","remote":"127.0.0.1:36050","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":"info","ts":"2026-01-29T19:36:35.349547Z","caller":"traceutil/trace.go:171","msg":"trace[361446506] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:517; }","duration":"721.719726ms","start":"2026-01-29T19:36:34.627819Z","end":"2026-01-29T19:36:35.349539Z","steps":["trace[361446506] 'range keys from in-memory index tree' (duration: 721.646796ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.349728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.858373ms","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-01-29T19:36:35.349759Z","caller":"traceutil/trace.go:171","msg":"trace[1435174214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"456.903144ms","start":"2026-01-29T19:36:34.892845Z","end":"2026-01-29T19:36:35.349749Z","steps":["trace[1435174214] 'range keys from in-memory index tree' (duration: 456.782383ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.349801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.612966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-01-29T19:36:35.349809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:34.89284Z","time spent":"456.939785ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T19:36:35.349832Z","caller":"traceutil/trace.go:171","msg":"trace[166063267] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"688.680336ms","start":"2026-01-29T19:36:34.661142Z","end":"2026-01-29T19:36:35.349823Z","steps":["trace[166063267] 'range keys from in-memory index tree' (duration: 688.517525ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.349864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:34.661124Z","time spent":"688.732396ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:36:35.349932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.531191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:35.349958Z","caller":"traceutil/trace.go:171","msg":"trace[1399455644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"236.587362ms","start":"2026-01-29T19:36:35.113362Z","end":"2026-01-29T19:36:35.349949Z","steps":["trace[1399455644] 'range keys from in-memory index tree' (duration: 236.469661ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:36:35.349445Z","caller":"traceutil/trace.go:171","msg":"trace[1952198639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:517; }","duration":"466.505829ms","start":"2026-01-29T19:36:34.882919Z","end":"2026-01-29T19:36:35.349425Z","steps":["trace[1952198639] 'range keys from in-memory index tree' (duration: 466.230167ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.350081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:34.882906Z","time spent":"467.164502ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-29T19:36:35.656154Z","caller":"traceutil/trace.go:171","msg":"trace[664129380] linearizableReadLoop","detail":"{readStateIndex:542; appliedIndex:541; }","duration":"300.942749ms","start":"2026-01-29T19:36:35.355191Z","end":"2026-01-29T19:36:35.656134Z","steps":["trace[664129380] 'read index received' (duration: 300.846599ms)","trace[664129380] 'applied index is now lower than readState.Index' (duration: 95.44µs)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:35.656302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.085761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:35.656323Z","caller":"traceutil/trace.go:171","msg":"trace[1645959207] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"301.153151ms","start":"2026-01-29T19:36:35.355164Z","end":"2026-01-29T19:36:35.656317Z","steps":["trace[1645959207] 'agreement among raft nodes before linearized reading' (duration: 301.051051ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.656346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:35.355152Z","time spent":"301.189291ms","remote":"127.0.0.1:35776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-29T19:36:35.656454Z","caller":"traceutil/trace.go:171","msg":"trace[784058808] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"301.662604ms","start":"2026-01-29T19:36:35.354769Z","end":"2026-01-29T19:36:35.656431Z","steps":["trace[784058808] 'process raft request' (duration: 301.244041ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.657285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:35.354746Z","time spent":"301.750644ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:36:35.658162Z","caller":"traceutil/trace.go:171","msg":"trace[727962] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"300.723129ms","start":"2026-01-29T19:36:35.357428Z","end":"2026-01-29T19:36:35.658151Z","steps":["trace[727962] 'process raft request' (duration: 300.699749ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.658212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:35.357423Z","time spent":"300.760839ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:36:35.658359Z","caller":"traceutil/trace.go:171","msg":"trace[1658353201] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"303.079832ms","start":"2026-01-29T19:36:35.355272Z","end":"2026-01-29T19:36:35.658352Z","steps":["trace[1658353201] 'process raft request' (duration: 302.76652ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.658394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:35.355263Z","time spent":"303.110882ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:36:35.658486Z","caller":"traceutil/trace.go:171","msg":"trace[1382521806] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"301.664244ms","start":"2026-01-29T19:36:35.356815Z","end":"2026-01-29T19:36:35.65848Z","steps":["trace[1382521806] 'process raft request' (duration: 301.287521ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:35.658518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:35.356802Z","time spent":"301.697574ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:42.17783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.601085ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:36:42.178215Z","caller":"traceutil/trace.go:171","msg":"trace[11957714] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"399.697872ms","start":"2026-01-29T19:36:41.778479Z","end":"2026-01-29T19:36:42.178177Z","steps":["trace[11957714] 'process raft request' (duration: 157.690554ms)","trace[11957714] 'compare' (duration: 241.519025ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:42.178285Z","caller":"traceutil/trace.go:171","msg":"trace[1566138536] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"398.124043ms","start":"2026-01-29T19:36:41.780149Z","end":"2026-01-29T19:36:42.178273Z","steps":["trace[1566138536] 'process raft request' (duration: 397.747631ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:42.178358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:41.780143Z","time spent":"398.175713ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:42.178393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:41.778467Z","time spent":"399.822692ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:42.888931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.267698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-prn5c\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-01-29T19:36:42.888984Z","caller":"traceutil/trace.go:171","msg":"trace[1379504994] range","detail":"{range_begin:/registry/pods/kube-system/cilium-prn5c; range_end:; response_count:1; response_revision:559; }","duration":"467.348499ms","start":"2026-01-29T19:36:42.421622Z","end":"2026-01-29T19:36:42.888971Z","steps":["trace[1379504994] 'range keys from in-memory index tree' (duration: 467.204098ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:42.88901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:42.42161Z","time spent":"467.393979ms","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-prn5c\" "} {"level":"warn","ts":"2026-01-29T19:36:42.889524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.72352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-29T19:36:42.889565Z","caller":"traceutil/trace.go:171","msg":"trace[1686299581] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"303.708916ms","start":"2026-01-29T19:36:42.585848Z","end":"2026-01-29T19:36:42.889557Z","steps":["trace[1686299581] 'process raft request' (duration: 183.913605ms)","trace[1686299581] 'compare' (duration: 119.66513ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:36:42.889594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:42.585833Z","time spent":"303.745336ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:43.613475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916917174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:36:44.114228Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916917174,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:36:44.157538Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.247882613s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T19:36:44.157798Z","caller":"traceutil/trace.go:171","msg":"trace[714732694] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"1.044589514s","start":"2026-01-29T19:36:43.113194Z","end":"2026-01-29T19:36:44.157784Z","steps":["trace[714732694] 'read index received' (duration: 1.044434923s)","trace[714732694] 'applied index is now lower than readState.Index' (duration: 154.031µs)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:44.157973Z","caller":"traceutil/trace.go:171","msg":"trace[2071595158] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.248395306s","start":"2026-01-29T19:36:42.909572Z","end":"2026-01-29T19:36:44.157967Z","steps":["trace[2071595158] 'process raft request' (duration: 1.248108714s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:44.15803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:42.909554Z","time spent":"1.248432156s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:44.158185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.983496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:36:44.158241Z","caller":"traceutil/trace.go:171","msg":"trace[15034042] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:561; }","duration":"781.081256ms","start":"2026-01-29T19:36:43.377142Z","end":"2026-01-29T19:36:44.158223Z","steps":["trace[15034042] 'agreement among raft nodes before linearized reading' (duration: 780.916855ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:44.158268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:43.377124Z","time spent":"781.139076ms","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.84\" "} {"level":"warn","ts":"2026-01-29T19:36:44.158285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.054512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-01-29T19:36:44.158308Z","caller":"traceutil/trace.go:171","msg":"trace[2136707738] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"378.117462ms","start":"2026-01-29T19:36:43.780185Z","end":"2026-01-29T19:36:44.158303Z","steps":["trace[2136707738] 'agreement among raft nodes before linearized reading' (duration: 378.037612ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:44.158323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:43.780171Z","time spent":"378.149172ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-29T19:36:44.158392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.045197787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:44.158405Z","caller":"traceutil/trace.go:171","msg":"trace[1207953599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"1.045245557s","start":"2026-01-29T19:36:43.113156Z","end":"2026-01-29T19:36:44.158402Z","steps":["trace[1207953599] 'agreement among raft nodes before linearized reading' (duration: 1.045223987s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:44.158416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:43.113139Z","time spent":"1.045274367s","remote":"127.0.0.1:35762","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-01-29T19:36:44.158451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.190544ms","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-01-29T19:36:44.158472Z","caller":"traceutil/trace.go:171","msg":"trace[941945790] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"473.235465ms","start":"2026-01-29T19:36:43.68523Z","end":"2026-01-29T19:36:44.158466Z","steps":["trace[941945790] 'agreement among raft nodes before linearized reading' (duration: 473.176925ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:44.15849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:43.685217Z","time spent":"473.269005ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-29T19:36:44.670614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916917179,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:36:45.462526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.030496ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:45.462631Z","caller":"traceutil/trace.go:171","msg":"trace[1405818719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:561; }","duration":"835.144487ms","start":"2026-01-29T19:36:44.627471Z","end":"2026-01-29T19:36:45.462616Z","steps":["trace[1405818719] 'range keys from in-memory index tree' (duration: 835.015806ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.463346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.974613ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-01-29T19:36:45.463395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.162576Z","time spent":"1.300816815s","remote":"127.0.0.1:35802","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-01-29T19:36:45.606492Z","caller":"traceutil/trace.go:171","msg":"trace[1660838938] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:586; }","duration":"1.436719364s","start":"2026-01-29T19:36:44.169755Z","end":"2026-01-29T19:36:45.606474Z","steps":["trace[1660838938] 'read index received' (duration: 828.620232ms)","trace[1660838938] 'applied index is now lower than readState.Index' (duration: 608.098502ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:45.606718Z","caller":"traceutil/trace.go:171","msg":"trace[647172343] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.437510739s","start":"2026-01-29T19:36:44.169201Z","end":"2026-01-29T19:36:45.606711Z","steps":["trace[647172343] 'process raft request' (duration: 1.437133697s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.60679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.169184Z","time spent":"1.437553829s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-29T19:36:45.606927Z","caller":"traceutil/trace.go:171","msg":"trace[1273351232] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.436385713s","start":"2026-01-29T19:36:44.170533Z","end":"2026-01-29T19:36:45.606919Z","steps":["trace[1273351232] 'process raft request' (duration: 1.4359081s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.606961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.170524Z","time spent":"1.436418233s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-29T19:36:45.607043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437287047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:36:45.607063Z","caller":"traceutil/trace.go:171","msg":"trace[545697501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:563; }","duration":"1.437321698s","start":"2026-01-29T19:36:44.169735Z","end":"2026-01-29T19:36:45.607057Z","steps":["trace[545697501] 'agreement among raft nodes before linearized reading' (duration: 1.437290137s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.60709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.169729Z","time spent":"1.437348218s","remote":"127.0.0.1:35762","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-01-29T19:36:45.610843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428239518s","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-01-29T19:36:45.610891Z","caller":"traceutil/trace.go:171","msg":"trace[1072447861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.428311258s","start":"2026-01-29T19:36:44.182567Z","end":"2026-01-29T19:36:45.610878Z","steps":["trace[1072447861] 'agreement among raft nodes before linearized reading' (duration: 1.428140787s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.610923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.182556Z","time spent":"1.428360479s","remote":"127.0.0.1:36050","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":"info","ts":"2026-01-29T19:36:45.611075Z","caller":"traceutil/trace.go:171","msg":"trace[1082994909] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"147.01616ms","start":"2026-01-29T19:36:45.464048Z","end":"2026-01-29T19:36:45.611065Z","steps":["trace[1082994909] 'process raft request' (duration: 146.554918ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.611241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.4249856s","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-01-29T19:36:45.611266Z","caller":"traceutil/trace.go:171","msg":"trace[1852675301] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.42503239s","start":"2026-01-29T19:36:44.186225Z","end":"2026-01-29T19:36:45.611258Z","steps":["trace[1852675301] 'agreement among raft nodes before linearized reading' (duration: 1.42495666s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:45.611289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:36:44.186215Z","time spent":"1.42506864s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-01-29T19:36:46.558587Z","caller":"traceutil/trace.go:171","msg":"trace[1299842680] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"126.542577ms","start":"2026-01-29T19:36:46.432026Z","end":"2026-01-29T19:36:46.558569Z","steps":["trace[1299842680] 'process raft request' (duration: 126.469516ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:36:46.613046Z","caller":"traceutil/trace.go:171","msg":"trace[1745146735] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"101.083548ms","start":"2026-01-29T19:36:46.511946Z","end":"2026-01-29T19:36:46.61303Z","steps":["trace[1745146735] 'process raft request' (duration: 100.988757ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:36:48.162219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.641544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-01-29T19:36:48.162273Z","caller":"traceutil/trace.go:171","msg":"trace[2062713321] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:582; }","duration":"164.723756ms","start":"2026-01-29T19:36:47.99754Z","end":"2026-01-29T19:36:48.162263Z","steps":["trace[2062713321] 'range keys from in-memory index tree' (duration: 164.587923ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:36:58.710835Z","caller":"traceutil/trace.go:171","msg":"trace[1019717947] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"154.267022ms","start":"2026-01-29T19:36:58.556542Z","end":"2026-01-29T19:36:58.710809Z","steps":["trace[1019717947] 'process raft request' (duration: 104.089215ms)","trace[1019717947] 'compare' (duration: 49.802075ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:36:58.716748Z","caller":"traceutil/trace.go:171","msg":"trace[611298608] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"133.208429ms","start":"2026-01-29T19:36:58.583522Z","end":"2026-01-29T19:36:58.716731Z","steps":["trace[611298608] 'process raft request' (duration: 133.111566ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:37:05.900864Z","caller":"traceutil/trace.go:171","msg":"trace[592778177] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"116.741881ms","start":"2026-01-29T19:37:05.784107Z","end":"2026-01-29T19:37:05.900849Z","steps":["trace[592778177] 'read index received' (duration: 108.6748ms)","trace[592778177] 'applied index is now lower than readState.Index' (duration: 8.066481ms)"],"step_count":2} {"level":"warn","ts":"2026-01-29T19:37:05.901135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.04319ms","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-01-29T19:37:05.90116Z","caller":"traceutil/trace.go:171","msg":"trace[1842220845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"114.087352ms","start":"2026-01-29T19:37:05.787066Z","end":"2026-01-29T19:37:05.901153Z","steps":["trace[1842220845] 'agreement among raft nodes before linearized reading' (duration: 113.990079ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:37:05.901221Z","caller":"traceutil/trace.go:171","msg":"trace[567031347] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"181.959562ms","start":"2026-01-29T19:37:05.719257Z","end":"2026-01-29T19:37:05.901217Z","steps":["trace[567031347] 'process raft request' (duration: 173.54632ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:37:05.901299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.193284ms","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-01-29T19:37:05.901311Z","caller":"traceutil/trace.go:171","msg":"trace[1940915890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"117.229576ms","start":"2026-01-29T19:37:05.784078Z","end":"2026-01-29T19:37:05.901307Z","steps":["trace[1940915890] 'agreement among raft nodes before linearized reading' (duration: 117.192995ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:37:06.035523Z","caller":"traceutil/trace.go:171","msg":"trace[2135927985] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"131.09056ms","start":"2026-01-29T19:37:05.904414Z","end":"2026-01-29T19:37:06.035504Z","steps":["trace[2135927985] 'process raft request' (duration: 112.038237ms)","trace[2135927985] 'compare' (duration: 18.967411ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:37:06.03657Z","caller":"traceutil/trace.go:171","msg":"trace[595095490] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"130.531063ms","start":"2026-01-29T19:37:05.906022Z","end":"2026-01-29T19:37:06.036553Z","steps":["trace[595095490] 'process raft request' (duration: 130.445071ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:38:26.493176Z","caller":"traceutil/trace.go:171","msg":"trace[1713336839] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"216.643ms","start":"2026-01-29T19:38:26.276436Z","end":"2026-01-29T19:38:26.493079Z","steps":["trace[1713336839] 'process raft request' (duration: 216.336585ms)"],"step_count":1} {"level":"info","ts":"2026-01-29T19:38:26.992486Z","caller":"traceutil/trace.go:171","msg":"trace[1561372851] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"104.757709ms","start":"2026-01-29T19:38:26.887709Z","end":"2026-01-29T19:38:26.992466Z","steps":["trace[1561372851] 'process raft request' (duration: 104.649697ms)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:49.640193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:50.140954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:50.641796Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:51.142279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:51.642386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:52.11683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000294479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-29T19:38:52.116954Z","caller":"traceutil/trace.go:171","msg":"trace[820001461] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00046783s","start":"2026-01-29T19:38:50.116475Z","end":"2026-01-29T19:38:52.116943Z","steps":["trace[820001461] 'agreement among raft nodes before linearized reading' (duration: 2.000335519s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:52.117039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.116464Z","time spent":"2.000549501s","remote":"127.0.0.1:35762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/29 19:38:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T19:38:52.143455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:52.644567Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:53.154155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:53.655093Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:54.117459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0000381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-29T19:38:54.11753Z","caller":"traceutil/trace.go:171","msg":"trace[968897107] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000133271s","start":"2026-01-29T19:38:52.117379Z","end":"2026-01-29T19:38:54.117512Z","steps":["trace[968897107] 'agreement among raft nodes before linearized reading' (duration: 2.00004577s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:54.117988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:52.117373Z","time spent":"2.000438275s","remote":"127.0.0.1:35776","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/01/29 19:38:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T19:38:54.123167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.12348Z","time spent":"4.999682212s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-01-29T19:38:54.156145Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:54.657256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:55.158679Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:55.312532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999706358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-01-29T19:38:55.312627Z","caller":"traceutil/trace.go:171","msg":"trace[1642329939] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999850859s","start":"2026-01-29T19:38:50.312754Z","end":"2026-01-29T19:38:55.312604Z","steps":["trace[1642329939] 'agreement among raft nodes before linearized reading' (duration: 4.999722388s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.312689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.312713Z","time spent":"4.99996611s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/01/29 19:38:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T19:38:55.576588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.00013658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-01-29T19:38:55.576728Z","caller":"traceutil/trace.go:171","msg":"trace[219004019] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000325522s","start":"2026-01-29T19:38:50.576388Z","end":"2026-01-29T19:38:55.576714Z","steps":["trace[219004019] 'agreement among raft nodes before linearized reading' (duration: 5.00017227s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.576801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.576371Z","time spent":"5.000422063s","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/01/29 19:38:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-01-29T19:38:55.659096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5320893056916919123,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-29T19:38:55.802832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.679264377s","expected-duration":"1s"} {"level":"info","ts":"2026-01-29T19:38:55.804785Z","caller":"traceutil/trace.go:171","msg":"trace[473346511] linearizableReadLoop","detail":"{readStateIndex:1167; appliedIndex:1165; }","duration":"6.664887577s","start":"2026-01-29T19:38:49.139876Z","end":"2026-01-29T19:38:55.804764Z","steps":["trace[473346511] 'read index received' (duration: 6.6634699s)","trace[473346511] 'applied index is now lower than readState.Index' (duration: 1.416557ms)"],"step_count":2} {"level":"info","ts":"2026-01-29T19:38:55.804917Z","caller":"traceutil/trace.go:171","msg":"trace[1371602826] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"1.683062647s","start":"2026-01-29T19:38:54.121843Z","end":"2026-01-29T19:38:55.804906Z","steps":["trace[1371602826] 'process raft request' (duration: 1.682852885s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.805005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:54.121821Z","time spent":"1.683131138s","remote":"127.0.0.1:35836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-01-29T19:38:55.805343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.665459094s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wscsj\" ","response":"range_response_count:1 size:4624"} {"level":"info","ts":"2026-01-29T19:38:55.805386Z","caller":"traceutil/trace.go:171","msg":"trace[1753328430] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wscsj; range_end:; response_count:1; response_revision:1112; }","duration":"6.665516575s","start":"2026-01-29T19:38:49.139858Z","end":"2026-01-29T19:38:55.805374Z","steps":["trace[1753328430] 'agreement among raft nodes before linearized reading' (duration: 6.665334343s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.805421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.13985Z","time spent":"6.665564345s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4648,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-wscsj\" "} {"level":"warn","ts":"2026-01-29T19:38:55.806416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.602202905s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329936"} {"level":"info","ts":"2026-01-29T19:38:55.806548Z","caller":"traceutil/trace.go:171","msg":"trace[550310686] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1112; }","duration":"6.602302986s","start":"2026-01-29T19:38:49.204159Z","end":"2026-01-29T19:38:55.806462Z","steps":["trace[550310686] 'agreement among raft nodes before linearized reading' (duration: 6.601419645s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.806599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.204144Z","time spent":"6.602441578s","remote":"127.0.0.1:35866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":4,"response size":329960,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-01-29T19:38:55.810975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.19701949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:38:55.811243Z","caller":"traceutil/trace.go:171","msg":"trace[622224273] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1112; }","duration":"4.197317975s","start":"2026-01-29T19:38:51.613898Z","end":"2026-01-29T19:38:55.811216Z","steps":["trace[622224273] 'agreement among raft nodes before linearized reading' (duration: 4.196988511s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.811364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:51.613883Z","time spent":"4.197467626s","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:38:55.812585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68577913s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:38:55.812702Z","caller":"traceutil/trace.go:171","msg":"trace[280917105] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"1.685935851s","start":"2026-01-29T19:38:54.126754Z","end":"2026-01-29T19:38:55.81269Z","steps":["trace[280917105] 'agreement among raft nodes before linearized reading' (duration: 1.68578672s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.812833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:54.126743Z","time spent":"1.686078743s","remote":"127.0.0.1:35762","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-01-29T19:38:55.813638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.429845723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.84\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-01-29T19:38:55.81376Z","caller":"traceutil/trace.go:171","msg":"trace[1522736231] range","detail":"{range_begin:/registry/masterleases/162.253.55.84; range_end:; response_count:1; response_revision:1112; }","duration":"2.430005974s","start":"2026-01-29T19:38:53.383744Z","end":"2026-01-29T19:38:55.81375Z","steps":["trace[1522736231] 'agreement among raft nodes before linearized reading' (duration: 2.429560919s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.813849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:53.383722Z","time spent":"2.430117135s","remote":"127.0.0.1:35802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.84\" "} {"level":"warn","ts":"2026-01-29T19:38:55.814749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.932450164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-29T19:38:55.814812Z","caller":"traceutil/trace.go:171","msg":"trace[112579196] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1112; }","duration":"3.932525176s","start":"2026-01-29T19:38:51.882272Z","end":"2026-01-29T19:38:55.814797Z","steps":["trace[112579196] 'agreement among raft nodes before linearized reading' (duration: 3.932441635s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.814958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:51.882266Z","time spent":"3.932675947s","remote":"127.0.0.1:36020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:38:55.817755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.711349497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:38:55.817807Z","caller":"traceutil/trace.go:171","msg":"trace[1922322121] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1112; }","duration":"5.711453899s","start":"2026-01-29T19:38:50.106342Z","end":"2026-01-29T19:38:55.817796Z","steps":["trace[1922322121] 'agreement among raft nodes before linearized reading' (duration: 5.711353898s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.817851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.106293Z","time spent":"5.711534839s","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:38:55.818092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.980907653s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-29T19:38:55.818125Z","caller":"traceutil/trace.go:171","msg":"trace[1995321285] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1112; }","duration":"5.980985554s","start":"2026-01-29T19:38:49.83713Z","end":"2026-01-29T19:38:55.818116Z","steps":["trace[1995321285] 'agreement among raft nodes before linearized reading' (duration: 5.980934563s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.818258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.837111Z","time spent":"5.981125235s","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-29T19:38:55.81845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.564343758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-prn5c\" ","response":"range_response_count:1 size:18058"} {"level":"info","ts":"2026-01-29T19:38:55.818473Z","caller":"traceutil/trace.go:171","msg":"trace[917904050] range","detail":"{range_begin:/registry/pods/kube-system/cilium-prn5c; range_end:; response_count:1; response_revision:1112; }","duration":"6.564382578s","start":"2026-01-29T19:38:49.254085Z","end":"2026-01-29T19:38:55.818467Z","steps":["trace[917904050] 'agreement among raft nodes before linearized reading' (duration: 6.564300397s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.818544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.254076Z","time spent":"6.564461199s","remote":"127.0.0.1:35944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18082,"request content":"key:\"/registry/pods/kube-system/cilium-prn5c\" "} {"level":"warn","ts":"2026-01-29T19:38:55.819764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.600736202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 ","response":"range_response_count:37 size:8042"} {"level":"info","ts":"2026-01-29T19:38:55.819792Z","caller":"traceutil/trace.go:171","msg":"trace[1878015634] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/; range_end:/registry/serviceaccounts/kube-system0; response_count:37; response_revision:1112; }","duration":"6.600780172s","start":"2026-01-29T19:38:49.219005Z","end":"2026-01-29T19:38:55.819785Z","steps":["trace[1878015634] 'agreement among raft nodes before linearized reading' (duration: 6.599630688s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.819831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:49.218998Z","time spent":"6.600824713s","remote":"127.0.0.1:35974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":37,"response size":8066,"request content":"key:\"/registry/serviceaccounts/kube-system/\" range_end:\"/registry/serviceaccounts/kube-system0\" limit:500 "} {"level":"warn","ts":"2026-01-29T19:38:55.820444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.201306989s","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-01-29T19:38:55.820483Z","caller":"traceutil/trace.go:171","msg":"trace[796490488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"5.201376589s","start":"2026-01-29T19:38:50.619099Z","end":"2026-01-29T19:38:55.820476Z","steps":["trace[796490488] 'agreement among raft nodes before linearized reading' (duration: 5.198386903s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.820517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.619089Z","time spent":"5.20140656s","remote":"127.0.0.1:36050","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-01-29T19:38:55.821649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.238618002s","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-01-29T19:38:55.821743Z","caller":"traceutil/trace.go:171","msg":"trace[1982201989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"5.238748063s","start":"2026-01-29T19:38:50.582988Z","end":"2026-01-29T19:38:55.821736Z","steps":["trace[1982201989] 'agreement among raft nodes before linearized reading' (duration: 5.234520151s)"],"step_count":1} {"level":"warn","ts":"2026-01-29T19:38:55.822029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-29T19:38:50.58297Z","time spent":"5.238849795s","remote":"127.0.0.1:36050","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\" "}