{"level":"warn","ts":"2026-02-02T17:41:45.205301Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:41:45.205842Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.192:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.192:2380","--initial-cluster=instance=https://199.19.213.192:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.192:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.192:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-02T17:41:45.205955Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-02T17:41:45.206003Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-02-02T17:41:45.206026Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-02T17:41:45.206813Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"]} {"level":"info","ts":"2026-02-02T17:41:45.206911Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.192:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-02T17:41:45.219371Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"12.27476ms"} {"level":"info","ts":"2026-02-02T17:41:45.230536Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"341a3703c39c1f5b","cluster-id":"65341987fa712ba2"} {"level":"info","ts":"2026-02-02T17:41:45.230661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T17:41:45.230714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 0"} {"level":"info","ts":"2026-02-02T17:41:45.230732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 341a3703c39c1f5b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T17:41:45.230749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became follower at term 1"} {"level":"info","ts":"2026-02-02T17:41:45.230833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"warn","ts":"2026-02-02T17:41:45.23682Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T17:41:45.239596Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T17:41:45.242669Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-02T17:41:45.24774Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"341a3703c39c1f5b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T17:41:45.25229Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"341a3703c39c1f5b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T17:41:45.252402Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T17:41:45.252595Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T17:41:45.252604Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-02T17:41:45.252774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b switched to configuration voters=(3754373728672948059)"} {"level":"info","ts":"2026-02-02T17:41:45.252843Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","added-peer-id":"341a3703c39c1f5b","added-peer-peer-urls":["https://199.19.213.192:2380"]} {"level":"info","ts":"2026-02-02T17:41:45.272782Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-02T17:41:45.274788Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"341a3703c39c1f5b","initial-advertise-peer-urls":["https://199.19.213.192:2380"],"listen-peer-urls":["https://199.19.213.192:2380"],"advertise-client-urls":["https://199.19.213.192:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.192:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T17:41:45.274606Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-02-02T17:41:45.2774Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.192:2380"} {"level":"info","ts":"2026-02-02T17:41:45.277312Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T17:41:46.233447Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T17:41:46.233539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T17:41:46.233564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgPreVoteResp from 341a3703c39c1f5b at term 1"} {"level":"info","ts":"2026-02-02T17:41:46.233587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became candidate at term 2"} {"level":"info","ts":"2026-02-02T17:41:46.233617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b received MsgVoteResp from 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-02-02T17:41:46.234025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"341a3703c39c1f5b became leader at term 2"} {"level":"info","ts":"2026-02-02T17:41:46.234065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 341a3703c39c1f5b elected leader 341a3703c39c1f5b at term 2"} {"level":"info","ts":"2026-02-02T17:41:46.235789Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"341a3703c39c1f5b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.192:2379]}","request-path":"/0/members/341a3703c39c1f5b/attributes","cluster-id":"65341987fa712ba2","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T17:41:46.235908Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:46.236892Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T17:41:46.236174Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:46.238527Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T17:41:46.238577Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T17:41:46.242138Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.192:2379"} {"level":"info","ts":"2026-02-02T17:41:46.242297Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65341987fa712ba2","local-member-id":"341a3703c39c1f5b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:46.248288Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:46.248327Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T17:41:46.249451Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-02T17:42:18.806281Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:19.307159Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:19.807967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:20.308721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:20.6016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000370175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:20.601737Z","caller":"traceutil/trace.go:171","msg":"trace[26777329] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000566076s","start":"2026-02-02T17:42:18.601146Z","end":"2026-02-02T17:42:20.601712Z","steps":["trace[26777329] 'agreement among raft nodes before linearized reading' (duration: 2.000397045s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:20.601795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.601128Z","time spent":"2.000655466s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:20.809049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:21.309579Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:21.810258Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:22.311293Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:22.391778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99999819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:22.391884Z","caller":"traceutil/trace.go:171","msg":"trace[1658977751] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000180141s","start":"2026-02-02T17:42:20.391676Z","end":"2026-02-02T17:42:22.391856Z","steps":["trace[1658977751] 'agreement among raft nodes before linearized reading' (duration: 2.00003872s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:22.391977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:20.391647Z","time spent":"2.000317752s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:22.603133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000002829s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:22.603226Z","caller":"traceutil/trace.go:171","msg":"trace[413860703] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000168629s","start":"2026-02-02T17:42:20.60303Z","end":"2026-02-02T17:42:22.603199Z","steps":["trace[413860703] 'agreement among raft nodes before linearized reading' (duration: 2.000044609s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:22.60327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:20.602996Z","time spent":"2.00026491s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:22.811969Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:23.312738Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:23.423513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998807748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:23.423635Z","caller":"traceutil/trace.go:171","msg":"trace[1370668473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999001759s","start":"2026-02-02T17:42:18.424606Z","end":"2026-02-02T17:42:23.423608Z","steps":["trace[1370668473] 'agreement among raft nodes before linearized reading' (duration: 4.998861728s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.423704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.424582Z","time spent":"4.9991085s","remote":"127.0.0.1:58264","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/02/02 17:42:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:23.424836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999554173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:23.424886Z","caller":"traceutil/trace.go:171","msg":"trace[643858571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999624083s","start":"2026-02-02T17:42:18.425246Z","end":"2026-02-02T17:42:23.42487Z","steps":["trace[643858571] 'agreement among raft nodes before linearized reading' (duration: 4.999571423s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:23.424922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.425238Z","time spent":"4.999675383s","remote":"127.0.0.1:58264","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/02/02 17:42:23 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:23.813206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:24.314049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:24.609206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999864324s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:24.609306Z","caller":"traceutil/trace.go:171","msg":"trace[1992798267] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000025985s","start":"2026-02-02T17:42:22.609258Z","end":"2026-02-02T17:42:24.609283Z","steps":["trace[1992798267] 'agreement among raft nodes before linearized reading' (duration: 1.999891814s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:24.60938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.609239Z","time spent":"2.000114085s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:24.815207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665304,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:25.305046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.303981Z","time spent":"7.001057647s","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:25.306344Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-02T17:42:25.306556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000467842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-02T17:42:25.306639Z","caller":"traceutil/trace.go:171","msg":"trace[665193606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"7.000611454s","start":"2026-02-02T17:42:18.306006Z","end":"2026-02-02T17:42:25.306617Z","steps":["trace[665193606] 'agreement among raft nodes before linearized reading' (duration: 7.000485383s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:25.306728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.30599Z","time spent":"7.000720675s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:25.782995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.782401Z","time spent":"7.000556434s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:25.807202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:26.308046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:26.611485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000586009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:26.611584Z","caller":"traceutil/trace.go:171","msg":"trace[93802724] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00074573s","start":"2026-02-02T17:42:24.610811Z","end":"2026-02-02T17:42:26.611557Z","steps":["trace[93802724] 'agreement among raft nodes before linearized reading' (duration: 2.000616129s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:26.611646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:24.61079Z","time spent":"2.000845801s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:26.808937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:27.309718Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:27.810623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:28.305841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.432786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:28.306003Z","caller":"traceutil/trace.go:171","msg":"trace[884507365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"990.670907ms","start":"2026-02-02T17:42:27.315306Z","end":"2026-02-02T17:42:28.305977Z","steps":["trace[884507365] 'agreement among raft nodes before linearized reading' (duration: 990.409626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:28.306106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:27.315282Z","time spent":"990.809818ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/02 17:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:28.311421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:28.322644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999000002s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:28.3227Z","caller":"traceutil/trace.go:171","msg":"trace[1463355232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999096773s","start":"2026-02-02T17:42:18.32359Z","end":"2026-02-02T17:42:28.322686Z","steps":["trace[1463355232] 'agreement among raft nodes before linearized reading' (duration: 9.999036732s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:28.322749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:18.323568Z","time spent":"9.999172193s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/02 17:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:28.423775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997268389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:28.423832Z","caller":"traceutil/trace.go:171","msg":"trace[1489765100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.997333719s","start":"2026-02-02T17:42:25.426485Z","end":"2026-02-02T17:42:28.423818Z","steps":["trace[1489765100] 'agreement among raft nodes before linearized reading' (duration: 2.997267289s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:28.423951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:25.426401Z","time spent":"2.99753677s","remote":"127.0.0.1:58264","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/02/02 17:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:28.425746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.996924836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:28.426113Z","caller":"traceutil/trace.go:171","msg":"trace[40763554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.997335779s","start":"2026-02-02T17:42:25.428763Z","end":"2026-02-02T17:42:28.426099Z","steps":["trace[40763554] 'agreement among raft nodes before linearized reading' (duration: 2.996966957s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:28.426765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:25.428736Z","time spent":"2.99751055s","remote":"127.0.0.1:58264","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/02/02 17:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/02 17:42:28 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:28.620458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000273676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:28.620572Z","caller":"traceutil/trace.go:171","msg":"trace[1458638794] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000452237s","start":"2026-02-02T17:42:26.620091Z","end":"2026-02-02T17:42:28.620543Z","steps":["trace[1458638794] 'agreement among raft nodes before linearized reading' (duration: 2.000307926s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:28.620625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:26.620064Z","time spent":"2.000549937s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:28.812275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665310,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:28.893469Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"10.589350992s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:29.260281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.259768Z","time spent":"7.000504745s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:29.396861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:22.396229Z","time spent":"7.000625683s","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:30.621532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000638149s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:30.621641Z","caller":"traceutil/trace.go:171","msg":"trace[103333653] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.0008197s","start":"2026-02-02T17:42:28.620779Z","end":"2026-02-02T17:42:30.621598Z","steps":["trace[103333653] 'agreement among raft nodes before linearized reading' (duration: 2.0007063s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:30.621692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:28.62076Z","time spent":"2.00092219s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:31.882318Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"1f5b9c1f723892ec","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-02T17:42:32.315722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:25.314724Z","time spent":"7.000992163s","remote":"127.0.0.1:58156","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:32.392128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00073239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:32.392307Z","caller":"traceutil/trace.go:171","msg":"trace[1614935482] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000963591s","start":"2026-02-02T17:42:30.391319Z","end":"2026-02-02T17:42:32.392282Z","steps":["trace[1614935482] 'agreement among raft nodes before linearized reading' (duration: 2.00075649s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:32.392385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:30.391297Z","time spent":"2.001070072s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:32.629519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000757123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:32.629656Z","caller":"traceutil/trace.go:171","msg":"trace[833580431] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000933354s","start":"2026-02-02T17:42:30.628695Z","end":"2026-02-02T17:42:32.629628Z","steps":["trace[833580431] 'agreement among raft nodes before linearized reading' (duration: 2.000770483s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:32.629724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:30.628685Z","time spent":"2.001022844s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:32 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:32.787369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:25.786812Z","time spent":"7.000547951s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:33.425696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:28.429454Z","time spent":"4.996235785s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/02 17:42:33 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:34.638242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000766183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:34.638341Z","caller":"traceutil/trace.go:171","msg":"trace[439340289] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000927943s","start":"2026-02-02T17:42:32.63739Z","end":"2026-02-02T17:42:34.638318Z","steps":["trace[439340289] 'agreement among raft nodes before linearized reading' (duration: 2.000789303s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:34.638402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:32.637374Z","time spent":"2.001012983s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:35.310188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:28.309169Z","time spent":"7.00099784s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:35.318928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:28.324794Z","time spent":"6.994122968s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/02 17:42:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:36.267638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:29.266638Z","time spent":"7.000990221s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:36.401473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:29.400858Z","time spent":"7.0006056s","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:36.63934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999908785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:36.639453Z","caller":"traceutil/trace.go:171","msg":"trace[1171594523] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000082505s","start":"2026-02-02T17:42:34.639341Z","end":"2026-02-02T17:42:36.639424Z","steps":["trace[1171594523] 'agreement among raft nodes before linearized reading' (duration: 1.999933915s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:36.639514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:34.639323Z","time spent":"2.000180535s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:36 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:38.533474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:30.275895Z","time spent":"8.257571588s","remote":"127.0.0.1:50874","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:38.534424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.470313653s","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-02-02T17:42:38.534516Z","caller":"traceutil/trace.go:171","msg":"trace[1714374652] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:514; }","duration":"4.470449254s","start":"2026-02-02T17:42:34.064047Z","end":"2026-02-02T17:42:38.534496Z","steps":["trace[1714374652] 'range keys from in-memory index tree' (duration: 4.470219923s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:38.64677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000518644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:42:38.646871Z","caller":"traceutil/trace.go:171","msg":"trace[1509025519] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000687204s","start":"2026-02-02T17:42:36.646161Z","end":"2026-02-02T17:42:38.646848Z","steps":["trace[1509025519] 'agreement among raft nodes before linearized reading' (duration: 2.000566184s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:38.646917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:36.646134Z","time spent":"2.000774394s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:38.888503Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"1f5b9c1f723892ec","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-02T17:42:39.792329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:32.791441Z","time spent":"7.000879958s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-02T17:42:40.012763Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"11.119032261s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:42:40.477941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.114787ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T17:42:40.48036Z","caller":"traceutil/trace.go:171","msg":"trace[1921485591] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:530; }","duration":"15.173847335s","start":"2026-02-02T17:42:25.306458Z","end":"2026-02-02T17:42:40.480306Z","steps":["trace[1921485591] 'read index received' (duration: 3.590405168s)","trace[1921485591] 'applied index is now lower than readState.Index' (duration: 11.583440587s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:40.480493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"21.075803219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:40.480545Z","caller":"traceutil/trace.go:171","msg":"trace[1993743442] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:0; response_revision:522; }","duration":"21.07591652s","start":"2026-02-02T17:42:19.40461Z","end":"2026-02-02T17:42:40.480527Z","steps":["trace[1993743442] 'agreement among raft nodes before linearized reading' (duration: 21.075801029s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.480602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:19.404589Z","time spent":"21.07600441s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"warn","ts":"2026-02-02T17:42:40.53924Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"40.36µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-02-02T17:42:40.539353Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"15.28µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-02-02T17:42:40.53945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.913886611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-02T17:42:40.539476Z","caller":"traceutil/trace.go:171","msg":"trace[153718343] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:523; }","duration":"9.913940571s","start":"2026-02-02T17:42:30.625522Z","end":"2026-02-02T17:42:40.539463Z","steps":["trace[153718343] 'agreement among raft nodes before linearized reading' (duration: 9.913881911s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:40.539433Z","caller":"traceutil/trace.go:171","msg":"trace[478604743] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"4.26026911s","start":"2026-02-02T17:42:36.27913Z","end":"2026-02-02T17:42:40.539399Z","steps":["trace[478604743] 'process raft request' (duration: 4.26017401s)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:40.539555Z","caller":"traceutil/trace.go:171","msg":"trace[1889619338] transaction","detail":"{read_only:false; number_of_response:1; response_revision:523; }","duration":"741.01134ms","start":"2026-02-02T17:42:39.798536Z","end":"2026-02-02T17:42:40.539547Z","steps":["trace[1889619338] 'process raft request' (duration: 740.83589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.539602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.301982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:40.539619Z","caller":"traceutil/trace.go:171","msg":"trace[470269249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"148.385492ms","start":"2026-02-02T17:42:40.391227Z","end":"2026-02-02T17:42:40.539613Z","steps":["trace[470269249] 'agreement among raft nodes before linearized reading' (duration: 148.357292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.539774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.89229594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:40.539793Z","caller":"traceutil/trace.go:171","msg":"trace[1760166445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:523; }","duration":"1.89235426s","start":"2026-02-02T17:42:38.647432Z","end":"2026-02-02T17:42:40.539786Z","steps":["trace[1760166445] 'agreement among raft nodes before linearized reading' (duration: 1.8923249s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.539811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:38.64741Z","time spent":"1.89239446s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:40.539937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.11818637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-02T17:42:40.539956Z","caller":"traceutil/trace.go:171","msg":"trace[427688175] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:523; }","duration":"2.11824226s","start":"2026-02-02T17:42:38.421708Z","end":"2026-02-02T17:42:40.53995Z","steps":["trace[427688175] 'agreement among raft nodes before linearized reading' (duration: 2.11819197s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.539977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:38.421659Z","time spent":"2.11831245s","remote":"127.0.0.1:58100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":4,"response size":1405,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-02-02T17:42:40.540083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.133363205s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-02T17:42:40.540105Z","caller":"traceutil/trace.go:171","msg":"trace[2040499168] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad; range_end:; response_count:1; response_revision:523; }","duration":"4.133420355s","start":"2026-02-02T17:42:36.406677Z","end":"2026-02-02T17:42:40.540097Z","steps":["trace[2040499168] 'agreement among raft nodes before linearized reading' (duration: 4.133386365s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:40.540123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:36.406655Z","time spent":"4.133463415s","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" "} {"level":"warn","ts":"2026-02-02T17:42:40.539504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:30.625504Z","time spent":"9.913994681s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7067,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:40.539526Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"1f5b9c1f723892ec","error":"lease not found"} {"level":"warn","ts":"2026-02-02T17:42:40.544881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:36.279099Z","time spent":"4.26046062s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":568,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:40.54743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:39.798514Z","time spent":"741.06168ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:41.064176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.068873ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-02-02T17:42:41.064466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.482122Z","time spent":"582.339655ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-02T17:42:41.064726Z","caller":"traceutil/trace.go:171","msg":"trace[1960329363] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:547; }","duration":"503.347125ms","start":"2026-02-02T17:42:40.561364Z","end":"2026-02-02T17:42:41.064711Z","steps":["trace[1960329363] 'read index received' (duration: 110.521211ms)","trace[1960329363] 'applied index is now lower than readState.Index' (duration: 392.824914ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:41.064914Z","caller":"traceutil/trace.go:171","msg":"trace[183172379] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"521.735736ms","start":"2026-02-02T17:42:40.543105Z","end":"2026-02-02T17:42:41.064841Z","steps":["trace[183172379] 'process raft request' (duration: 521.262035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.065028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.543086Z","time spent":"521.866216ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:41.065309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.621326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:41.065382Z","caller":"traceutil/trace.go:171","msg":"trace[1932442927] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"503.719136ms","start":"2026-02-02T17:42:40.561644Z","end":"2026-02-02T17:42:41.065363Z","steps":["trace[1932442927] 'agreement among raft nodes before linearized reading' (duration: 503.584866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.065422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.561638Z","time spent":"503.775997ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:41.065596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.706531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:41.065625Z","caller":"traceutil/trace.go:171","msg":"trace[351639036] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:525; }","duration":"451.776271ms","start":"2026-02-02T17:42:40.613838Z","end":"2026-02-02T17:42:41.065615Z","steps":["trace[351639036] 'agreement among raft nodes before linearized reading' (duration: 451.733721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.065637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.266807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"warn","ts":"2026-02-02T17:42:41.065654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.613816Z","time spent":"451.832121ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" "} {"level":"info","ts":"2026-02-02T17:42:41.065675Z","caller":"traceutil/trace.go:171","msg":"trace[1795557413] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:525; }","duration":"504.323037ms","start":"2026-02-02T17:42:40.561339Z","end":"2026-02-02T17:42:41.065662Z","steps":["trace[1795557413] 'agreement among raft nodes before linearized reading' (duration: 504.199147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.065709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.561324Z","time spent":"504.378167ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":709,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-02T17:42:41.06585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.915318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-02T17:42:41.065863Z","caller":"traceutil/trace.go:171","msg":"trace[881021157] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"507.646742ms","start":"2026-02-02T17:42:40.558206Z","end":"2026-02-02T17:42:41.065853Z","steps":["trace[881021157] 'process raft request' (duration: 506.45374ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:41.065877Z","caller":"traceutil/trace.go:171","msg":"trace[1362101607] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:525; }","duration":"498.967498ms","start":"2026-02-02T17:42:40.566902Z","end":"2026-02-02T17:42:41.065869Z","steps":["trace[1362101607] 'agreement among raft nodes before linearized reading' (duration: 498.845528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.065904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.566881Z","time spent":"499.018268ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-02T17:42:41.065922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:40.558155Z","time spent":"507.733022ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:41.37511Z","caller":"traceutil/trace.go:171","msg":"trace[1458514489] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"299.373862ms","start":"2026-02-02T17:42:41.075707Z","end":"2026-02-02T17:42:41.375081Z","steps":["trace[1458514489] 'process raft request' (duration: 299.225062ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:41.375527Z","caller":"traceutil/trace.go:171","msg":"trace[1170070562] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:551; }","duration":"291.378749ms","start":"2026-02-02T17:42:41.084137Z","end":"2026-02-02T17:42:41.375516Z","steps":["trace[1170070562] 'read index received' (duration: 291.374329ms)","trace[1170070562] 'applied index is now lower than readState.Index' (duration: 3.56µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:41.375723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.565989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-02T17:42:41.375764Z","caller":"traceutil/trace.go:171","msg":"trace[1234050064] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad; range_end:; response_count:1; response_revision:526; }","duration":"291.653699ms","start":"2026-02-02T17:42:41.084096Z","end":"2026-02-02T17:42:41.37575Z","steps":["trace[1234050064] 'agreement among raft nodes before linearized reading' (duration: 291.483499ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:41.461174Z","caller":"traceutil/trace.go:171","msg":"trace[1955842134] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"376.904852ms","start":"2026-02-02T17:42:41.08424Z","end":"2026-02-02T17:42:41.461145Z","steps":["trace[1955842134] 'process raft request' (duration: 376.687582ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.461334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.084229Z","time spent":"377.022512ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:41.461466Z","caller":"traceutil/trace.go:171","msg":"trace[1471779959] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"376.206701ms","start":"2026-02-02T17:42:41.085249Z","end":"2026-02-02T17:42:41.461456Z","steps":["trace[1471779959] 'process raft request' (duration: 375.81174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.461523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.085242Z","time spent":"376.251301ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:41.461727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.243335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:41.461773Z","caller":"traceutil/trace.go:171","msg":"trace[170439492] range","detail":"{range_begin:/registry/secrets/kube-node-lease/; range_end:/registry/secrets/kube-node-lease0; response_count:0; response_revision:528; }","duration":"331.329445ms","start":"2026-02-02T17:42:41.130432Z","end":"2026-02-02T17:42:41.461761Z","steps":["trace[170439492] 'agreement among raft nodes before linearized reading' (duration: 331.156185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.461808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.130414Z","time spent":"331.386195ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-node-lease/\" range_end:\"/registry/secrets/kube-node-lease0\" "} {"level":"warn","ts":"2026-02-02T17:42:41.462139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.953104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7237"} {"level":"info","ts":"2026-02-02T17:42:41.462179Z","caller":"traceutil/trace.go:171","msg":"trace[1934668908] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:528; }","duration":"378.002784ms","start":"2026-02-02T17:42:41.084167Z","end":"2026-02-02T17:42:41.462169Z","steps":["trace[1934668908] 'agreement among raft nodes before linearized reading' (duration: 377.808923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.462215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.084162Z","time spent":"378.045614ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7259,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-02T17:42:41.773774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.420924ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:41.773891Z","caller":"traceutil/trace.go:171","msg":"trace[623744629] linearizableReadLoop","detail":"{readStateIndex:554; appliedIndex:553; }","duration":"312.768614ms","start":"2026-02-02T17:42:41.461107Z","end":"2026-02-02T17:42:41.773875Z","steps":["trace[623744629] 'read index received' (duration: 107.17677ms)","trace[623744629] 'applied index is now lower than readState.Index' (duration: 205.590804ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:41.773973Z","caller":"traceutil/trace.go:171","msg":"trace[1155072804] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"396.271315ms","start":"2026-02-02T17:42:41.377689Z","end":"2026-02-02T17:42:41.77396Z","steps":["trace[1155072804] 'process raft request' (duration: 190.58443ms)","trace[1155072804] 'compare' (duration: 205.318884ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:41.774032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.377676Z","time spent":"396.326045ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:41.774253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.911305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-02T17:42:41.77429Z","caller":"traceutil/trace.go:171","msg":"trace[1534993687] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:529; }","duration":"396.979465ms","start":"2026-02-02T17:42:41.377299Z","end":"2026-02-02T17:42:41.774278Z","steps":["trace[1534993687] 'agreement among raft nodes before linearized reading' (duration: 396.893255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.774331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.377283Z","time spent":"397.040125ms","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-02-02T17:42:41.940509Z","caller":"traceutil/trace.go:171","msg":"trace[321342988] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"468.641875ms","start":"2026-02-02T17:42:41.471849Z","end":"2026-02-02T17:42:41.940491Z","steps":["trace[321342988] 'process raft request' (duration: 442.503892ms)","trace[321342988] 'compare' (duration: 25.996103ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:41.940695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.471827Z","time spent":"468.788866ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7030,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:41.942187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.097183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:41.942213Z","caller":"traceutil/trace.go:171","msg":"trace[1968511609] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"342.163673ms","start":"2026-02-02T17:42:41.600042Z","end":"2026-02-02T17:42:41.942205Z","steps":["trace[1968511609] 'agreement among raft nodes before linearized reading' (duration: 342.121713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.94223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.600021Z","time spent":"342.205893ms","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T17:42:41.942349Z","caller":"traceutil/trace.go:171","msg":"trace[2013367003] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"166.526339ms","start":"2026-02-02T17:42:41.77393Z","end":"2026-02-02T17:42:41.940457Z","steps":["trace[2013367003] 'read index received' (duration: 140.363016ms)","trace[2013367003] 'applied index is now lower than readState.Index' (duration: 26.162563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:41.942414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.507075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:41.942427Z","caller":"traceutil/trace.go:171","msg":"trace[514706062] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:530; }","duration":"432.554255ms","start":"2026-02-02T17:42:41.509869Z","end":"2026-02-02T17:42:41.942423Z","steps":["trace[514706062] 'agreement among raft nodes before linearized reading' (duration: 432.529585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.942442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:41.509852Z","time spent":"432.584645ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" "} {"level":"warn","ts":"2026-02-02T17:42:41.942593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.911078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:42:41.942609Z","caller":"traceutil/trace.go:171","msg":"trace[987148377] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:530; }","duration":"165.985188ms","start":"2026-02-02T17:42:41.77662Z","end":"2026-02-02T17:42:41.942605Z","steps":["trace[987148377] 'agreement among raft nodes before linearized reading' (duration: 165.913878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:41.942698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.821764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd468bb147\" ","response":"range_response_count:1 size:773"} {"level":"info","ts":"2026-02-02T17:42:41.942711Z","caller":"traceutil/trace.go:171","msg":"trace[449527676] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edd468bb147; range_end:; response_count:1; response_revision:530; }","duration":"163.856464ms","start":"2026-02-02T17:42:41.77885Z","end":"2026-02-02T17:42:41.942706Z","steps":["trace[449527676] 'agreement among raft nodes before linearized reading' (duration: 163.816314ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:42.261513Z","caller":"traceutil/trace.go:171","msg":"trace[878713257] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"251.593552ms","start":"2026-02-02T17:42:42.009895Z","end":"2026-02-02T17:42:42.261489Z","steps":["trace[878713257] 'process raft request' (duration: 251.414281ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:42.263315Z","caller":"traceutil/trace.go:171","msg":"trace[1682407189] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:558; }","duration":"230.333245ms","start":"2026-02-02T17:42:42.032973Z","end":"2026-02-02T17:42:42.263306Z","steps":["trace[1682407189] 'read index received' (duration: 230.329305ms)","trace[1682407189] 'applied index is now lower than readState.Index' (duration: 3.29µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:42.263558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.876604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-02T17:42:42.263582Z","caller":"traceutil/trace.go:171","msg":"trace[1336446850] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:532; }","duration":"252.913284ms","start":"2026-02-02T17:42:42.010658Z","end":"2026-02-02T17:42:42.263571Z","steps":["trace[1336446850] 'agreement among raft nodes before linearized reading' (duration: 252.678394ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:42.313822Z","caller":"traceutil/trace.go:171","msg":"trace[138701591] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"279.59318ms","start":"2026-02-02T17:42:42.034196Z","end":"2026-02-02T17:42:42.313789Z","steps":["trace[138701591] 'process raft request' (duration: 279.368609ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:42.314137Z","caller":"traceutil/trace.go:171","msg":"trace[198514212] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"279.79238ms","start":"2026-02-02T17:42:42.034323Z","end":"2026-02-02T17:42:42.314116Z","steps":["trace[198514212] 'process raft request' (duration: 279.429709ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:42.629767Z","caller":"traceutil/trace.go:171","msg":"trace[400769996] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"304.266582ms","start":"2026-02-02T17:42:42.32547Z","end":"2026-02-02T17:42:42.629736Z","steps":["trace[400769996] 'process raft request' (duration: 304.155482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.629893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.325455Z","time spent":"304.378932ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":859,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-02T17:42:42.629875Z","caller":"traceutil/trace.go:171","msg":"trace[1437110310] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:561; }","duration":"302.490639ms","start":"2026-02-02T17:42:42.327338Z","end":"2026-02-02T17:42:42.629829Z","steps":["trace[1437110310] 'read index received' (duration: 302.476129ms)","trace[1437110310] 'applied index is now lower than readState.Index' (duration: 8.68µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:42.630093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.715609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-02T17:42:42.63013Z","caller":"traceutil/trace.go:171","msg":"trace[177540453] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:535; }","duration":"302.792109ms","start":"2026-02-02T17:42:42.327328Z","end":"2026-02-02T17:42:42.63012Z","steps":["trace[177540453] 'agreement among raft nodes before linearized reading' (duration: 302.635999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.63018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.327322Z","time spent":"302.83854ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-02-02T17:42:42.860333Z","caller":"traceutil/trace.go:171","msg":"trace[940354532] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:561; }","duration":"230.294016ms","start":"2026-02-02T17:42:42.629941Z","end":"2026-02-02T17:42:42.860235Z","steps":["trace[940354532] 'read index received' (duration: 220.716109ms)","trace[940354532] 'applied index is now lower than readState.Index' (duration: 9.577487ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:42.860659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.08321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-02T17:42:42.860681Z","caller":"traceutil/trace.go:171","msg":"trace[1264879906] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad; range_end:; response_count:1; response_revision:536; }","duration":"227.16033ms","start":"2026-02-02T17:42:42.633514Z","end":"2026-02-02T17:42:42.860675Z","steps":["trace[1264879906] 'agreement among raft nodes before linearized reading' (duration: 227.04881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.860682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.624196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-02T17:42:42.860749Z","caller":"traceutil/trace.go:171","msg":"trace[807140757] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:536; }","duration":"481.752487ms","start":"2026-02-02T17:42:42.378981Z","end":"2026-02-02T17:42:42.860733Z","steps":["trace[807140757] 'agreement among raft nodes before linearized reading' (duration: 481.435836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.860781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.37896Z","time spent":"481.816067ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329962,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-02T17:42:42.861008Z","caller":"traceutil/trace.go:171","msg":"trace[2112360516] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"533.632146ms","start":"2026-02-02T17:42:42.327368Z","end":"2026-02-02T17:42:42.861Z","steps":["trace[2112360516] 'process raft request' (duration: 523.336658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:42.861065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.32736Z","time spent":"533.661456ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4347,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:43.266567Z","caller":"traceutil/trace.go:171","msg":"trace[1569705978] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"364.458247ms","start":"2026-02-02T17:42:42.902086Z","end":"2026-02-02T17:42:43.266544Z","steps":["trace[1569705978] 'process raft request' (duration: 364.357056ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:43.266698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.90208Z","time spent":"364.541197ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4865,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:43.418278Z","caller":"traceutil/trace.go:171","msg":"trace[484965623] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"465.200195ms","start":"2026-02-02T17:42:42.953058Z","end":"2026-02-02T17:42:43.418258Z","steps":["trace[484965623] 'read index received' (duration: 313.950679ms)","trace[484965623] 'applied index is now lower than readState.Index' (duration: 151.248886ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:43.418416Z","caller":"traceutil/trace.go:171","msg":"trace[868073200] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"486.467771ms","start":"2026-02-02T17:42:42.931914Z","end":"2026-02-02T17:42:43.418382Z","steps":["trace[868073200] 'process raft request' (duration: 486.267071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:43.418515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.437336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329940"} {"level":"info","ts":"2026-02-02T17:42:43.418536Z","caller":"traceutil/trace.go:171","msg":"trace[1566205081] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:540; }","duration":"465.506966ms","start":"2026-02-02T17:42:42.953023Z","end":"2026-02-02T17:42:43.41853Z","steps":["trace[1566205081] 'agreement among raft nodes before linearized reading' (duration: 465.311955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:43.418556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.953004Z","time spent":"465.546456ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329962,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"warn","ts":"2026-02-02T17:42:43.418548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:42.931889Z","time spent":"486.594862ms","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":746,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:42:45.608297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.361375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:45.608567Z","caller":"traceutil/trace.go:171","msg":"trace[933677967] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"179.85213ms","start":"2026-02-02T17:42:45.428703Z","end":"2026-02-02T17:42:45.608556Z","steps":["trace[933677967] 'process raft request' (duration: 179.81502ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:45.608671Z","caller":"traceutil/trace.go:171","msg":"trace[87857658] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"211.085767ms","start":"2026-02-02T17:42:45.397558Z","end":"2026-02-02T17:42:45.608644Z","steps":["trace[87857658] 'process raft request' (duration: 104.312711ms)","trace[87857658] 'compare' (duration: 106.236615ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:45.608891Z","caller":"traceutil/trace.go:171","msg":"trace[1413506218] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"195.75748ms","start":"2026-02-02T17:42:45.413127Z","end":"2026-02-02T17:42:45.608884Z","steps":["trace[1413506218] 'read index received' (duration: 12.710003ms)","trace[1413506218] 'applied index is now lower than readState.Index' (duration: 183.045417ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:45.609057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.91022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-02-02T17:42:45.609085Z","caller":"traceutil/trace.go:171","msg":"trace[1177835886] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:549; }","duration":"195.98694ms","start":"2026-02-02T17:42:45.413092Z","end":"2026-02-02T17:42:45.609079Z","steps":["trace[1177835886] 'agreement among raft nodes before linearized reading' (duration: 195.89345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.609075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.150756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4225"} {"level":"info","ts":"2026-02-02T17:42:45.609234Z","caller":"traceutil/trace.go:171","msg":"trace[954321301] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:549; }","duration":"188.311566ms","start":"2026-02-02T17:42:45.420903Z","end":"2026-02-02T17:42:45.609215Z","steps":["trace[954321301] 'agreement among raft nodes before linearized reading' (duration: 188.102586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.609407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.790325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:429"} {"level":"info","ts":"2026-02-02T17:42:45.60949Z","caller":"traceutil/trace.go:171","msg":"trace[1012184055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:549; }","duration":"149.879685ms","start":"2026-02-02T17:42:45.459603Z","end":"2026-02-02T17:42:45.609483Z","steps":["trace[1012184055] 'agreement among raft nodes before linearized reading' (duration: 149.765594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.60967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.072005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:425"} {"level":"info","ts":"2026-02-02T17:42:45.60976Z","caller":"traceutil/trace.go:171","msg":"trace[1379118433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"150.188426ms","start":"2026-02-02T17:42:45.459564Z","end":"2026-02-02T17:42:45.609752Z","steps":["trace[1379118433] 'agreement among raft nodes before linearized reading' (duration: 150.050176ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:45.805086Z","caller":"traceutil/trace.go:171","msg":"trace[1390839347] linearizableReadLoop","detail":"{readStateIndex:576; appliedIndex:575; }","duration":"156.707658ms","start":"2026-02-02T17:42:45.648356Z","end":"2026-02-02T17:42:45.805064Z","steps":["trace[1390839347] 'read index received' (duration: 116.118193ms)","trace[1390839347] 'applied index is now lower than readState.Index' (duration: 40.588925ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:45.805232Z","caller":"traceutil/trace.go:171","msg":"trace[1875295057] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"167.821038ms","start":"2026-02-02T17:42:45.637405Z","end":"2026-02-02T17:42:45.805226Z","steps":["trace[1875295057] 'process raft request' (duration: 127.056143ms)","trace[1875295057] 'compare' (duration: 35.124705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:45.805367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.990708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:45.805385Z","caller":"traceutil/trace.go:171","msg":"trace[1417564586] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:550; }","duration":"157.058608ms","start":"2026-02-02T17:42:45.648321Z","end":"2026-02-02T17:42:45.80538Z","steps":["trace[1417564586] 'agreement among raft nodes before linearized reading' (duration: 157.006408ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:45.807337Z","caller":"traceutil/trace.go:171","msg":"trace[1313639213] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"121.821874ms","start":"2026-02-02T17:42:45.685504Z","end":"2026-02-02T17:42:45.807326Z","steps":["trace[1313639213] 'process raft request' (duration: 120.127181ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:45.809601Z","caller":"traceutil/trace.go:171","msg":"trace[465472065] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"107.616668ms","start":"2026-02-02T17:42:45.701976Z","end":"2026-02-02T17:42:45.809593Z","steps":["trace[465472065] 'process raft request' (duration: 103.697841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:45.810742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.998223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:4 size:1383"} {"level":"info","ts":"2026-02-02T17:42:45.810859Z","caller":"traceutil/trace.go:171","msg":"trace[308816770] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:4; response_revision:552; }","duration":"105.151083ms","start":"2026-02-02T17:42:45.705699Z","end":"2026-02-02T17:42:45.81085Z","steps":["trace[308816770] 'agreement among raft nodes before linearized reading' (duration: 99.990624ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:45.953695Z","caller":"traceutil/trace.go:171","msg":"trace[1096521759] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"103.63939ms","start":"2026-02-02T17:42:45.85003Z","end":"2026-02-02T17:42:45.95367Z","steps":["trace[1096521759] 'process raft request' (duration: 75.582339ms)","trace[1096521759] 'compare' (duration: 27.41901ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:45.955009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.685695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"info","ts":"2026-02-02T17:42:45.960443Z","caller":"traceutil/trace.go:171","msg":"trace[581640270] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:553; }","duration":"106.130655ms","start":"2026-02-02T17:42:45.854285Z","end":"2026-02-02T17:42:45.960416Z","steps":["trace[581640270] 'agreement among raft nodes before linearized reading' (duration: 100.648175ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.782061Z","caller":"traceutil/trace.go:171","msg":"trace[1356152211] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"138.376229ms","start":"2026-02-02T17:42:46.643662Z","end":"2026-02-02T17:42:46.782038Z","steps":["trace[1356152211] 'process raft request' (duration: 138.345569ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.782842Z","caller":"traceutil/trace.go:171","msg":"trace[1587958843] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"139.231821ms","start":"2026-02-02T17:42:46.64359Z","end":"2026-02-02T17:42:46.782822Z","steps":["trace[1587958843] 'process raft request' (duration: 138.357249ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.783243Z","caller":"traceutil/trace.go:171","msg":"trace[2043756790] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"139.593672ms","start":"2026-02-02T17:42:46.643637Z","end":"2026-02-02T17:42:46.783231Z","steps":["trace[2043756790] 'process raft request' (duration: 138.344849ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.783528Z","caller":"traceutil/trace.go:171","msg":"trace[1412879880] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"140.264053ms","start":"2026-02-02T17:42:46.643251Z","end":"2026-02-02T17:42:46.783515Z","steps":["trace[1412879880] 'process raft request' (duration: 80.794452ms)","trace[1412879880] 'compare' (duration: 57.798288ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:46.784931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.571035ms","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-02-02T17:42:46.78515Z","caller":"traceutil/trace.go:171","msg":"trace[2135862482] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"141.827325ms","start":"2026-02-02T17:42:46.643301Z","end":"2026-02-02T17:42:46.785129Z","steps":["trace[2135862482] 'agreement among raft nodes before linearized reading' (duration: 141.485485ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:46.785772Z","caller":"traceutil/trace.go:171","msg":"trace[904171655] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"139.421241ms","start":"2026-02-02T17:42:46.643332Z","end":"2026-02-02T17:42:46.782753Z","steps":["trace[904171655] 'read index received' (duration: 80.831661ms)","trace[904171655] 'applied index is now lower than readState.Index' (duration: 58.58709ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:46.787204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.202772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-02T17:42:46.787277Z","caller":"traceutil/trace.go:171","msg":"trace[1718987086] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:560; }","duration":"113.327542ms","start":"2026-02-02T17:42:46.673933Z","end":"2026-02-02T17:42:46.787261Z","steps":["trace[1718987086] 'agreement among raft nodes before linearized reading' (duration: 112.947432ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:47.005762Z","caller":"traceutil/trace.go:171","msg":"trace[1331447622] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:586; }","duration":"132.546119ms","start":"2026-02-02T17:42:46.873146Z","end":"2026-02-02T17:42:47.005692Z","steps":["trace[1331447622] 'read index received' (duration: 71.554014ms)","trace[1331447622] 'applied index is now lower than readState.Index' (duration: 60.991355ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:47.006007Z","caller":"traceutil/trace.go:171","msg":"trace[957155961] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"199.958645ms","start":"2026-02-02T17:42:46.806018Z","end":"2026-02-02T17:42:47.005977Z","steps":["trace[957155961] 'process raft request' (duration: 138.765821ms)","trace[957155961] 'compare' (duration: 60.491893ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:47.006068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.90484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/kubeadm:get-nodes\" ","response":"range_response_count:1 size:305"} {"level":"info","ts":"2026-02-02T17:42:47.006216Z","caller":"traceutil/trace.go:171","msg":"trace[560530608] range","detail":"{range_begin:/registry/clusterroles/kubeadm:get-nodes; range_end:; response_count:1; response_revision:561; }","duration":"133.10009ms","start":"2026-02-02T17:42:46.873104Z","end":"2026-02-02T17:42:47.006204Z","steps":["trace[560530608] 'agreement among raft nodes before linearized reading' (duration: 132.891589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:47.006369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.841705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:1 size:840"} {"level":"info","ts":"2026-02-02T17:42:47.006438Z","caller":"traceutil/trace.go:171","msg":"trace[84296975] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:1; response_revision:561; }","duration":"130.939005ms","start":"2026-02-02T17:42:46.875467Z","end":"2026-02-02T17:42:47.006405Z","steps":["trace[84296975] 'agreement among raft nodes before linearized reading' (duration: 130.841255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:51.335631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.060937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-02-02T17:42:51.335696Z","caller":"traceutil/trace.go:171","msg":"trace[2016374420] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:567; }","duration":"125.140778ms","start":"2026-02-02T17:42:51.210538Z","end":"2026-02-02T17:42:51.335679Z","steps":["trace[2016374420] 'range keys from in-memory index tree' (duration: 124.966137ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.152676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:52.653437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665566,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:52.818163Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.192506871s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:42:52.818678Z","caller":"traceutil/trace.go:171","msg":"trace[413083478] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.193082872s","start":"2026-02-02T17:42:51.62557Z","end":"2026-02-02T17:42:52.818652Z","steps":["trace[413083478] 'process raft request' (duration: 1.192939862s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.818875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.62555Z","time spent":"1.193181913s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:42:52.878605Z","caller":"traceutil/trace.go:171","msg":"trace[158163497] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:593; }","duration":"1.226971655s","start":"2026-02-02T17:42:51.65161Z","end":"2026-02-02T17:42:52.878582Z","steps":["trace[158163497] 'read index received' (duration: 1.167019879s)","trace[158163497] 'applied index is now lower than readState.Index' (duration: 59.950986ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:52.878705Z","caller":"traceutil/trace.go:171","msg":"trace[260674753] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.100088353s","start":"2026-02-02T17:42:51.778609Z","end":"2026-02-02T17:42:52.878697Z","steps":["trace[260674753] 'process raft request' (duration: 1.066340892s)","trace[260674753] 'compare' (duration: 33.53782ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:52.878797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.778582Z","time spent":"1.100145063s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:52.878958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.163874814s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" ","response":"range_response_count:1 size:747"} {"level":"info","ts":"2026-02-02T17:42:52.879014Z","caller":"traceutil/trace.go:171","msg":"trace[1172582706] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:cronjob-controller; range_end:; response_count:1; response_revision:569; }","duration":"1.163958445s","start":"2026-02-02T17:42:51.715036Z","end":"2026-02-02T17:42:52.878994Z","steps":["trace[1172582706] 'agreement among raft nodes before linearized reading' (duration: 1.163870354s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.715022Z","time spent":"1.164020915s","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":769,"request content":"key:\"/registry/clusterrolebindings/system:controller:cronjob-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:52.8791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.119119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-02T17:42:52.879121Z","caller":"traceutil/trace.go:171","msg":"trace[1789479978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"291.183729ms","start":"2026-02-02T17:42:52.58793Z","end":"2026-02-02T17:42:52.879114Z","steps":["trace[1789479978] 'agreement among raft nodes before linearized reading' (duration: 291.092609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227606406s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-02-02T17:42:52.879231Z","caller":"traceutil/trace.go:171","msg":"trace[431796735] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:569; }","duration":"1.227653316s","start":"2026-02-02T17:42:51.651571Z","end":"2026-02-02T17:42:52.879225Z","steps":["trace[431796735] 'agreement among raft nodes before linearized reading' (duration: 1.227620446s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.651553Z","time spent":"1.227693446s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":745,"request content":"key:\"/registry/clusterroles/system:controller:namespace-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:52.879279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.793051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:42:52.87931Z","caller":"traceutil/trace.go:171","msg":"trace[664522805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"736.862581ms","start":"2026-02-02T17:42:52.142437Z","end":"2026-02-02T17:42:52.8793Z","steps":["trace[664522805] 'agreement among raft nodes before linearized reading' (duration: 736.773271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.142418Z","time spent":"736.920001ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:52.879356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.780153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:42:52.879516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.522442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T17:42:52.879516Z","caller":"traceutil/trace.go:171","msg":"trace[859331840] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"278.972164ms","start":"2026-02-02T17:42:52.600531Z","end":"2026-02-02T17:42:52.879503Z","steps":["trace[859331840] 'agreement among raft nodes before linearized reading' (duration: 278.809503ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:52.879535Z","caller":"traceutil/trace.go:171","msg":"trace[42518463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"837.578032ms","start":"2026-02-02T17:42:52.041951Z","end":"2026-02-02T17:42:52.879529Z","steps":["trace[42518463] 'agreement among raft nodes before linearized reading' (duration: 837.524722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:52.041933Z","time spent":"837.616152ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-02T17:42:52.879634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.403206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:42:52.879652Z","caller":"traceutil/trace.go:171","msg":"trace[218204747] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:569; }","duration":"882.462316ms","start":"2026-02-02T17:42:51.997185Z","end":"2026-02-02T17:42:52.879647Z","steps":["trace[218204747] 'agreement among raft nodes before linearized reading' (duration: 882.431096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:52.879668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:51.997164Z","time spent":"882.500746ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"info","ts":"2026-02-02T17:42:53.086058Z","caller":"traceutil/trace.go:171","msg":"trace[173460284] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"117.571808ms","start":"2026-02-02T17:42:52.968463Z","end":"2026-02-02T17:42:53.086035Z","steps":["trace[173460284] 'read index received' (duration: 21.898295ms)","trace[173460284] 'applied index is now lower than readState.Index' (duration: 95.672593ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:53.086443Z","caller":"traceutil/trace.go:171","msg":"trace[400309597] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"180.43092ms","start":"2026-02-02T17:42:52.905995Z","end":"2026-02-02T17:42:53.086426Z","steps":["trace[400309597] 'process raft request' (duration: 84.411476ms)","trace[400309597] 'compare' (duration: 95.507063ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:53.08694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.46201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:namespace-controller\" ","response":"range_response_count:1 size:723"} {"level":"info","ts":"2026-02-02T17:42:53.087026Z","caller":"traceutil/trace.go:171","msg":"trace[2039852029] range","detail":"{range_begin:/registry/clusterroles/system:controller:namespace-controller; range_end:; response_count:1; response_revision:572; }","duration":"118.55983ms","start":"2026-02-02T17:42:52.968446Z","end":"2026-02-02T17:42:53.087005Z","steps":["trace[2039852029] 'agreement among raft nodes before linearized reading' (duration: 118.43367ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:53.936124Z","caller":"traceutil/trace.go:171","msg":"trace[575692916] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"219.201596ms","start":"2026-02-02T17:42:53.716895Z","end":"2026-02-02T17:42:53.936096Z","steps":["trace[575692916] 'read index received' (duration: 219.050736ms)","trace[575692916] 'applied index is now lower than readState.Index' (duration: 149.53µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:53.93636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.432256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:pv-protection-controller\" ","response":"range_response_count:1 size:693"} {"level":"info","ts":"2026-02-02T17:42:53.936399Z","caller":"traceutil/trace.go:171","msg":"trace[1233034594] range","detail":"{range_begin:/registry/clusterroles/system:controller:pv-protection-controller; range_end:; response_count:1; response_revision:573; }","duration":"219.554786ms","start":"2026-02-02T17:42:53.716833Z","end":"2026-02-02T17:42:53.936387Z","steps":["trace[1233034594] 'agreement among raft nodes before linearized reading' (duration: 219.446446ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:53.936606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.10622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-02-02T17:42:53.936682Z","caller":"traceutil/trace.go:171","msg":"trace[1521715665] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:573; }","duration":"169.22789ms","start":"2026-02-02T17:42:53.767433Z","end":"2026-02-02T17:42:53.936661Z","steps":["trace[1521715665] 'agreement among raft nodes before linearized reading' (duration: 169.08843ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:54.041183Z","caller":"traceutil/trace.go:171","msg":"trace[1687854867] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"102.948381ms","start":"2026-02-02T17:42:53.938213Z","end":"2026-02-02T17:42:54.041162Z","steps":["trace[1687854867] 'process raft request' (duration: 50.378438ms)","trace[1687854867] 'compare' (duration: 52.436653ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:54.318192Z","caller":"traceutil/trace.go:171","msg":"trace[1450657325] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"101.754559ms","start":"2026-02-02T17:42:54.216422Z","end":"2026-02-02T17:42:54.318176Z","steps":["trace[1450657325] 'process raft request' (duration: 101.351718ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:54.633045Z","caller":"traceutil/trace.go:171","msg":"trace[291640951] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"129.212059ms","start":"2026-02-02T17:42:54.50381Z","end":"2026-02-02T17:42:54.633022Z","steps":["trace[291640951] 'process raft request' (duration: 128.836808ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:42:55.144964Z","caller":"traceutil/trace.go:171","msg":"trace[746176188] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"180.878255ms","start":"2026-02-02T17:42:54.963974Z","end":"2026-02-02T17:42:55.144853Z","steps":["trace[746176188] 'read index received' (duration: 55.804471ms)","trace[746176188] 'applied index is now lower than readState.Index' (duration: 125.073114ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:55.144934Z","caller":"traceutil/trace.go:171","msg":"trace[1735758051] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"185.363795ms","start":"2026-02-02T17:42:54.959558Z","end":"2026-02-02T17:42:55.144922Z","steps":["trace[1735758051] 'process raft request' (duration: 173.93244ms)","trace[1735758051] 'compare' (duration: 11.059584ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:55.145148Z","caller":"traceutil/trace.go:171","msg":"trace[1785348653] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"134.272205ms","start":"2026-02-02T17:42:55.010869Z","end":"2026-02-02T17:42:55.145141Z","steps":["trace[1785348653] 'process raft request' (duration: 133.945404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.145458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.466046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-02-02T17:42:55.145485Z","caller":"traceutil/trace.go:171","msg":"trace[1265434961] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:579; }","duration":"181.519206ms","start":"2026-02-02T17:42:54.963958Z","end":"2026-02-02T17:42:55.145478Z","steps":["trace[1265434961] 'agreement among raft nodes before linearized reading' (duration: 181.463936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.347297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.147572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:42:55.347411Z","caller":"traceutil/trace.go:171","msg":"trace[590727957] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:607; }","duration":"181.235747ms","start":"2026-02-02T17:42:55.166152Z","end":"2026-02-02T17:42:55.347387Z","steps":["trace[590727957] 'read index received' (duration: 21.746458ms)","trace[590727957] 'applied index is now lower than readState.Index' (duration: 159.488039ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:42:55.347587Z","caller":"traceutil/trace.go:171","msg":"trace[506382465] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"183.211532ms","start":"2026-02-02T17:42:55.164342Z","end":"2026-02-02T17:42:55.347553Z","steps":["trace[506382465] 'process raft request' (duration: 81.566139ms)","trace[506382465] 'compare' (duration: 100.972951ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:55.347987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.823399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6534"} {"level":"info","ts":"2026-02-02T17:42:55.34803Z","caller":"traceutil/trace.go:171","msg":"trace[521002069] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:581; }","duration":"181.893019ms","start":"2026-02-02T17:42:55.166125Z","end":"2026-02-02T17:42:55.348018Z","steps":["trace[521002069] 'agreement among raft nodes before linearized reading' (duration: 181.784798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.348076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.788411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"info","ts":"2026-02-02T17:42:55.348112Z","caller":"traceutil/trace.go:171","msg":"trace[102288877] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:581; }","duration":"100.858332ms","start":"2026-02-02T17:42:55.247241Z","end":"2026-02-02T17:42:55.3481Z","steps":["trace[102288877] 'agreement among raft nodes before linearized reading' (duration: 100.807132ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.348019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.179295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:expand-controller\" ","response":"range_response_count:1 size:743"} {"level":"info","ts":"2026-02-02T17:42:55.348303Z","caller":"traceutil/trace.go:171","msg":"trace[1208798785] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:expand-controller; range_end:; response_count:1; response_revision:581; }","duration":"125.512915ms","start":"2026-02-02T17:42:55.222778Z","end":"2026-02-02T17:42:55.348291Z","steps":["trace[1208798785] 'agreement among raft nodes before linearized reading' (duration: 125.171144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:55.971588Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:56.47187Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665654,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:42:56.957116Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.522103311s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:42:56.957788Z","caller":"traceutil/trace.go:171","msg":"trace[523980736] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.522864022s","start":"2026-02-02T17:42:55.434901Z","end":"2026-02-02T17:42:56.957765Z","steps":["trace[523980736] 'process raft request' (duration: 1.522682522s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:56.958207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.434875Z","time spent":"1.522967192s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:42:57.599955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000269459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:42:57.600031Z","caller":"traceutil/trace.go:171","msg":"trace[1282028233] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00038881s","start":"2026-02-02T17:42:55.599625Z","end":"2026-02-02T17:42:57.600014Z","steps":["trace[1282028233] 'agreement among raft nodes before linearized reading' (duration: 2.000288049s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.600276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.599613Z","time spent":"2.00045914s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:42:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:42:57.794963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.906511ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T17:42:57.795064Z","caller":"traceutil/trace.go:171","msg":"trace[1876191641] linearizableReadLoop","detail":"{readStateIndex:611; appliedIndex:610; }","duration":"2.32356917s","start":"2026-02-02T17:42:55.471478Z","end":"2026-02-02T17:42:57.795047Z","steps":["trace[1876191641] 'read index received' (duration: 1.486450962s)","trace[1876191641] 'applied index is now lower than readState.Index' (duration: 837.116748ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:57.795525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.324036161s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-02T17:42:57.795599Z","caller":"traceutil/trace.go:171","msg":"trace[1623255296] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:582; }","duration":"2.324108841s","start":"2026-02-02T17:42:55.471448Z","end":"2026-02-02T17:42:57.795557Z","steps":["trace[1623255296] 'agreement among raft nodes before linearized reading' (duration: 2.324019781s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:57.795639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.471434Z","time spent":"2.324193821s","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":797,"request content":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" "} {"level":"warn","ts":"2026-02-02T17:42:58.369606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.539429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-02T17:42:58.369754Z","caller":"traceutil/trace.go:171","msg":"trace[2068794111] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"574.607949ms","start":"2026-02-02T17:42:57.795093Z","end":"2026-02-02T17:42:58.369701Z","steps":["trace[2068794111] 'read index received' (duration: 706.392µs)","trace[2068794111] 'applied index is now lower than readState.Index' (duration: 573.899197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:42:58.370043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.677662709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:58.370075Z","caller":"traceutil/trace.go:171","msg":"trace[1160987280] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:582; }","duration":"1.677740729s","start":"2026-02-02T17:42:56.692324Z","end":"2026-02-02T17:42:58.370064Z","steps":["trace[1160987280] 'agreement among raft nodes before linearized reading' (duration: 1.677661159s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.692304Z","time spent":"1.677795369s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":6,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:58.370118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347018502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-02T17:42:58.370191Z","caller":"traceutil/trace.go:171","msg":"trace[1932087998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.347128482s","start":"2026-02-02T17:42:57.023042Z","end":"2026-02-02T17:42:58.37017Z","steps":["trace[1932087998] 'agreement among raft nodes before linearized reading' (duration: 1.346911971s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.023024Z","time spent":"1.347200372s","remote":"127.0.0.1:58264","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-02-02T17:42:58.370336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.743065383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-02T17:42:58.370365Z","caller":"traceutil/trace.go:171","msg":"trace[812717087] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"1.743126823s","start":"2026-02-02T17:42:56.62723Z","end":"2026-02-02T17:42:58.370357Z","steps":["trace[812717087] 'agreement among raft nodes before linearized reading' (duration: 1.743016963s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:56.627184Z","time spent":"1.743201394s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:42:58.370499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.932629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:42:58.370532Z","caller":"traceutil/trace.go:171","msg":"trace[1658857076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"770.020189ms","start":"2026-02-02T17:42:57.600501Z","end":"2026-02-02T17:42:58.370522Z","steps":["trace[1658857076] 'agreement among raft nodes before linearized reading' (duration: 769.974189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.600484Z","time spent":"770.067929ms","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:42:58.370596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.820607848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:58.370634Z","caller":"traceutil/trace.go:171","msg":"trace[246292327] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:582; }","duration":"2.820681158s","start":"2026-02-02T17:42:55.549943Z","end":"2026-02-02T17:42:58.370625Z","steps":["trace[246292327] 'agreement among raft nodes before linearized reading' (duration: 2.820588048s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.549926Z","time spent":"2.820729399s","remote":"127.0.0.1:58180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:58.370726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016912827s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-02T17:42:58.370755Z","caller":"traceutil/trace.go:171","msg":"trace[1337183659] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"1.017001117s","start":"2026-02-02T17:42:57.353745Z","end":"2026-02-02T17:42:58.370746Z","steps":["trace[1337183659] 'agreement among raft nodes before linearized reading' (duration: 1.016919107s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.37078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.353725Z","time spent":"1.017050467s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:42:58.370811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.88552978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-02T17:42:58.370849Z","caller":"traceutil/trace.go:171","msg":"trace[450807946] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:582; }","duration":"2.885586071s","start":"2026-02-02T17:42:55.485252Z","end":"2026-02-02T17:42:58.370838Z","steps":["trace[450807946] 'agreement among raft nodes before linearized reading' (duration: 2.88552586s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:55.485239Z","time spent":"2.885637491s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":699,"request content":"key:\"/registry/clusterroles/system:controller:route-controller\" "} {"level":"warn","ts":"2026-02-02T17:42:58.370931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211627407s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T17:42:58.370958Z","caller":"traceutil/trace.go:171","msg":"trace[585324780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.211684627s","start":"2026-02-02T17:42:57.159265Z","end":"2026-02-02T17:42:58.37095Z","steps":["trace[585324780] 'agreement among raft nodes before linearized reading' (duration: 1.211612347s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.370982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.15925Z","time spent":"1.211726587s","remote":"127.0.0.1:58264","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-02-02T17:42:58.371042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.815744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:42:58.371071Z","caller":"traceutil/trace.go:171","msg":"trace[911352018] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:582; }","duration":"545.876824ms","start":"2026-02-02T17:42:57.825187Z","end":"2026-02-02T17:42:58.371063Z","steps":["trace[911352018] 'agreement among raft nodes before linearized reading' (duration: 545.830314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.3711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.82517Z","time spent":"545.921794ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:42:58.371349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.261367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-02T17:42:58.371382Z","caller":"traceutil/trace.go:171","msg":"trace[1525892420] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:582; }","duration":"507.314387ms","start":"2026-02-02T17:42:57.864055Z","end":"2026-02-02T17:42:58.371369Z","steps":["trace[1525892420] 'agreement among raft nodes before linearized reading' (duration: 507.257627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.371445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:57.864042Z","time spent":"507.395757ms","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":797,"request content":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" "} {"level":"info","ts":"2026-02-02T17:42:58.810975Z","caller":"traceutil/trace.go:171","msg":"trace[1924503362] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"427.114158ms","start":"2026-02-02T17:42:58.383839Z","end":"2026-02-02T17:42:58.810953Z","steps":["trace[1924503362] 'process raft request' (duration: 426.957028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:42:58.811126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.383829Z","time spent":"427.211898ms","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:01.113731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.758821139s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:01.113931Z","caller":"traceutil/trace.go:171","msg":"trace[1888669423] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:612; }","duration":"2.506981003s","start":"2026-02-02T17:42:58.606936Z","end":"2026-02-02T17:43:01.113917Z","steps":["trace[1888669423] 'read index received' (duration: 203.961907ms)","trace[1888669423] 'applied index is now lower than readState.Index' (duration: 2.303018506s)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:01.114109Z","caller":"traceutil/trace.go:171","msg":"trace[1350474633] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"2.730186985s","start":"2026-02-02T17:42:58.383913Z","end":"2026-02-02T17:43:01.1141Z","steps":["trace[1350474633] 'process raft request' (duration: 970.927265ms)","trace[1350474633] 'compare' (duration: 1.758057307s)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:01.114152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.383909Z","time spent":"2.730221605s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:01.114266Z","caller":"traceutil/trace.go:171","msg":"trace[901865108] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"2.727861739s","start":"2026-02-02T17:42:58.386398Z","end":"2026-02-02T17:43:01.11426Z","steps":["trace[901865108] 'process raft request' (duration: 2.727408998s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.114298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.386387Z","time spent":"2.727894179s","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:01.114385Z","caller":"traceutil/trace.go:171","msg":"trace[556279909] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"2.727931169s","start":"2026-02-02T17:42:58.386444Z","end":"2026-02-02T17:43:01.114375Z","steps":["trace[556279909] 'process raft request' (duration: 2.727437748s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.114414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.386441Z","time spent":"2.727957109s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:01.114513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.507574175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:route-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-02T17:43:01.114572Z","caller":"traceutil/trace.go:171","msg":"trace[1533921036] range","detail":"{range_begin:/registry/clusterroles/system:controller:route-controller; range_end:; response_count:1; response_revision:586; }","duration":"2.507654255s","start":"2026-02-02T17:42:58.606911Z","end":"2026-02-02T17:43:01.114565Z","steps":["trace[1533921036] 'agreement among raft nodes before linearized reading' (duration: 2.507574725s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.114591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.606896Z","time spent":"2.507689535s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":699,"request content":"key:\"/registry/clusterroles/system:controller:route-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:01.114693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.496963981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-02T17:43:01.114711Z","caller":"traceutil/trace.go:171","msg":"trace[1143322013] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:586; }","duration":"2.496994371s","start":"2026-02-02T17:42:58.61771Z","end":"2026-02-02T17:43:01.114704Z","steps":["trace[1143322013] 'agreement among raft nodes before linearized reading' (duration: 2.496961301s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.114728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:58.617703Z","time spent":"2.497019581s","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":797,"request content":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-02-02T17:43:01.115053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.784218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T17:43:01.115073Z","caller":"traceutil/trace.go:171","msg":"trace[896060914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"299.844548ms","start":"2026-02-02T17:43:00.815222Z","end":"2026-02-02T17:43:01.115067Z","steps":["trace[896060914] 'agreement among raft nodes before linearized reading' (duration: 299.790448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.115168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174162292s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:01.115189Z","caller":"traceutil/trace.go:171","msg":"trace[457954847] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:586; }","duration":"1.174225542s","start":"2026-02-02T17:42:59.940957Z","end":"2026-02-02T17:43:01.115183Z","steps":["trace[457954847] 'agreement among raft nodes before linearized reading' (duration: 1.174189372s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.115219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.940934Z","time spent":"1.174278812s","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:01.115327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.425497586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:43:01.115345Z","caller":"traceutil/trace.go:171","msg":"trace[1502585754] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:586; }","duration":"1.425550346s","start":"2026-02-02T17:42:59.68979Z","end":"2026-02-02T17:43:01.11534Z","steps":["trace[1502585754] 'agreement among raft nodes before linearized reading' (duration: 1.425516336s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.115363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.689774Z","time spent":"1.425583696s","remote":"127.0.0.1:58152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:01.115459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514809342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:01.115476Z","caller":"traceutil/trace.go:171","msg":"trace[1390442743] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"1.514863602s","start":"2026-02-02T17:42:59.600607Z","end":"2026-02-02T17:43:01.115471Z","steps":["trace[1390442743] 'agreement among raft nodes before linearized reading' (duration: 1.514836692s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:01.115492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:42:59.600557Z","time spent":"1.514931092s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:43:01.630978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665670,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:02.132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665670,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:02.632344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665670,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:03.108451Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.98355028s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-02T17:43:03.130778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000350312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-02T17:43:03.13087Z","caller":"traceutil/trace.go:171","msg":"trace[516278301] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000468322s","start":"2026-02-02T17:43:01.130377Z","end":"2026-02-02T17:43:03.130845Z","steps":["trace[516278301] 'agreement among raft nodes before linearized reading' (duration: 2.000354392s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.131077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.130371Z","time spent":"2.000690993s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:43:03.290196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.156362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:03.290296Z","caller":"traceutil/trace.go:171","msg":"trace[229430444] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.165447986s","start":"2026-02-02T17:43:01.12483Z","end":"2026-02-02T17:43:03.290278Z","steps":["trace[229430444] 'process raft request' (duration: 1.984132333s)","trace[229430444] 'compare' (duration: 179.364628ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:03.290351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.12482Z","time spent":"2.165506016s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:03.291541Z","caller":"traceutil/trace.go:171","msg":"trace[160978734] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"2.161133015s","start":"2026-02-02T17:43:01.130396Z","end":"2026-02-02T17:43:03.291529Z","steps":["trace[160978734] 'read index received' (duration: 1.978578589s)","trace[160978734] 'applied index is now lower than readState.Index' (duration: 182.553516ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:03.291687Z","caller":"traceutil/trace.go:171","msg":"trace[1266357242] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"2.164062612s","start":"2026-02-02T17:43:01.127615Z","end":"2026-02-02T17:43:03.291678Z","steps":["trace[1266357242] 'process raft request' (duration: 2.163860902s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.291743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.127575Z","time spent":"2.164133562s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:03.299747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.972763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:03.299827Z","caller":"traceutil/trace.go:171","msg":"trace[501465949] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:590; }","duration":"785.102923ms","start":"2026-02-02T17:43:02.514709Z","end":"2026-02-02T17:43:03.299812Z","steps":["trace[501465949] 'agreement among raft nodes before linearized reading' (duration: 784.969753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.299867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:02.514689Z","time spent":"785.170213ms","remote":"127.0.0.1:58298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-02T17:43:03.300078Z","caller":"traceutil/trace.go:171","msg":"trace[728921595] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"103.139363ms","start":"2026-02-02T17:43:03.196931Z","end":"2026-02-02T17:43:03.30007Z","steps":["trace[728921595] 'process raft request' (duration: 102.550241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.300297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.838552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:03.300375Z","caller":"traceutil/trace.go:171","msg":"trace[1874718488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"168.949693ms","start":"2026-02-02T17:43:03.131419Z","end":"2026-02-02T17:43:03.300369Z","steps":["trace[1874718488] 'agreement among raft nodes before linearized reading' (duration: 168.861793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.300569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.029083ms","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-02-02T17:43:03.300611Z","caller":"traceutil/trace.go:171","msg":"trace[714178488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"177.082853ms","start":"2026-02-02T17:43:03.12352Z","end":"2026-02-02T17:43:03.300603Z","steps":["trace[714178488] 'agreement among raft nodes before linearized reading' (duration: 176.986653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.300797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.339633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-02T17:43:03.300861Z","caller":"traceutil/trace.go:171","msg":"trace[1371507135] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"177.431803ms","start":"2026-02-02T17:43:03.123423Z","end":"2026-02-02T17:43:03.300855Z","steps":["trace[1371507135] 'agreement among raft nodes before linearized reading' (duration: 177.340603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.301606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.303659274s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:43:03.30168Z","caller":"traceutil/trace.go:171","msg":"trace[1204739133] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:590; }","duration":"1.303771394s","start":"2026-02-02T17:43:01.997902Z","end":"2026-02-02T17:43:03.301673Z","steps":["trace[1204739133] 'agreement among raft nodes before linearized reading' (duration: 1.303672084s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.301743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.997879Z","time spent":"1.303858025s","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.192\" "} {"level":"warn","ts":"2026-02-02T17:43:03.30214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.887946636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-02-02T17:43:03.30225Z","caller":"traceutil/trace.go:171","msg":"trace[385986730] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:590; }","duration":"1.888091946s","start":"2026-02-02T17:43:01.41415Z","end":"2026-02-02T17:43:03.302242Z","steps":["trace[385986730] 'agreement among raft nodes before linearized reading' (duration: 1.887941545s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.302348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.414129Z","time spent":"1.888191586s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4396,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-02-02T17:43:03.302546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.053874229s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:service-account-controller\" ","response":"range_response_count:1 size:677"} {"level":"info","ts":"2026-02-02T17:43:03.302607Z","caller":"traceutil/trace.go:171","msg":"trace[801276266] range","detail":"{range_begin:/registry/clusterroles/system:controller:service-account-controller; range_end:; response_count:1; response_revision:590; }","duration":"2.053971151s","start":"2026-02-02T17:43:01.248622Z","end":"2026-02-02T17:43:03.302594Z","steps":["trace[801276266] 'agreement among raft nodes before linearized reading' (duration: 2.05381399s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.302683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.248609Z","time spent":"2.054065441s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":699,"request content":"key:\"/registry/clusterroles/system:controller:service-account-controller\" "} {"level":"warn","ts":"2026-02-02T17:43:03.302853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.064137004s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-02-02T17:43:03.302924Z","caller":"traceutil/trace.go:171","msg":"trace[1107279008] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:590; }","duration":"2.064206324s","start":"2026-02-02T17:43:01.238709Z","end":"2026-02-02T17:43:03.302916Z","steps":["trace[1107279008] 'agreement among raft nodes before linearized reading' (duration: 2.064085434s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:03.302999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:01.238657Z","time spent":"2.064335485s","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":797,"request content":"key:\"/registry/clusterrolebindings/system:controller:horizontal-pod-autoscaler\" "} {"level":"info","ts":"2026-02-02T17:43:08.698909Z","caller":"traceutil/trace.go:171","msg":"trace[112703500] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"301.747237ms","start":"2026-02-02T17:43:08.397139Z","end":"2026-02-02T17:43:08.698886Z","steps":["trace[112703500] 'read index received' (duration: 217.24498ms)","trace[112703500] 'applied index is now lower than readState.Index' (duration: 84.501387ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:08.699091Z","caller":"traceutil/trace.go:171","msg":"trace[1031494688] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"219.550826ms","start":"2026-02-02T17:43:08.479529Z","end":"2026-02-02T17:43:08.69908Z","steps":["trace[1031494688] 'process raft request' (duration: 219.272585ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:08.798356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.718705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-02-02T17:43:08.798434Z","caller":"traceutil/trace.go:171","msg":"trace[1007204506] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:667; }","duration":"393.807745ms","start":"2026-02-02T17:43:08.404607Z","end":"2026-02-02T17:43:08.798415Z","steps":["trace[1007204506] 'agreement among raft nodes before linearized reading' (duration: 294.460789ms)","trace[1007204506] 'range keys from in-memory index tree' (duration: 99.224066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.798936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.79665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-02T17:43:08.798987Z","caller":"traceutil/trace.go:171","msg":"trace[227931173] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:667; }","duration":"228.88639ms","start":"2026-02-02T17:43:08.570088Z","end":"2026-02-02T17:43:08.798974Z","steps":["trace[227931173] 'agreement among raft nodes before linearized reading' (duration: 129.226723ms)","trace[227931173] 'range keys from in-memory index tree' (duration: 99.538957ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.79941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.266266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-02T17:43:08.799551Z","caller":"traceutil/trace.go:171","msg":"trace[1247747890] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:667; }","duration":"402.330977ms","start":"2026-02-02T17:43:08.397109Z","end":"2026-02-02T17:43:08.79944Z","steps":["trace[1247747890] 'agreement among raft nodes before linearized reading' (duration: 301.910427ms)","trace[1247747890] 'range keys from in-memory index tree' (duration: 100.344948ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.799685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.397096Z","time spent":"402.576397ms","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":721,"request content":"key:\"/registry/clusterrolebindings/system:node-proxier\" "} {"level":"warn","ts":"2026-02-02T17:43:08.800018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.482567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T17:43:08.800168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.404602Z","time spent":"394.054316ms","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":677,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"info","ts":"2026-02-02T17:43:08.800426Z","caller":"traceutil/trace.go:171","msg":"trace[2042418250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"200.549537ms","start":"2026-02-02T17:43:08.599497Z","end":"2026-02-02T17:43:08.800046Z","steps":["trace[2042418250] 'agreement among raft nodes before linearized reading' (duration: 99.809887ms)","trace[2042418250] 'range keys from in-memory index tree' (duration: 100.6769ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.801195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.425886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-02T17:43:08.801465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.540356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lkv7p\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-02T17:43:08.801504Z","caller":"traceutil/trace.go:171","msg":"trace[1635600532] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lkv7p; range_end:; response_count:1; response_revision:667; }","duration":"324.622536ms","start":"2026-02-02T17:43:08.476871Z","end":"2026-02-02T17:43:08.801493Z","steps":["trace[1635600532] 'agreement among raft nodes before linearized reading' (duration: 222.449772ms)","trace[1635600532] 'range keys from in-memory index tree' (duration: 102.044594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.80171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.476825Z","time spent":"324.781206ms","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17940,"request content":"key:\"/registry/pods/kube-system/cilium-lkv7p\" "} {"level":"info","ts":"2026-02-02T17:43:08.80124Z","caller":"traceutil/trace.go:171","msg":"trace[2099735498] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:667; }","duration":"355.525186ms","start":"2026-02-02T17:43:08.445702Z","end":"2026-02-02T17:43:08.801228Z","steps":["trace[2099735498] 'agreement among raft nodes before linearized reading' (duration: 253.624143ms)","trace[2099735498] 'count revisions from in-memory index tree' (duration: 101.844143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:08.802285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.44568Z","time spent":"356.590059ms","remote":"127.0.0.1:58086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":4,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:09.312782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:09.813913Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:10.314644Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:10.805654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.993078874s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-02T17:43:10.805728Z","caller":"traceutil/trace.go:171","msg":"trace[299443827] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.993182015s","start":"2026-02-02T17:43:08.812529Z","end":"2026-02-02T17:43:10.805711Z","steps":["trace[299443827] 'agreement among raft nodes before linearized reading' (duration: 1.993092694s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:10.80578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.812517Z","time spent":"1.993254415s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/02 17:43:10 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-02T17:43:10.81538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:11.31563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2259571296930665991,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-02T17:43:11.7652Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.967324578s","expected-duration":"1s"} {"level":"info","ts":"2026-02-02T17:43:11.765968Z","caller":"traceutil/trace.go:171","msg":"trace[2120839188] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"2.968446221s","start":"2026-02-02T17:43:08.797493Z","end":"2026-02-02T17:43:11.76594Z","steps":["trace[2120839188] 'process raft request' (duration: 2.96808958s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:11.766101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.797469Z","time spent":"2.968562212s","remote":"127.0.0.1:58030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:43:12.007151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.719248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:12.007695Z","caller":"traceutil/trace.go:171","msg":"trace[302723865] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"3.195128406s","start":"2026-02-02T17:43:08.812549Z","end":"2026-02-02T17:43:12.007677Z","steps":["trace[302723865] 'read index received' (duration: 2.954748837s)","trace[302723865] 'applied index is now lower than readState.Index' (duration: 240.378729ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:12.00794Z","caller":"traceutil/trace.go:171","msg":"trace[1717090023] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"3.193100441s","start":"2026-02-02T17:43:08.814665Z","end":"2026-02-02T17:43:12.007765Z","steps":["trace[1717090023] 'process raft request' (duration: 3.061657021s)","trace[1717090023] 'compare' (duration: 130.474247ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:12.008278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.814657Z","time spent":"3.193326311s","remote":"127.0.0.1:58168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18060,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T17:43:12.008764Z","caller":"traceutil/trace.go:171","msg":"trace[1571926522] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"2.809073004s","start":"2026-02-02T17:43:09.199679Z","end":"2026-02-02T17:43:12.008752Z","steps":["trace[1571926522] 'process raft request' (duration: 2.807817741s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.008912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.199663Z","time spent":"2.809187185s","remote":"127.0.0.1:58030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T17:43:12.287251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.180515ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T17:43:12.287351Z","caller":"traceutil/trace.go:171","msg":"trace[1306600533] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"279.600409ms","start":"2026-02-02T17:43:12.007736Z","end":"2026-02-02T17:43:12.287336Z","steps":["trace[1306600533] 'read index received' (duration: 117.032653ms)","trace[1306600533] 'applied index is now lower than readState.Index' (duration: 162.566666ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:12.287502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.474778275s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-02-02T17:43:12.287546Z","caller":"traceutil/trace.go:171","msg":"trace[2088321357] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:671; }","duration":"3.474909756s","start":"2026-02-02T17:43:08.812624Z","end":"2026-02-02T17:43:12.287534Z","steps":["trace[2088321357] 'agreement among raft nodes before linearized reading' (duration: 3.474753115s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.287622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.812622Z","time spent":"3.474987496s","remote":"127.0.0.1:58340","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":677,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"info","ts":"2026-02-02T17:43:12.28796Z","caller":"traceutil/trace.go:171","msg":"trace[49561384] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"391.589368ms","start":"2026-02-02T17:43:11.896356Z","end":"2026-02-02T17:43:12.287946Z","steps":["trace[49561384] 'process raft request' (duration: 228.639831ms)","trace[49561384] 'compare' (duration: 161.846324ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:12.288035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:11.89634Z","time spent":"391.656018ms","remote":"127.0.0.1:58030","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T17:43:12.288433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.830436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.192\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-02T17:43:12.288473Z","caller":"traceutil/trace.go:171","msg":"trace[1427050139] range","detail":"{range_begin:/registry/masterleases/199.19.213.192; range_end:; response_count:1; response_revision:671; }","duration":"289.902867ms","start":"2026-02-02T17:43:11.998561Z","end":"2026-02-02T17:43:12.288463Z","steps":["trace[1427050139] 'agreement among raft nodes before linearized reading' (duration: 289.786157ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.288789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.45072668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-02T17:43:12.288833Z","caller":"traceutil/trace.go:171","msg":"trace[282675332] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:671; }","duration":"1.450807501s","start":"2026-02-02T17:43:10.838014Z","end":"2026-02-02T17:43:12.288822Z","steps":["trace[282675332] 'agreement among raft nodes before linearized reading' (duration: 1.450736371s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.288867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:10.837997Z","time spent":"1.450860531s","remote":"127.0.0.1:58456","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:12.289231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.476294527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-02T17:43:12.289275Z","caller":"traceutil/trace.go:171","msg":"trace[1098294043] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad; range_end:; response_count:1; response_revision:671; }","duration":"1.476350627s","start":"2026-02-02T17:43:10.812914Z","end":"2026-02-02T17:43:12.289264Z","steps":["trace[1098294043] 'agreement among raft nodes before linearized reading' (duration: 1.476241737s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.289306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:10.812907Z","time spent":"1.476391987s","remote":"127.0.0.1:58066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":796,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18907edd5329b0ad\" "} {"level":"warn","ts":"2026-02-02T17:43:12.289545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47711077s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:12.289585Z","caller":"traceutil/trace.go:171","msg":"trace[895810647] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"1.47717366s","start":"2026-02-02T17:43:10.812401Z","end":"2026-02-02T17:43:12.289575Z","steps":["trace[895810647] 'agreement among raft nodes before linearized reading' (duration: 1.47711175s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.289611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:10.812389Z","time spent":"1.47721691s","remote":"127.0.0.1:57996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:43:12.289945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.542000471s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:12.289987Z","caller":"traceutil/trace.go:171","msg":"trace[1373205539] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:671; }","duration":"1.542082731s","start":"2026-02-02T17:43:10.747893Z","end":"2026-02-02T17:43:12.289976Z","steps":["trace[1373205539] 'agreement among raft nodes before linearized reading' (duration: 1.542014151s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.290016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:10.747875Z","time spent":"1.542133051s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:12.290275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.901013316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:12.290343Z","caller":"traceutil/trace.go:171","msg":"trace[734018083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:671; }","duration":"1.901107366s","start":"2026-02-02T17:43:10.389224Z","end":"2026-02-02T17:43:12.290332Z","steps":["trace[734018083] 'agreement among raft nodes before linearized reading' (duration: 1.901023686s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.290375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:10.389209Z","time spent":"1.901157776s","remote":"127.0.0.1:58004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T17:43:12.290664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.917646168s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-02T17:43:12.290713Z","caller":"traceutil/trace.go:171","msg":"trace[1510926602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"2.917727378s","start":"2026-02-02T17:43:09.372974Z","end":"2026-02-02T17:43:12.290702Z","steps":["trace[1510926602] 'agreement among raft nodes before linearized reading' (duration: 2.917596478s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.290742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.372961Z","time spent":"2.917774218s","remote":"127.0.0.1:58264","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-02-02T17:43:12.29131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.921970219s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-02-02T17:43:12.291354Z","caller":"traceutil/trace.go:171","msg":"trace[214545684] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"2.92204285s","start":"2026-02-02T17:43:09.3693Z","end":"2026-02-02T17:43:12.291343Z","steps":["trace[214545684] 'agreement among raft nodes before linearized reading' (duration: 2.92190153s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.291384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.369288Z","time spent":"2.92208869s","remote":"127.0.0.1:58030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4202,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:43:12.291649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.930964503s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-02T17:43:12.291685Z","caller":"traceutil/trace.go:171","msg":"trace[1268320956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"2.931028173s","start":"2026-02-02T17:43:09.360648Z","end":"2026-02-02T17:43:12.291676Z","steps":["trace[1268320956] 'agreement among raft nodes before linearized reading' (duration: 2.930905943s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.291714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.360637Z","time spent":"2.931069803s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-02T17:43:12.292067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.934015811s","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-02-02T17:43:12.292106Z","caller":"traceutil/trace.go:171","msg":"trace[992408484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"2.934086171s","start":"2026-02-02T17:43:09.35801Z","end":"2026-02-02T17:43:12.292096Z","steps":["trace[992408484] 'agreement among raft nodes before linearized reading' (duration: 2.933984371s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.292141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.357995Z","time spent":"2.934138641s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T17:43:12.292577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.963238127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T17:43:12.292722Z","caller":"traceutil/trace.go:171","msg":"trace[948364503] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:671; }","duration":"2.963306887s","start":"2026-02-02T17:43:09.329303Z","end":"2026-02-02T17:43:12.29261Z","steps":["trace[948364503] 'agreement among raft nodes before linearized reading' (duration: 2.963235327s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.292884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.329288Z","time spent":"2.963497128s","remote":"127.0.0.1:58216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-02-02T17:43:12.293212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.971017658s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-02T17:43:12.293253Z","caller":"traceutil/trace.go:171","msg":"trace[1420115747] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"2.971095508s","start":"2026-02-02T17:43:09.322146Z","end":"2026-02-02T17:43:12.293242Z","steps":["trace[1420115747] 'agreement among raft nodes before linearized reading' (duration: 2.970994908s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.293283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.32211Z","time spent":"2.971166508s","remote":"127.0.0.1:58264","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-02-02T17:43:12.293631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.082064537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-02T17:43:12.293671Z","caller":"traceutil/trace.go:171","msg":"trace[1233905573] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:671; }","duration":"3.082125217s","start":"2026-02-02T17:43:09.211536Z","end":"2026-02-02T17:43:12.293662Z","steps":["trace[1233905573] 'agreement among raft nodes before linearized reading' (duration: 3.082023127s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.2937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:09.211529Z","time spent":"3.082164827s","remote":"127.0.0.1:58264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-02T17:43:12.294079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.324585198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-02-02T17:43:12.294119Z","caller":"traceutil/trace.go:171","msg":"trace[1698969730] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"3.324674079s","start":"2026-02-02T17:43:08.969434Z","end":"2026-02-02T17:43:12.294108Z","steps":["trace[1698969730] 'agreement among raft nodes before linearized reading' (duration: 3.324540429s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.294213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.969413Z","time spent":"3.32478748s","remote":"127.0.0.1:58030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-02-02T17:43:12.294546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.425673199s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:node-proxier\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-02-02T17:43:12.294651Z","caller":"traceutil/trace.go:171","msg":"trace[710945658] range","detail":"{range_begin:/registry/clusterrolebindings/system:node-proxier; range_end:; response_count:1; response_revision:671; }","duration":"3.42580153s","start":"2026-02-02T17:43:08.868827Z","end":"2026-02-02T17:43:12.294629Z","steps":["trace[710945658] 'agreement among raft nodes before linearized reading' (duration: 3.425654609s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.29475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T17:43:08.868809Z","time spent":"3.42592733s","remote":"127.0.0.1:58350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":721,"request content":"key:\"/registry/clusterrolebindings/system:node-proxier\" "} {"level":"info","ts":"2026-02-02T17:43:12.720721Z","caller":"traceutil/trace.go:171","msg":"trace[365690197] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"166.013186ms","start":"2026-02-02T17:43:12.5547Z","end":"2026-02-02T17:43:12.720713Z","steps":["trace[365690197] 'process raft request' (duration: 165.336164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T17:43:12.721116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.966415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-02-02T17:43:12.72114Z","caller":"traceutil/trace.go:171","msg":"trace[2123296539] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:681; }","duration":"110.020255ms","start":"2026-02-02T17:43:12.611113Z","end":"2026-02-02T17:43:12.721133Z","steps":["trace[2123296539] 'agreement among raft nodes before linearized reading' (duration: 109.967415ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:12.72033Z","caller":"traceutil/trace.go:171","msg":"trace[251301515] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:713; }","duration":"109.174563ms","start":"2026-02-02T17:43:12.611141Z","end":"2026-02-02T17:43:12.720316Z","steps":["trace[251301515] 'read index received' (duration: 108.910522ms)","trace[251301515] 'applied index is now lower than readState.Index' (duration: 263.591µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T17:43:12.769495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.653884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:public-info-viewer\" ","response":"range_response_count:1 size:783"} {"level":"info","ts":"2026-02-02T17:43:12.769548Z","caller":"traceutil/trace.go:171","msg":"trace[1822090255] range","detail":"{range_begin:/registry/clusterrolebindings/system:public-info-viewer; range_end:; response_count:1; response_revision:682; }","duration":"146.744474ms","start":"2026-02-02T17:43:12.622793Z","end":"2026-02-02T17:43:12.769537Z","steps":["trace[1822090255] 'agreement among raft nodes before linearized reading' (duration: 146.639434ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T17:43:12.769688Z","caller":"traceutil/trace.go:171","msg":"trace[223318296] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"119.687841ms","start":"2026-02-02T17:43:12.649995Z","end":"2026-02-02T17:43:12.769683Z","steps":["trace[223318296] 'process raft request' (duration: 94.077363ms)","trace[223318296] 'compare' (duration: 25.275307ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T17:43:13.458661Z","caller":"traceutil/trace.go:171","msg":"trace[1463394164] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"112.344805ms","start":"2026-02-02T17:43:13.346274Z","end":"2026-02-02T17:43:13.458618Z","steps":["trace[1463394164] 'process raft request' (duration: 32.968879ms)","trace[1463394164] 'compare' (duration: 79.087425ms)"],"step_count":2}