{"level":"warn","ts":"2026-02-23T08:45:29.806968Z","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-23T08:45:29.807078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235: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-23T08:45:29.807149Z","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-23T08:45:29.807159Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-23T08:45:29.807179Z","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-23T08:45:29.808401Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-02-23T08:45:29.808475Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235: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-23T08:45:29.813226Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.167905ms"} {"level":"info","ts":"2026-02-23T08:45:29.820013Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-02-23T08:45:29.820097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-02-23T08:45:29.820125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-02-23T08:45:29.820138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-23T08:45:29.820154Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-02-23T08:45:29.820244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-02-23T08:45:29.828862Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-23T08:45:29.830206Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-23T08:45:29.831489Z","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-23T08:45:29.833705Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-23T08:45:29.83397Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-23T08:45:29.834151Z","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-23T08:45:29.83577Z","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-23T08:45:29.835909Z","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-23T08:45:29.836463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-02-23T08:45:29.837027Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-02-23T08:45:29.83935Z","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-23T08:45:29.839512Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-23T08:45:29.839597Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-02-23T08:45:29.839838Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-23T08:45:29.8399Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-23T08:45:30.720992Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-02-23T08:45:30.721051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-23T08:45:30.721094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-02-23T08:45:30.72112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-02-23T08:45:30.721135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-23T08:45:30.721153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-02-23T08:45:30.72117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-02-23T08:45:30.721946Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T08:45:30.722821Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T08:45:30.722801Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-02-23T08:45:30.723093Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T08:45:30.723194Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T08:45:30.723163Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T08:45:30.723219Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T08:45:30.72503Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-23T08:45:30.725901Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-02-23T08:45:30.726364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-23T08:45:30.726458Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-23T08:45:42.585676Z","caller":"traceutil/trace.go:171","msg":"trace[17361291] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"103.459315ms","start":"2026-02-23T08:45:42.482178Z","end":"2026-02-23T08:45:42.585637Z","steps":["trace[17361291] 'process raft request' (duration: 98.084546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:45:43.410292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.886579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:45:43.410449Z","caller":"traceutil/trace.go:171","msg":"trace[1485925837] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"727.564075ms","start":"2026-02-23T08:45:42.682861Z","end":"2026-02-23T08:45:43.410425Z","steps":["trace[1485925837] 'process raft request' (duration: 13.669588ms)","trace[1485925837] 'compare' (duration: 712.730415ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:43.41053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:42.682838Z","time spent":"727.655698ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:45:44.224938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.581139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-23T08:45:44.224993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.917031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-23T08:45:44.225071Z","caller":"traceutil/trace.go:171","msg":"trace[822532171] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:359; }","duration":"496.047304ms","start":"2026-02-23T08:45:43.729002Z","end":"2026-02-23T08:45:44.225049Z","steps":["trace[822532171] 'range keys from in-memory index tree' (duration: 495.664364ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:45:44.225071Z","caller":"traceutil/trace.go:171","msg":"trace[830589929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:359; }","duration":"280.815785ms","start":"2026-02-23T08:45:43.944228Z","end":"2026-02-23T08:45:44.225044Z","steps":["trace[830589929] 'range keys from in-memory index tree' (duration: 280.396394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:45:44.225278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:43.728972Z","time spent":"496.291621ms","remote":"127.0.0.1:45688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-02-23T08:45:45.877149Z","caller":"traceutil/trace.go:171","msg":"trace[1821528482] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"112.482485ms","start":"2026-02-23T08:45:45.764628Z","end":"2026-02-23T08:45:45.877111Z","steps":["trace[1821528482] 'process raft request' (duration: 112.30075ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:45:46.846817Z","caller":"traceutil/trace.go:171","msg":"trace[945357009] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"143.193465ms","start":"2026-02-23T08:45:46.703591Z","end":"2026-02-23T08:45:46.846785Z","steps":["trace[945357009] 'process raft request' (duration: 96.986626ms)","trace[945357009] 'compare' (duration: 46.076135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:49.193989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.554621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:45:49.19427Z","caller":"traceutil/trace.go:171","msg":"trace[917728659] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"476.35758ms","start":"2026-02-23T08:45:48.71788Z","end":"2026-02-23T08:45:49.194238Z","steps":["trace[917728659] 'process raft request' (duration: 49.415478ms)","trace[917728659] 'compare' (duration: 426.387296ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:49.194411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:48.717862Z","time spent":"476.490303ms","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6673,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T08:45:49.194536Z","caller":"traceutil/trace.go:171","msg":"trace[1364581795] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"458.812114ms","start":"2026-02-23T08:45:48.735615Z","end":"2026-02-23T08:45:49.194427Z","steps":["trace[1364581795] 'read index received' (duration: 31.635746ms)","trace[1364581795] 'applied index is now lower than readState.Index' (duration: 427.167198ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:49.547907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.25555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-23T08:45:49.547953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.811117ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-23T08:45:49.548007Z","caller":"traceutil/trace.go:171","msg":"trace[940981199] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"812.401354ms","start":"2026-02-23T08:45:48.735582Z","end":"2026-02-23T08:45:49.547984Z","steps":["trace[940981199] 'agreement among raft nodes before linearized reading' (duration: 458.958178ms)","trace[940981199] 'range keys from in-memory index tree' (duration: 353.253421ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:49.548049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:48.735574Z","time spent":"812.466776ms","remote":"127.0.0.1:45898","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":"info","ts":"2026-02-23T08:45:49.548047Z","caller":"traceutil/trace.go:171","msg":"trace[1404840877] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:387; }","duration":"353.318373ms","start":"2026-02-23T08:45:49.194712Z","end":"2026-02-23T08:45:49.54803Z","steps":["trace[1404840877] 'read index received' (duration: 106.563µs)","trace[1404840877] 'applied index is now lower than readState.Index' (duration: 353.21049ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:45:49.548205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.511088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4015"} {"level":"info","ts":"2026-02-23T08:45:49.548398Z","caller":"traceutil/trace.go:171","msg":"trace[134633464] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:376; }","duration":"578.737354ms","start":"2026-02-23T08:45:48.969646Z","end":"2026-02-23T08:45:49.548383Z","steps":["trace[134633464] 'agreement among raft nodes before linearized reading' (duration: 578.496818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:45:49.548534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:48.96963Z","time spent":"578.859707ms","remote":"127.0.0.1:45800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4036,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-23T08:45:49.548641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.658534ms","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-23T08:45:49.548857Z","caller":"traceutil/trace.go:171","msg":"trace[2097680610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"691.170337ms","start":"2026-02-23T08:45:48.857634Z","end":"2026-02-23T08:45:49.548804Z","steps":["trace[2097680610] 'agreement among raft nodes before linearized reading' (duration: 690.584691ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:45:49.548873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.160504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:45:49.549086Z","caller":"traceutil/trace.go:171","msg":"trace[1840680399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"182.456392ms","start":"2026-02-23T08:45:49.366607Z","end":"2026-02-23T08:45:49.549063Z","steps":["trace[1840680399] 'agreement among raft nodes before linearized reading' (duration: 182.188955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:45:49.549049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:45:48.857623Z","time spent":"691.357473ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-23T08:46:05.83922Z","caller":"traceutil/trace.go:171","msg":"trace[1123886254] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"129.83229ms","start":"2026-02-23T08:46:05.709349Z","end":"2026-02-23T08:46:05.839181Z","steps":["trace[1123886254] 'process raft request' (duration: 129.609834ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:09.995133Z","caller":"traceutil/trace.go:171","msg":"trace[392829164] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"137.859194ms","start":"2026-02-23T08:46:09.857243Z","end":"2026-02-23T08:46:09.995102Z","steps":["trace[392829164] 'process raft request' (duration: 137.670969ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:11.88545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.568223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:46:11.885614Z","caller":"traceutil/trace.go:171","msg":"trace[1948124234] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"118.321609ms","start":"2026-02-23T08:46:11.76727Z","end":"2026-02-23T08:46:11.885591Z","steps":["trace[1948124234] 'read index received' (duration: 56.301µs)","trace[1948124234] 'applied index is now lower than readState.Index' (duration: 118.263748ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:46:11.885666Z","caller":"traceutil/trace.go:171","msg":"trace[319802739] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"190.150382ms","start":"2026-02-23T08:46:11.695457Z","end":"2026-02-23T08:46:11.885608Z","steps":["trace[319802739] 'process raft request' (duration: 27.089175ms)","trace[319802739] 'compare' (duration: 162.277155ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:46:11.885886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.620947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-m8c5l\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-23T08:46:11.885969Z","caller":"traceutil/trace.go:171","msg":"trace[1279726278] range","detail":"{range_begin:/registry/pods/kube-system/cilium-m8c5l; range_end:; response_count:1; response_revision:496; }","duration":"118.755541ms","start":"2026-02-23T08:46:11.767199Z","end":"2026-02-23T08:46:11.885955Z","steps":["trace[1279726278] 'agreement among raft nodes before linearized reading' (duration: 118.454962ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:12.079708Z","caller":"traceutil/trace.go:171","msg":"trace[1806403533] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"177.837949ms","start":"2026-02-23T08:46:11.90182Z","end":"2026-02-23T08:46:12.079658Z","steps":["trace[1806403533] 'process raft request' (duration: 177.52242ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:12.387759Z","caller":"traceutil/trace.go:171","msg":"trace[1466569535] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"302.502475ms","start":"2026-02-23T08:46:12.085214Z","end":"2026-02-23T08:46:12.387716Z","steps":["trace[1466569535] 'process raft request' (duration: 293.0218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:12.388025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:46:12.085185Z","time spent":"302.705521ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:46:12.969738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:46:12.580214Z","time spent":"389.512691ms","remote":"127.0.0.1:57456","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-23T08:46:12.969892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.171417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-23T08:46:12.969879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.517316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-23T08:46:12.969976Z","caller":"traceutil/trace.go:171","msg":"trace[1160055200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"382.307091ms","start":"2026-02-23T08:46:12.587651Z","end":"2026-02-23T08:46:12.969958Z","steps":["trace[1160055200] 'range keys from in-memory index tree' (duration: 381.93377ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:12.969989Z","caller":"traceutil/trace.go:171","msg":"trace[1152104673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"207.698272ms","start":"2026-02-23T08:46:12.762268Z","end":"2026-02-23T08:46:12.969966Z","steps":["trace[1152104673] 'range keys from in-memory index tree' (duration: 207.220759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:12.97001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:46:12.587632Z","time spent":"382.370562ms","remote":"127.0.0.1:45898","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":"info","ts":"2026-02-23T08:46:13.148449Z","caller":"traceutil/trace.go:171","msg":"trace[658678001] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"173.915109ms","start":"2026-02-23T08:46:12.974502Z","end":"2026-02-23T08:46:13.148417Z","steps":["trace[658678001] 'process raft request' (duration: 173.752204ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:13.158132Z","caller":"traceutil/trace.go:171","msg":"trace[1689586600] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"183.081995ms","start":"2026-02-23T08:46:12.975008Z","end":"2026-02-23T08:46:13.15809Z","steps":["trace[1689586600] 'process raft request' (duration: 182.892229ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:31.487311Z","caller":"traceutil/trace.go:171","msg":"trace[137826470] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:702; }","duration":"251.828372ms","start":"2026-02-23T08:46:31.235447Z","end":"2026-02-23T08:46:31.487275Z","steps":["trace[137826470] 'read index received' (duration: 251.672238ms)","trace[137826470] 'applied index is now lower than readState.Index' (duration: 154.694µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:46:31.487393Z","caller":"traceutil/trace.go:171","msg":"trace[345150924] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"273.377103ms","start":"2026-02-23T08:46:31.213969Z","end":"2026-02-23T08:46:31.487346Z","steps":["trace[345150924] 'process raft request' (duration: 273.109936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:31.487616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.147681ms","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-23T08:46:31.487653Z","caller":"traceutil/trace.go:171","msg":"trace[1333543528] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"252.231743ms","start":"2026-02-23T08:46:31.235411Z","end":"2026-02-23T08:46:31.487642Z","steps":["trace[1333543528] 'agreement among raft nodes before linearized reading' (duration: 252.056179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:31.487654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.476479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:46:31.487761Z","caller":"traceutil/trace.go:171","msg":"trace[1580814077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"120.598482ms","start":"2026-02-23T08:46:31.367139Z","end":"2026-02-23T08:46:31.487737Z","steps":["trace[1580814077] 'agreement among raft nodes before linearized reading' (duration: 120.432497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:31.487761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.578671ms","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-23T08:46:31.48786Z","caller":"traceutil/trace.go:171","msg":"trace[2083310006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"243.744036ms","start":"2026-02-23T08:46:31.244092Z","end":"2026-02-23T08:46:31.487836Z","steps":["trace[2083310006] 'agreement among raft nodes before linearized reading' (duration: 243.472548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:46:31.487695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.668123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:46:31.487955Z","caller":"traceutil/trace.go:171","msg":"trace[1242007272] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:682; }","duration":"234.971702ms","start":"2026-02-23T08:46:31.252964Z","end":"2026-02-23T08:46:31.487936Z","steps":["trace[1242007272] 'agreement among raft nodes before linearized reading' (duration: 234.657163ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:31.746116Z","caller":"traceutil/trace.go:171","msg":"trace[846318965] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"252.954544ms","start":"2026-02-23T08:46:31.493127Z","end":"2026-02-23T08:46:31.746082Z","steps":["trace[846318965] 'process raft request' (duration: 252.888132ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:46:31.746298Z","caller":"traceutil/trace.go:171","msg":"trace[1767011392] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"253.786506ms","start":"2026-02-23T08:46:31.492457Z","end":"2026-02-23T08:46:31.746243Z","steps":["trace[1767011392] 'process raft request' (duration: 241.098013ms)","trace[1767011392] 'compare' (duration: 12.321653ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:00.617865Z","caller":"traceutil/trace.go:171","msg":"trace[1117424836] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"252.002487ms","start":"2026-02-23T08:47:00.365836Z","end":"2026-02-23T08:47:00.617838Z","steps":["trace[1117424836] 'read index received' (duration: 251.778171ms)","trace[1117424836] 'applied index is now lower than readState.Index' (duration: 223.446µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:00.617987Z","caller":"traceutil/trace.go:171","msg":"trace[565341078] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"292.248949ms","start":"2026-02-23T08:47:00.325674Z","end":"2026-02-23T08:47:00.617923Z","steps":["trace[565341078] 'process raft request' (duration: 292.010233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:00.61804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.185891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:47:00.618077Z","caller":"traceutil/trace.go:171","msg":"trace[1263469543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"252.294295ms","start":"2026-02-23T08:47:00.365772Z","end":"2026-02-23T08:47:00.618066Z","steps":["trace[1263469543] 'agreement among raft nodes before linearized reading' (duration: 252.184822ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:01.932953Z","caller":"traceutil/trace.go:171","msg":"trace[1544848682] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"281.284373ms","start":"2026-02-23T08:47:01.65161Z","end":"2026-02-23T08:47:01.932895Z","steps":["trace[1544848682] 'process raft request' (duration: 280.258415ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:02.047436Z","caller":"traceutil/trace.go:171","msg":"trace[398281804] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"109.356059ms","start":"2026-02-23T08:47:01.938035Z","end":"2026-02-23T08:47:02.047391Z","steps":["trace[398281804] 'process raft request' (duration: 79.595299ms)","trace[398281804] 'compare' (duration: 29.612276ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:02.082651Z","caller":"traceutil/trace.go:171","msg":"trace[408842748] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"144.315644ms","start":"2026-02-23T08:47:01.938307Z","end":"2026-02-23T08:47:02.082623Z","steps":["trace[408842748] 'process raft request' (duration: 144.16228ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:02.951248Z","caller":"traceutil/trace.go:171","msg":"trace[2008322878] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:801; }","duration":"270.804601ms","start":"2026-02-23T08:47:02.680416Z","end":"2026-02-23T08:47:02.951221Z","steps":["trace[2008322878] 'read index received' (duration: 270.601306ms)","trace[2008322878] 'applied index is now lower than readState.Index' (duration: 202.175µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:02.951361Z","caller":"traceutil/trace.go:171","msg":"trace[1580092934] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"324.146328ms","start":"2026-02-23T08:47:02.627184Z","end":"2026-02-23T08:47:02.951331Z","steps":["trace[1580092934] 'process raft request' (duration: 323.870881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:02.951491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.046068ms","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-23T08:47:02.951536Z","caller":"traceutil/trace.go:171","msg":"trace[1086468489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"271.137891ms","start":"2026-02-23T08:47:02.680386Z","end":"2026-02-23T08:47:02.951524Z","steps":["trace[1086468489] 'agreement among raft nodes before linearized reading' (duration: 270.936955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:02.951619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:02.627166Z","time spent":"324.262912ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:47:03.149693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.203849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:47:03.150085Z","caller":"traceutil/trace.go:171","msg":"trace[1760013370] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"192.544932ms","start":"2026-02-23T08:47:02.95747Z","end":"2026-02-23T08:47:03.150015Z","steps":["trace[1760013370] 'process raft request' (duration: 73.911301ms)","trace[1760013370] 'compare' (duration: 118.025254ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:47:03.666026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.38326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:47:03.666157Z","caller":"traceutil/trace.go:171","msg":"trace[1015972403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"299.583325ms","start":"2026-02-23T08:47:03.366544Z","end":"2026-02-23T08:47:03.666128Z","steps":["trace[1015972403] 'range keys from in-memory index tree' (duration: 299.238186ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:15.20312Z","caller":"traceutil/trace.go:171","msg":"trace[1176426813] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"111.489774ms","start":"2026-02-23T08:47:15.091595Z","end":"2026-02-23T08:47:15.203084Z","steps":["trace[1176426813] 'process raft request' (duration: 111.257148ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:15.444405Z","caller":"traceutil/trace.go:171","msg":"trace[1228639403] linearizableReadLoop","detail":"{readStateIndex:893; appliedIndex:892; }","duration":"172.428911ms","start":"2026-02-23T08:47:15.271947Z","end":"2026-02-23T08:47:15.444376Z","steps":["trace[1228639403] 'read index received' (duration: 104.541874ms)","trace[1228639403] 'applied index is now lower than readState.Index' (duration: 67.886077ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:15.444491Z","caller":"traceutil/trace.go:171","msg":"trace[914469066] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"292.053649ms","start":"2026-02-23T08:47:15.152395Z","end":"2026-02-23T08:47:15.444449Z","steps":["trace[914469066] 'process raft request' (duration: 224.038839ms)","trace[914469066] 'compare' (duration: 67.598298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:47:15.444704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.740449ms","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-23T08:47:15.44475Z","caller":"traceutil/trace.go:171","msg":"trace[1694368312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:861; }","duration":"172.852372ms","start":"2026-02-23T08:47:15.271886Z","end":"2026-02-23T08:47:15.444738Z","steps":["trace[1694368312] 'agreement among raft nodes before linearized reading' (duration: 172.660027ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:16.208357Z","caller":"traceutil/trace.go:171","msg":"trace[17169734] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"121.806228ms","start":"2026-02-23T08:47:16.086526Z","end":"2026-02-23T08:47:16.208333Z","steps":["trace[17169734] 'process raft request' (duration: 121.116359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:16.708275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.172204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:47:16.70867Z","caller":"traceutil/trace.go:171","msg":"trace[780361416] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:895; }","duration":"342.966778ms","start":"2026-02-23T08:47:16.365686Z","end":"2026-02-23T08:47:16.708653Z","steps":["trace[780361416] 'read index received' (duration: 10.735295ms)","trace[780361416] 'applied index is now lower than readState.Index' (duration: 332.230523ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:16.708704Z","caller":"traceutil/trace.go:171","msg":"trace[1390382396] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"493.218929ms","start":"2026-02-23T08:47:16.21546Z","end":"2026-02-23T08:47:16.708679Z","steps":["trace[1390382396] 'process raft request' (duration: 160.946284ms)","trace[1390382396] 'compare' (duration: 330.970979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:47:16.708887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.752614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-23T08:47:16.708864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.155014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:47:16.708923Z","caller":"traceutil/trace.go:171","msg":"trace[1850827285] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:865; }","duration":"198.845146ms","start":"2026-02-23T08:47:16.510067Z","end":"2026-02-23T08:47:16.708912Z","steps":["trace[1850827285] 'agreement among raft nodes before linearized reading' (duration: 198.696442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:16.708925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:16.215435Z","time spent":"493.341682ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T08:47:16.708948Z","caller":"traceutil/trace.go:171","msg":"trace[872689409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:865; }","duration":"343.278687ms","start":"2026-02-23T08:47:16.365648Z","end":"2026-02-23T08:47:16.708927Z","steps":["trace[872689409] 'agreement among raft nodes before linearized reading' (duration: 343.096362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:16.70899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:16.365629Z","time spent":"343.352849ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T08:47:16.709041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.147677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-23T08:47:16.709142Z","caller":"traceutil/trace.go:171","msg":"trace[776347103] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:865; }","duration":"174.306652ms","start":"2026-02-23T08:47:16.534812Z","end":"2026-02-23T08:47:16.709118Z","steps":["trace[776347103] 'agreement among raft nodes before linearized reading' (duration: 174.019754ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:16.710229Z","caller":"traceutil/trace.go:171","msg":"trace[970017555] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"493.639171ms","start":"2026-02-23T08:47:16.21573Z","end":"2026-02-23T08:47:16.70937Z","steps":["trace[970017555] 'process raft request' (duration: 492.849109ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:16.710654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:16.215724Z","time spent":"494.821913ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:47:18.503134Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889118,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-23T08:47:18.847268Z","caller":"traceutil/trace.go:171","msg":"trace[1340664] linearizableReadLoop","detail":"{readStateIndex:900; appliedIndex:899; }","duration":"845.155114ms","start":"2026-02-23T08:47:18.002088Z","end":"2026-02-23T08:47:18.847243Z","steps":["trace[1340664] 'read index received' (duration: 844.938048ms)","trace[1340664] 'applied index is now lower than readState.Index' (duration: 215.976µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:18.847575Z","caller":"traceutil/trace.go:171","msg":"trace[2046192271] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"970.103288ms","start":"2026-02-23T08:47:17.87746Z","end":"2026-02-23T08:47:18.847563Z","steps":["trace[2046192271] 'process raft request' (duration: 969.607625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.847677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:17.877441Z","time spent":"970.174351ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:47:18.847884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.815812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-23T08:47:18.848085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.267453ms","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-23T08:47:18.848122Z","caller":"traceutil/trace.go:171","msg":"trace[165693453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:868; }","duration":"635.340675ms","start":"2026-02-23T08:47:18.212773Z","end":"2026-02-23T08:47:18.848114Z","steps":["trace[165693453] 'agreement among raft nodes before linearized reading' (duration: 635.236743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.848157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:18.212753Z","time spent":"635.397957ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":511,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-23T08:47:18.848356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.397367ms","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-23T08:47:18.848392Z","caller":"traceutil/trace.go:171","msg":"trace[1582381812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:868; }","duration":"133.448919ms","start":"2026-02-23T08:47:18.714935Z","end":"2026-02-23T08:47:18.848384Z","steps":["trace[1582381812] 'agreement among raft nodes before linearized reading' (duration: 133.348496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.848618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.708176ms","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-23T08:47:18.848654Z","caller":"traceutil/trace.go:171","msg":"trace[1092362200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:868; }","duration":"133.745477ms","start":"2026-02-23T08:47:18.7149Z","end":"2026-02-23T08:47:18.848646Z","steps":["trace[1092362200] 'agreement among raft nodes before linearized reading' (duration: 133.634344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.8488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.236056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:47:18.848833Z","caller":"traceutil/trace.go:171","msg":"trace[483732778] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:868; }","duration":"486.309139ms","start":"2026-02-23T08:47:18.362516Z","end":"2026-02-23T08:47:18.848825Z","steps":["trace[483732778] 'agreement among raft nodes before linearized reading' (duration: 486.263918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.848862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:18.362487Z","time spent":"486.36947ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-23T08:47:18.847963Z","caller":"traceutil/trace.go:171","msg":"trace[2145762342] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:868; }","duration":"845.900124ms","start":"2026-02-23T08:47:18.002015Z","end":"2026-02-23T08:47:18.847915Z","steps":["trace[2145762342] 'agreement among raft nodes before linearized reading' (duration: 845.830762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:18.849159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:18.001991Z","time spent":"847.130398ms","remote":"127.0.0.1:45732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":15,"response size":28,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:47:33.110165Z","caller":"traceutil/trace.go:171","msg":"trace[1829545434] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"176.341018ms","start":"2026-02-23T08:47:32.933797Z","end":"2026-02-23T08:47:33.110138Z","steps":["trace[1829545434] 'read index received' (duration: 176.209824ms)","trace[1829545434] 'applied index is now lower than readState.Index' (duration: 129.964µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:33.110285Z","caller":"traceutil/trace.go:171","msg":"trace[459051538] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"183.366951ms","start":"2026-02-23T08:47:32.926882Z","end":"2026-02-23T08:47:33.110249Z","steps":["trace[459051538] 'process raft request' (duration: 183.114414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:33.110395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.439918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-23T08:47:33.110395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.584594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-23T08:47:33.110432Z","caller":"traceutil/trace.go:171","msg":"trace[61002634] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:907; }","duration":"173.496469ms","start":"2026-02-23T08:47:32.936926Z","end":"2026-02-23T08:47:33.110423Z","steps":["trace[61002634] 'agreement among raft nodes before linearized reading' (duration: 173.351635ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:33.110453Z","caller":"traceutil/trace.go:171","msg":"trace[1893861213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:907; }","duration":"176.670597ms","start":"2026-02-23T08:47:32.933764Z","end":"2026-02-23T08:47:33.110434Z","steps":["trace[1893861213] 'agreement among raft nodes before linearized reading' (duration: 176.506562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:35.334243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.33397ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-23T08:47:35.334374Z","caller":"traceutil/trace.go:171","msg":"trace[1907282489] transaction","detail":"{read_only:false; response_revision:919; number_of_response:1; }","duration":"175.32859ms","start":"2026-02-23T08:47:35.159027Z","end":"2026-02-23T08:47:35.334355Z","steps":["trace[1907282489] 'process raft request' (duration: 59.792404ms)","trace[1907282489] 'compare' (duration: 115.227547ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:47:35.343041Z","caller":"traceutil/trace.go:171","msg":"trace[1898736729] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"183.71871ms","start":"2026-02-23T08:47:35.159295Z","end":"2026-02-23T08:47:35.343013Z","steps":["trace[1898736729] 'process raft request' (duration: 183.163625ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:41.932803Z","caller":"traceutil/trace.go:171","msg":"trace[797219382] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"732.009732ms","start":"2026-02-23T08:47:41.200771Z","end":"2026-02-23T08:47:41.932781Z","steps":["trace[797219382] 'process raft request' (duration: 731.881568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:41.932956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:41.200748Z","time spent":"732.126975ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:47:42.877312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:43.37768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:43.8779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:44.378269Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:44.386564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000034079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:44.386703Z","caller":"traceutil/trace.go:171","msg":"trace[436552086] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000233504s","start":"2026-02-23T08:47:42.386431Z","end":"2026-02-23T08:47:44.386665Z","steps":["trace[436552086] 'agreement among raft nodes before linearized reading' (duration: 2.000043469s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:44.386793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:42.386423Z","time spent":"2.000345667s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:44 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:44.878457Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:45.378609Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:45.879364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:46.380429Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:46.387723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000940696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:46.387847Z","caller":"traceutil/trace.go:171","msg":"trace[649184465] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001120061s","start":"2026-02-23T08:47:44.386699Z","end":"2026-02-23T08:47:46.387819Z","steps":["trace[649184465] 'agreement among raft nodes before linearized reading' (duration: 2.000942896s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:46.387919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:44.386691Z","time spent":"2.001217154s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:46.881011Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:46.906222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.529512743s","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-23T08:47:46.906293Z","caller":"traceutil/trace.go:171","msg":"trace[469175393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.529597547s","start":"2026-02-23T08:47:42.376678Z","end":"2026-02-23T08:47:46.906275Z","steps":["trace[469175393] 'agreement among raft nodes before linearized reading' (duration: 4.529521424s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:46.906335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:42.376673Z","time spent":"4.529653698s","remote":"127.0.0.1:45898","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/23 08:47:46 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:47.208213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000197045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:47.20832Z","caller":"traceutil/trace.go:171","msg":"trace[183575270] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00038s","start":"2026-02-23T08:47:45.207922Z","end":"2026-02-23T08:47:47.208302Z","steps":["trace[183575270] 'agreement among raft nodes before linearized reading' (duration: 2.000251967s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:47.208374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:45.207892Z","time spent":"2.000465433s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:47.379052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:42.381914Z","time spent":"4.997124569s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/23 08:47:47 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:47.381278Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:47.882524Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:48.382771Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:48.396219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00068767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:48.396311Z","caller":"traceutil/trace.go:171","msg":"trace[1223185287] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000810043s","start":"2026-02-23T08:47:46.395474Z","end":"2026-02-23T08:47:48.396284Z","steps":["trace[1223185287] 'agreement among raft nodes before linearized reading' (duration: 2.00068257s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:48.396374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:46.395431Z","time spent":"2.000933236s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:48 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:48.883601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889341,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:49.377346Z","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-23T08:47:49.377444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:42.376619Z","time spent":"7.00081927s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:49.38076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999092595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:49.380848Z","caller":"traceutil/trace.go:171","msg":"trace[1620590396] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.99924098s","start":"2026-02-23T08:47:44.381573Z","end":"2026-02-23T08:47:49.380814Z","steps":["trace[1620590396] 'agreement among raft nodes before linearized reading' (duration: 4.999130887s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:49.380905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:44.381548Z","time spent":"4.999345612s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-23T08:47:49.38198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:42.381804Z","time spent":"7.000168493s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:49.877944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:50.378685Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:50.396913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000184895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:50.397052Z","caller":"traceutil/trace.go:171","msg":"trace[1562615745] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00038866s","start":"2026-02-23T08:47:48.396641Z","end":"2026-02-23T08:47:50.39703Z","steps":["trace[1562615745] 'agreement among raft nodes before linearized reading' (duration: 2.000222046s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:50.397113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:48.396623Z","time spent":"2.000478213s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:50 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:50.879421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:51.379573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:51.617911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000444662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:51.618037Z","caller":"traceutil/trace.go:171","msg":"trace[103378621] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000637497s","start":"2026-02-23T08:47:49.61737Z","end":"2026-02-23T08:47:51.618007Z","steps":["trace[103378621] 'agreement among raft nodes before linearized reading' (duration: 2.000431811s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:51.618171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:49.617305Z","time spent":"2.000850693s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:51.879727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:51.906452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.523745687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"warn","ts":"2026-02-23T08:47:51.907031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.076159ms","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-23T08:47:51.906943Z","caller":"traceutil/trace.go:171","msg":"trace[1405999283] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.525034123s","start":"2026-02-23T08:47:49.381873Z","end":"2026-02-23T08:47:51.906907Z","steps":["trace[1405999283] 'agreement among raft nodes before linearized reading' (duration: 2.523789389s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:47:51.907137Z","caller":"traceutil/trace.go:171","msg":"trace[1194952216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"523.290256ms","start":"2026-02-23T08:47:51.383823Z","end":"2026-02-23T08:47:51.907113Z","steps":["trace[1194952216] 'agreement among raft nodes before linearized reading' (duration: 523.124961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:51.907164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:49.381846Z","time spent":"2.525299721s","remote":"127.0.0.1:45898","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\" "} {"level":"warn","ts":"2026-02-23T08:47:51.907197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.383788Z","time spent":"523.398449ms","remote":"127.0.0.1:45898","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/23 08:47:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/23 08:47:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:51.907524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998656923s","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-23T08:47:51.907653Z","caller":"traceutil/trace.go:171","msg":"trace[144740315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998876089s","start":"2026-02-23T08:47:48.908716Z","end":"2026-02-23T08:47:51.907592Z","steps":["trace[144740315] 'agreement among raft nodes before linearized reading' (duration: 2.998709844s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:51.907587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.538822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"warn","ts":"2026-02-23T08:47:51.907771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:48.908682Z","time spent":"2.999060714s","remote":"127.0.0.1:45898","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/23 08:47:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-23T08:47:51.907852Z","caller":"traceutil/trace.go:171","msg":"trace[1013600801] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"519.836771ms","start":"2026-02-23T08:47:51.387942Z","end":"2026-02-23T08:47:51.907778Z","steps":["trace[1013600801] 'agreement among raft nodes before linearized reading' (duration: 519.591794ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:51.908627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.387911Z","time spent":"520.643583ms","remote":"127.0.0.1:45898","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/23 08:47:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:52.38061Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:52.4051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000171775s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:52.405277Z","caller":"traceutil/trace.go:171","msg":"trace[996945911] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000401362s","start":"2026-02-23T08:47:50.404846Z","end":"2026-02-23T08:47:52.405248Z","steps":["trace[996945911] 'agreement among raft nodes before linearized reading' (duration: 2.000190556s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:52.405392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:50.404832Z","time spent":"2.000545935s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:52 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:52.792334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:45.791949Z","time spent":"7.000375639s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:52.859461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000070472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:52.859577Z","caller":"traceutil/trace.go:171","msg":"trace[1863687057] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000252147s","start":"2026-02-23T08:47:50.859301Z","end":"2026-02-23T08:47:52.859554Z","steps":["trace[1863687057] 'agreement among raft nodes before linearized reading' (duration: 2.000104653s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:52.859635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:50.859277Z","time spent":"2.00034727s","remote":"127.0.0.1:45656","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-23T08:47:52.881037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:53.381297Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:53.393588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:46.392966Z","time spent":"7.000614787s","remote":"127.0.0.1:45712","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:53.881914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:54.381154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99707702s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:54.381236Z","caller":"traceutil/trace.go:171","msg":"trace[1116343656] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997229954s","start":"2026-02-23T08:47:51.383986Z","end":"2026-02-23T08:47:54.381216Z","steps":["trace[1116343656] 'agreement among raft nodes before linearized reading' (duration: 2.997125441s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:54.381284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.383943Z","time spent":"2.997331706s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/23 08:47:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:54.385036Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:54.41455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00072857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:47:54.414642Z","caller":"traceutil/trace.go:171","msg":"trace[264500787] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000865444s","start":"2026-02-23T08:47:52.413751Z","end":"2026-02-23T08:47:54.414616Z","steps":["trace[264500787] 'agreement among raft nodes before linearized reading' (duration: 2.00074303s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:54.41487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:52.413741Z","time spent":"2.0011108s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:54 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:54.886127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:55.386727Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:55.67117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.998872409s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:55.671267Z","caller":"traceutil/trace.go:171","msg":"trace[381373343] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; }","duration":"9.999042534s","start":"2026-02-23T08:47:45.672203Z","end":"2026-02-23T08:47:55.671246Z","steps":["trace[381373343] 'agreement among raft nodes before linearized reading' (duration: 9.998920481s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:55.671319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:45.672171Z","time spent":"9.999136936s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} 2026/02/23 08:47:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:55.887502Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:55.914031Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4f119c89accf6421","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:47:56.313299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:49.312256Z","time spent":"7.001031319s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:56.378567Z","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-23T08:47:56.378744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.642156224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:47:56.378804Z","caller":"traceutil/trace.go:171","msg":"trace[1319425126] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; }","duration":"12.642336539s","start":"2026-02-23T08:47:43.736443Z","end":"2026-02-23T08:47:56.37878Z","steps":["trace[1319425126] 'agreement among raft nodes before linearized reading' (duration: 12.642240177s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:56.378855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:43.736409Z","time spent":"12.642434142s","remote":"127.0.0.1:45688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-02-23T08:47:56.414921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999932989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:56.415015Z","caller":"traceutil/trace.go:171","msg":"trace[141337286] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000090493s","start":"2026-02-23T08:47:54.414905Z","end":"2026-02-23T08:47:56.414996Z","steps":["trace[141337286] 'agreement among raft nodes before linearized reading' (duration: 1.999971949s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:56.415062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:54.414874Z","time spent":"2.000178295s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:56.879677Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:56.905916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.908535Z","time spent":"4.997372607s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/23 08:47:56 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:57.38027Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:57.881071Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:58.381295Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:58.422202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999762413s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:47:58.422242Z","caller":"traceutil/trace.go:171","msg":"trace[1051981105] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999859006s","start":"2026-02-23T08:47:56.422371Z","end":"2026-02-23T08:47:58.42223Z","steps":["trace[1051981105] 'agreement among raft nodes before linearized reading' (duration: 1.999802835s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:47:58.422272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:56.42235Z","time spent":"1.999916617s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:47:58 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:58.882157Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:58.909758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.909272Z","time spent":"7.000478384s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:58.912962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:51.912022Z","time spent":"7.000931547s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:59.382783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:47:59.388067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:54.389176Z","time spent":"4.99888403s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/23 08:47:59 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:47:59.797728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:52.797554Z","time spent":"7.000165885s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:47:59.883003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:00.383927Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:00.422517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000086271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:48:00.422604Z","caller":"traceutil/trace.go:171","msg":"trace[1054943887] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000213495s","start":"2026-02-23T08:47:58.42237Z","end":"2026-02-23T08:48:00.422583Z","steps":["trace[1054943887] 'agreement among raft nodes before linearized reading' (duration: 2.000104362s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:00.422658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:58.422357Z","time spent":"2.000290007s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:00 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:00.884542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:01.385169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:01.885369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:02.385898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:02.431167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000857044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:02.431247Z","caller":"traceutil/trace.go:171","msg":"trace[1822067487] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000992958s","start":"2026-02-23T08:48:00.430236Z","end":"2026-02-23T08:48:02.431229Z","steps":["trace[1822067487] 'agreement among raft nodes before linearized reading' (duration: 2.000888425s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:02.431304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:00.430217Z","time spent":"2.00107252s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:02 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:02.886119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889357,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:02.923108Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4f119c89accf6421","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:03.318633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:56.317883Z","time spent":"7.0007443s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:03.379016Z","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-23T08:48:03.37928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.164704868s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:03.379314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.980944018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:03.379349Z","caller":"traceutil/trace.go:171","msg":"trace[1896586719] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; }","duration":"10.164841422s","start":"2026-02-23T08:47:53.214492Z","end":"2026-02-23T08:48:03.379333Z","steps":["trace[1896586719] 'agreement among raft nodes before linearized reading' (duration: 10.16474921s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:03.379362Z","caller":"traceutil/trace.go:171","msg":"trace[188653211] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a; range_end:; }","duration":"9.98100922s","start":"2026-02-23T08:47:53.398337Z","end":"2026-02-23T08:48:03.379347Z","steps":["trace[188653211] 'agreement among raft nodes before linearized reading' (duration: 9.980940838s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:03.379383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:53.214464Z","time spent":"10.164909943s","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:03.379404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:53.398292Z","time spent":"9.981103782s","remote":"127.0.0.1:45712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a\" "} {"level":"warn","ts":"2026-02-23T08:48:03.379314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.970208231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:03.379427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.169707476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:03.37957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.11238543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:03.379631Z","caller":"traceutil/trace.go:171","msg":"trace[1947563209] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; }","duration":"13.112483572s","start":"2026-02-23T08:47:50.267111Z","end":"2026-02-23T08:48:03.379594Z","steps":["trace[1947563209] 'agreement among raft nodes before linearized reading' (duration: 13.112448271s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:03.379618Z","caller":"traceutil/trace.go:171","msg":"trace[1516252651] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; }","duration":"11.169961632s","start":"2026-02-23T08:47:52.209626Z","end":"2026-02-23T08:48:03.379588Z","steps":["trace[1516252651] 'agreement among raft nodes before linearized reading' (duration: 11.169744606s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:03.379668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:50.267078Z","time spent":"13.112582555s","remote":"127.0.0.1:33826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:03.379687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:52.209594Z","time spent":"11.170078246s","remote":"127.0.0.1:33820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:03.379546Z","caller":"traceutil/trace.go:171","msg":"trace[1090570497] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; }","duration":"10.970470789s","start":"2026-02-23T08:47:52.409033Z","end":"2026-02-23T08:48:03.379504Z","steps":["trace[1090570497] 'agreement among raft nodes before linearized reading' (duration: 10.970258613s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:03.379833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:52.409003Z","time spent":"10.970778737s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-23T08:48:03.379453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.031114027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:03.380023Z","caller":"traceutil/trace.go:171","msg":"trace[1788612578] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; }","duration":"10.031687542s","start":"2026-02-23T08:47:53.3483Z","end":"2026-02-23T08:48:03.379987Z","steps":["trace[1788612578] 'agreement among raft nodes before linearized reading' (duration: 10.031116247s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:03.380115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:53.348231Z","time spent":"10.031848087s","remote":"127.0.0.1:57472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:03.879629Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:04.380273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:04.431324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999865086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:48:04.431408Z","caller":"traceutil/trace.go:171","msg":"trace[201320341] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00000466s","start":"2026-02-23T08:48:02.431385Z","end":"2026-02-23T08:48:04.43139Z","steps":["trace[201320341] 'agreement among raft nodes before linearized reading' (duration: 1.999897937s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:04.431456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:02.431364Z","time spent":"2.000082172s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:04 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:04.880799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:05.381829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:05.882983Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:06.383488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:06.439396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00068083s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:06.439452Z","caller":"traceutil/trace.go:171","msg":"trace[473070006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000785522s","start":"2026-02-23T08:48:04.438652Z","end":"2026-02-23T08:48:06.439438Z","steps":["trace[473070006] 'agreement among raft nodes before linearized reading' (duration: 2.00071641s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:06.43949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:04.438636Z","time spent":"2.000844914s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:06 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:06.802785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:59.801993Z","time spent":"7.000786212s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:06.883994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:07.38433Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:07.885473Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:08.386414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:08.439611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000157831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:08.439704Z","caller":"traceutil/trace.go:171","msg":"trace[1877694894] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000275445s","start":"2026-02-23T08:48:06.439407Z","end":"2026-02-23T08:48:08.439683Z","steps":["trace[1877694894] 'agreement among raft nodes before linearized reading' (duration: 2.000152541s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:08.439758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:06.439353Z","time spent":"2.000394868s","remote":"127.0.0.1:45652","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-23T08:48:08.886586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:09.387058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:09.888091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889361,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:09.930808Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4f119c89accf6421","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:10.323151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:03.322991Z","time spent":"7.000152494s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:10.379938Z","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-23T08:48:10.380141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.999902765s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:10.38019Z","caller":"traceutil/trace.go:171","msg":"trace[920163154] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; }","duration":"13.999944506s","start":"2026-02-23T08:47:56.380227Z","end":"2026-02-23T08:48:10.380172Z","steps":["trace[920163154] 'agreement among raft nodes before linearized reading' (duration: 13.999902725s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.380232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:47:56.380152Z","time spent":"14.00007141s","remote":"127.0.0.1:45688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":0,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"warn","ts":"2026-02-23T08:48:10.380157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.719406316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:10.380322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.330751381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:10.380087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.11464999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:10.380526Z","caller":"traceutil/trace.go:171","msg":"trace[1170427545] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; }","duration":"7.115130223s","start":"2026-02-23T08:48:03.265358Z","end":"2026-02-23T08:48:10.380488Z","steps":["trace[1170427545] 'agreement among raft nodes before linearized reading' (duration: 7.114686291s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.380566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:03.265337Z","time spent":"7.115214966s","remote":"127.0.0.1:45938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:10.380385Z","caller":"traceutil/trace.go:171","msg":"trace[1242275608] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; }","duration":"9.719644052s","start":"2026-02-23T08:48:00.66063Z","end":"2026-02-23T08:48:10.380274Z","steps":["trace[1242275608] 'agreement among raft nodes before linearized reading' (duration: 9.719442837s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.380686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:00.660607Z","time spent":"9.720059013s","remote":"127.0.0.1:45764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":0,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:10.380641Z","caller":"traceutil/trace.go:171","msg":"trace[1965034305] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; }","duration":"10.331153182s","start":"2026-02-23T08:48:00.049325Z","end":"2026-02-23T08:48:10.380478Z","steps":["trace[1965034305] 'agreement among raft nodes before linearized reading' (duration: 10.330782141s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.381135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.402131403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:10.381196Z","caller":"traceutil/trace.go:171","msg":"trace[1998134549] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; }","duration":"8.402241556s","start":"2026-02-23T08:48:01.978945Z","end":"2026-02-23T08:48:10.381187Z","steps":["trace[1998134549] 'agreement among raft nodes before linearized reading' (duration: 8.402167854s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.381228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:01.978924Z","time spent":"8.402294377s","remote":"127.0.0.1:45800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":0,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:10.381159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:00.049303Z","time spent":"10.331787828s","remote":"127.0.0.1:46152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":0,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:10.448753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000253425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:48:10.448828Z","caller":"traceutil/trace.go:171","msg":"trace[1466228753] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000393229s","start":"2026-02-23T08:48:08.448423Z","end":"2026-02-23T08:48:10.448816Z","steps":["trace[1466228753] 'agreement among raft nodes before linearized reading' (duration: 2.000287416s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:10.448867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:08.448405Z","time spent":"2.0004532s","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:10 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:10.880373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:11.380936Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:11.881999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:12.383015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:12.449662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000492351s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:48:12.449748Z","caller":"traceutil/trace.go:171","msg":"trace[228741157] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000594424s","start":"2026-02-23T08:48:10.449135Z","end":"2026-02-23T08:48:12.44973Z","steps":["trace[228741157] 'agreement among raft nodes before linearized reading' (duration: 2.000488201s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:12.449797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:10.449101Z","time spent":"2.000687146s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:12 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:12.859388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999872794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:12.859492Z","caller":"traceutil/trace.go:171","msg":"trace[1357760289] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000059009s","start":"2026-02-23T08:48:10.859413Z","end":"2026-02-23T08:48:12.859472Z","steps":["trace[1357760289] 'agreement among raft nodes before linearized reading' (duration: 1.999924346s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:12.859571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:10.859381Z","time spent":"2.000153241s","remote":"127.0.0.1:45656","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-23T08:48:12.88345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:13.384614Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:13.809222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:06.808074Z","time spent":"7.001141681s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:13.885084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:14.385992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:14.457778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000181563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:14.45788Z","caller":"traceutil/trace.go:171","msg":"trace[2046834143] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000355848s","start":"2026-02-23T08:48:12.457505Z","end":"2026-02-23T08:48:14.457861Z","steps":["trace[2046834143] 'agreement among raft nodes before linearized reading' (duration: 2.000214954s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:14.457938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:12.457419Z","time spent":"2.000504262s","remote":"127.0.0.1:45656","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-23T08:48:14.886951Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:15.387605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:15.389878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:08.38918Z","time spent":"7.000691605s","remote":"127.0.0.1:45800","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:15.888082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:16.388211Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:16.458304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999851723s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-23T08:48:16.458385Z","caller":"traceutil/trace.go:171","msg":"trace[1474397715] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999939686s","start":"2026-02-23T08:48:14.458419Z","end":"2026-02-23T08:48:16.458359Z","steps":["trace[1474397715] 'agreement among raft nodes before linearized reading' (duration: 1.999849203s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:16.458439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:14.458365Z","time spent":"2.000058398s","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/23 08:48:16 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-23T08:48:16.888359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:16.938512Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4f119c89accf6421","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.32855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:10.327775Z","time spent":"7.000767918s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-23T08:48:17.380405Z","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-23T08:48:17.380606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.833919443s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.380664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.496331308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.380698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.071433673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.380747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.997562167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.380745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.997126105s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:17.38076Z","caller":"traceutil/trace.go:171","msg":"trace[350557395] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; }","duration":"9.071563907s","start":"2026-02-23T08:48:08.309179Z","end":"2026-02-23T08:48:17.380743Z","steps":["trace[350557395] 'agreement among raft nodes before linearized reading' (duration: 9.071493885s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:17.380778Z","caller":"traceutil/trace.go:171","msg":"trace[790414204] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a; range_end:; }","duration":"13.997626819s","start":"2026-02-23T08:48:03.383142Z","end":"2026-02-23T08:48:17.380769Z","steps":["trace[790414204] 'agreement among raft nodes before linearized reading' (duration: 13.997594228s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:17.380776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.632017058s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-23T08:48:17.380804Z","caller":"traceutil/trace.go:171","msg":"trace[1956673886] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; }","duration":"13.997203887s","start":"2026-02-23T08:48:03.383581Z","end":"2026-02-23T08:48:17.380784Z","steps":["trace[1956673886] 'agreement among raft nodes before linearized reading' (duration: 13.997140625s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:17.380829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:03.383126Z","time spent":"13.99769409s","remote":"127.0.0.1:45712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.1896d3e0eb331d3a\" "} {"level":"info","ts":"2026-02-23T08:48:17.380832Z","caller":"traceutil/trace.go:171","msg":"trace[993601682] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; }","duration":"10.632121331s","start":"2026-02-23T08:48:06.748699Z","end":"2026-02-23T08:48:17.38082Z","steps":["trace[993601682] 'agreement among raft nodes before linearized reading' (duration: 10.632049009s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:17.380844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:03.383573Z","time spent":"13.997261719s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-23T08:48:17.380699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.32235655s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-23T08:48:17.380837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:08.309144Z","time spent":"9.071671s","remote":"127.0.0.1:57492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:17.380871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:06.748682Z","time spent":"10.632178112s","remote":"127.0.0.1:57512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:17.380799Z","caller":"traceutil/trace.go:171","msg":"trace[185488386] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; }","duration":"10.496508683s","start":"2026-02-23T08:48:06.88423Z","end":"2026-02-23T08:48:17.380738Z","steps":["trace[185488386] 'agreement among raft nodes before linearized reading' (duration: 10.49638694s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:17.3809Z","caller":"traceutil/trace.go:171","msg":"trace[256062157] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; }","duration":"9.322586447s","start":"2026-02-23T08:48:08.058292Z","end":"2026-02-23T08:48:17.380878Z","steps":["trace[256062157] 'agreement among raft nodes before linearized reading' (duration: 9.322391451s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:17.380977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:06.884198Z","time spent":"10.496750969s","remote":"127.0.0.1:33822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:17.380996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:08.05827Z","time spent":"9.322683039s","remote":"127.0.0.1:45888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":0,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:17.381141Z","caller":"traceutil/trace.go:171","msg":"trace[2127850653] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; }","duration":"8.834034176s","start":"2026-02-23T08:48:08.5466Z","end":"2026-02-23T08:48:17.380634Z","steps":["trace[2127850653] 'agreement among raft nodes before linearized reading' (duration: 8.833962024s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:17.381354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:08.546578Z","time spent":"8.834667364s","remote":"127.0.0.1:46112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":0,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:17.881539Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14920879155578889370,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-23T08:48:18.215921Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"35.839118047s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-23T08:48:18.216373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"32.365514689s","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-23T08:48:18.216479Z","caller":"traceutil/trace.go:171","msg":"trace[890732235] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:958; }","duration":"32.365640673s","start":"2026-02-23T08:47:45.850819Z","end":"2026-02-23T08:48:18.21646Z","steps":["trace[890732235] 'range keys from in-memory index tree' (duration: 32.365482618s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.216529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.278934858s","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-23T08:48:18.216686Z","caller":"traceutil/trace.go:171","msg":"trace[1346259358] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:958; }","duration":"2.279142794s","start":"2026-02-23T08:48:15.937516Z","end":"2026-02-23T08:48:18.216658Z","steps":["trace[1346259358] 'range keys from in-memory index tree' (duration: 2.27862933s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.21717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"17.323387552s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.217316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:04.63115Z","time spent":"13.586160996s","remote":"127.0.0.1:59952","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-23T08:48:18.217375Z","caller":"traceutil/trace.go:171","msg":"trace[1748032282] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:958; }","duration":"17.323608258s","start":"2026-02-23T08:48:00.893737Z","end":"2026-02-23T08:48:18.217345Z","steps":["trace[1748032282] 'range keys from in-memory index tree' (duration: 17.323366551s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.463467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999905455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-23T08:48:18.46358Z","caller":"traceutil/trace.go:171","msg":"trace[1348638779] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000070889s","start":"2026-02-23T08:48:16.463489Z","end":"2026-02-23T08:48:18.46356Z","steps":["trace[1348638779] 'agreement among raft nodes before linearized reading' (duration: 1.999925985s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.463634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:16.463476Z","time spent":"2.000147011s","remote":"127.0.0.1:45656","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-23T08:48:18.909158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.73435ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"warn","ts":"2026-02-23T08:48:18.909693Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"43.041µs","request":"header: lease_revoke:","response":"size:26","error":"lease not found"} {"level":"warn","ts":"2026-02-23T08:48:18.910484Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"69.322µs","request":"header: lease_revoke:","response":"size:26","error":"lease not found"} {"level":"warn","ts":"2026-02-23T08:48:18.91096Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"39.671µs","request":"header: lease_revoke:","response":"size:26","error":"lease not found"} {"level":"warn","ts":"2026-02-23T08:48:18.911139Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"56.862µs","request":"header: lease_revoke:","response":"size:26","error":"lease not found"} {"level":"info","ts":"2026-02-23T08:48:18.91138Z","caller":"traceutil/trace.go:171","msg":"trace[627830955] linearizableReadLoop","detail":"{readStateIndex:1020; appliedIndex:995; }","duration":"1.530828026s","start":"2026-02-23T08:48:17.380531Z","end":"2026-02-23T08:48:18.911359Z","steps":["trace[627830955] 'read index received' (duration: 835.714172ms)","trace[627830955] 'applied index is now lower than readState.Index' (duration: 695.112724ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:48:18.911432Z","caller":"traceutil/trace.go:171","msg":"trace[1958409405] transaction","detail":"{read_only:false; number_of_response:1; response_revision:967; }","duration":"1.5779134s","start":"2026-02-23T08:48:17.333493Z","end":"2026-02-23T08:48:18.911406Z","steps":["trace[1958409405] 'process raft request' (duration: 1.577679614s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.517203032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5396"} {"level":"warn","ts":"2026-02-23T08:48:18.911547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.931633433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:48:18.911519Z","caller":"traceutil/trace.go:171","msg":"trace[1309833559] transaction","detail":"{read_only:false; number_of_response:1; response_revision:967; }","duration":"5.096943543s","start":"2026-02-23T08:48:13.814526Z","end":"2026-02-23T08:48:18.911469Z","steps":["trace[1309833559] 'process raft request' (duration: 5.096519271s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.529950048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.91162Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"4f119c89accf6421","error":"lease not found"} {"level":"info","ts":"2026-02-23T08:48:18.911666Z","caller":"traceutil/trace.go:171","msg":"trace[1406243800] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:0; response_revision:967; }","duration":"8.53002573s","start":"2026-02-23T08:48:10.381634Z","end":"2026-02-23T08:48:18.91166Z","steps":["trace[1406243800] 'agreement among raft nodes before linearized reading' (duration: 8.529974049s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.600673027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.911713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.856155175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.911733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.231509529s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-23T08:48:18.911766Z","caller":"traceutil/trace.go:171","msg":"trace[1452198698] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:967; }","duration":"5.600717289s","start":"2026-02-23T08:48:13.311036Z","end":"2026-02-23T08:48:18.911753Z","steps":["trace[1452198698] 'agreement among raft nodes before linearized reading' (duration: 5.600638997s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.52584278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"warn","ts":"2026-02-23T08:48:18.911801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:17.384727Z","time spent":"1.527073473s","remote":"127.0.0.1:45712","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-23T08:48:18.911802Z","caller":"traceutil/trace.go:171","msg":"trace[1114004764] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:967; }","duration":"6.231640943s","start":"2026-02-23T08:48:12.680148Z","end":"2026-02-23T08:48:18.91179Z","steps":["trace[1114004764] 'agreement among raft nodes before linearized reading' (duration: 6.231494189s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.919028733s","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-23T08:48:18.911807Z","caller":"traceutil/trace.go:171","msg":"trace[1745784035] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:967; }","duration":"7.856307929s","start":"2026-02-23T08:48:11.055475Z","end":"2026-02-23T08:48:18.911783Z","steps":["trace[1745784035] 'agreement among raft nodes before linearized reading' (duration: 7.856124484s)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:18.911847Z","caller":"traceutil/trace.go:171","msg":"trace[345692160] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:967; }","duration":"5.919256749s","start":"2026-02-23T08:48:12.99258Z","end":"2026-02-23T08:48:18.911837Z","steps":["trace[345692160] 'agreement among raft nodes before linearized reading' (duration: 5.919045563s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:12.68013Z","time spent":"6.231708504s","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":28,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:18.911868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:11.055453Z","time spent":"7.856403862s","remote":"127.0.0.1:46074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":26,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:18.911877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.188111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.911881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:12.992555Z","time spent":"5.919318481s","remote":"127.0.0.1:45718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:18.911917Z","caller":"traceutil/trace.go:171","msg":"trace[1727258678] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"447.265363ms","start":"2026-02-23T08:48:18.46464Z","end":"2026-02-23T08:48:18.911906Z","steps":["trace[1727258678] 'agreement among raft nodes before linearized reading' (duration: 447.204101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.897433ms","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":"warn","ts":"2026-02-23T08:48:18.911971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:18.464619Z","time spent":"447.341964ms","remote":"127.0.0.1:45652","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-23T08:48:18.911969Z","caller":"traceutil/trace.go:171","msg":"trace[1020320320] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:967; }","duration":"343.979045ms","start":"2026-02-23T08:48:18.567977Z","end":"2026-02-23T08:48:18.911956Z","steps":["trace[1020320320] 'agreement among raft nodes before linearized reading' (duration: 343.812551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.911808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:13.311007Z","time spent":"5.6007923s","remote":"127.0.0.1:33836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:18.911563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:17.333456Z","time spent":"1.578032444s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":568,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T08:48:18.911813Z","caller":"traceutil/trace.go:171","msg":"trace[1133850770] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:967; }","duration":"1.525893721s","start":"2026-02-23T08:48:17.385906Z","end":"2026-02-23T08:48:18.9118Z","steps":["trace[1133850770] 'agreement among raft nodes before linearized reading' (duration: 1.525828469s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.912105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:18.567915Z","time spent":"344.175751ms","remote":"127.0.0.1:45824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":28,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:18.912157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:17.385897Z","time spent":"1.52624051s","remote":"127.0.0.1:45810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7066,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-23T08:48:18.911757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:10.381612Z","time spent":"8.530135114s","remote":"127.0.0.1:45688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.235\" "} {"level":"info","ts":"2026-02-23T08:48:18.911587Z","caller":"traceutil/trace.go:171","msg":"trace[1467156630] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:967; }","duration":"3.517314765s","start":"2026-02-23T08:48:15.394226Z","end":"2026-02-23T08:48:18.91154Z","steps":["trace[1467156630] 'agreement among raft nodes before linearized reading' (duration: 3.517153071s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.912594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:15.394207Z","time spent":"3.518372544s","remote":"127.0.0.1:45800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5417,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-23T08:48:18.911586Z","caller":"traceutil/trace.go:171","msg":"trace[643451893] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:967; }","duration":"4.931716245s","start":"2026-02-23T08:48:13.979855Z","end":"2026-02-23T08:48:18.911571Z","steps":["trace[643451893] 'agreement among raft nodes before linearized reading' (duration: 4.931670814s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.912876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:13.814495Z","time spent":"5.098298s","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":719,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T08:48:18.912486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.301156758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:48:18.911507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.895242926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:48:18.913864Z","caller":"traceutil/trace.go:171","msg":"trace[743524424] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:967; }","duration":"3.897633512s","start":"2026-02-23T08:48:15.016199Z","end":"2026-02-23T08:48:18.913832Z","steps":["trace[743524424] 'agreement among raft nodes before linearized reading' (duration: 3.895263887s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.913925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:15.016176Z","time spent":"3.897729155s","remote":"127.0.0.1:45858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":26,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:18.913727Z","caller":"traceutil/trace.go:171","msg":"trace[461610395] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:967; }","duration":"3.302425972s","start":"2026-02-23T08:48:15.611282Z","end":"2026-02-23T08:48:18.913708Z","steps":["trace[461610395] 'agreement among raft nodes before linearized reading' (duration: 3.301170158s)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:18.914198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:15.611261Z","time spent":"3.302847824s","remote":"127.0.0.1:46164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":26,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-23T08:48:18.915036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:13.979832Z","time spent":"4.9351801s","remote":"127.0.0.1:57482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":26,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-23T08:48:22.114127Z","caller":"traceutil/trace.go:171","msg":"trace[194774197] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"223.105247ms","start":"2026-02-23T08:48:21.890988Z","end":"2026-02-23T08:48:22.114093Z","steps":["trace[194774197] 'read index received' (duration: 216.345413ms)","trace[194774197] 'applied index is now lower than readState.Index' (duration: 6.758704ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:48:22.114196Z","caller":"traceutil/trace.go:171","msg":"trace[2013698302] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"289.97009ms","start":"2026-02-23T08:48:21.824181Z","end":"2026-02-23T08:48:22.114151Z","steps":["trace[2013698302] 'process raft request' (duration: 283.259007ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:22.114401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.385145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-23T08:48:22.114446Z","caller":"traceutil/trace.go:171","msg":"trace[1698504323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1030; }","duration":"223.484058ms","start":"2026-02-23T08:48:21.890949Z","end":"2026-02-23T08:48:22.114433Z","steps":["trace[1698504323] 'agreement among raft nodes before linearized reading' (duration: 223.256641ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:24.360305Z","caller":"traceutil/trace.go:171","msg":"trace[381366358] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"111.03104ms","start":"2026-02-23T08:48:24.249251Z","end":"2026-02-23T08:48:24.360282Z","steps":["trace[381366358] 'read index received' (duration: 17.792016ms)","trace[381366358] 'applied index is now lower than readState.Index' (duration: 93.238014ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:48:24.360449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.179424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:48:24.360547Z","caller":"traceutil/trace.go:171","msg":"trace[1380032900] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1034; }","duration":"111.331128ms","start":"2026-02-23T08:48:24.249202Z","end":"2026-02-23T08:48:24.360533Z","steps":["trace[1380032900] 'agreement among raft nodes before linearized reading' (duration: 111.168743ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:24.360617Z","caller":"traceutil/trace.go:171","msg":"trace[1151576034] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"177.129733ms","start":"2026-02-23T08:48:24.183453Z","end":"2026-02-23T08:48:24.360583Z","steps":["trace[1151576034] 'process raft request' (duration: 83.649532ms)","trace[1151576034] 'compare' (duration: 93.07675ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:48:36.626719Z","caller":"traceutil/trace.go:171","msg":"trace[387323268] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"261.053122ms","start":"2026-02-23T08:48:36.365642Z","end":"2026-02-23T08:48:36.626695Z","steps":["trace[387323268] 'read index received' (duration: 260.916969ms)","trace[387323268] 'applied index is now lower than readState.Index' (duration: 134.863µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:48:36.626793Z","caller":"traceutil/trace.go:171","msg":"trace[963406414] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"382.023823ms","start":"2026-02-23T08:48:36.244734Z","end":"2026-02-23T08:48:36.626758Z","steps":["trace[963406414] 'process raft request' (duration: 381.821757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:36.626862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.210727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:48:36.6269Z","caller":"traceutil/trace.go:171","msg":"trace[1057440382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1088; }","duration":"261.29812ms","start":"2026-02-23T08:48:36.36559Z","end":"2026-02-23T08:48:36.626888Z","steps":["trace[1057440382] 'agreement among raft nodes before linearized reading' (duration: 261.226858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:36.626985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.390057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"warn","ts":"2026-02-23T08:48:36.626988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:48:36.244708Z","time spent":"382.177937ms","remote":"127.0.0.1:45898","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T08:48:36.62705Z","caller":"traceutil/trace.go:171","msg":"trace[1603489196] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1088; }","duration":"178.49774ms","start":"2026-02-23T08:48:36.448532Z","end":"2026-02-23T08:48:36.627029Z","steps":["trace[1603489196] 'agreement among raft nodes before linearized reading' (duration: 178.323826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:48:36.62715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.186492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-23T08:48:36.627246Z","caller":"traceutil/trace.go:171","msg":"trace[1121855343] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1088; }","duration":"141.301355ms","start":"2026-02-23T08:48:36.485922Z","end":"2026-02-23T08:48:36.627224Z","steps":["trace[1121855343] 'agreement among raft nodes before linearized reading' (duration: 140.972316ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:48:36.753265Z","caller":"traceutil/trace.go:171","msg":"trace[1250837533] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"121.716282ms","start":"2026-02-23T08:48:36.631511Z","end":"2026-02-23T08:48:36.753227Z","steps":["trace[1250837533] 'process raft request' (duration: 109.545499ms)","trace[1250837533] 'compare' (duration: 11.940636ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:48:36.75353Z","caller":"traceutil/trace.go:171","msg":"trace[107108158] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"121.972828ms","start":"2026-02-23T08:48:36.63152Z","end":"2026-02-23T08:48:36.753493Z","steps":["trace[107108158] 'process raft request' (duration: 121.642489ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:49:00.52798Z","caller":"traceutil/trace.go:171","msg":"trace[507934708] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"114.540915ms","start":"2026-02-23T08:49:00.413412Z","end":"2026-02-23T08:49:00.527952Z","steps":["trace[507934708] 'process raft request' (duration: 104.637155ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:49:20.329193Z","caller":"traceutil/trace.go:171","msg":"trace[954261173] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"122.566796ms","start":"2026-02-23T08:49:20.206591Z","end":"2026-02-23T08:49:20.329158Z","steps":["trace[954261173] 'read index received' (duration: 57.559622ms)","trace[954261173] 'applied index is now lower than readState.Index' (duration: 65.006234ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:49:20.329256Z","caller":"traceutil/trace.go:171","msg":"trace[985420911] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"181.3602ms","start":"2026-02-23T08:49:20.147861Z","end":"2026-02-23T08:49:20.329221Z","steps":["trace[985420911] 'process raft request' (duration: 116.371957ms)","trace[985420911] 'compare' (duration: 64.705656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:49:20.330051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.450109ms","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-23T08:49:20.330103Z","caller":"traceutil/trace.go:171","msg":"trace[99031125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1277; }","duration":"123.548612ms","start":"2026-02-23T08:49:20.206541Z","end":"2026-02-23T08:49:20.33009Z","steps":["trace[99031125] 'agreement among raft nodes before linearized reading' (duration: 122.7272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:49:20.59068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.117537ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:38"} {"level":"info","ts":"2026-02-23T08:49:21.367122Z","caller":"traceutil/trace.go:171","msg":"trace[1544364873] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"100.503067ms","start":"2026-02-23T08:49:21.266581Z","end":"2026-02-23T08:49:21.367084Z","steps":["trace[1544364873] 'process raft request' (duration: 100.342293ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:49:22.584988Z","caller":"traceutil/trace.go:171","msg":"trace[1275886443] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"194.554678ms","start":"2026-02-23T08:49:22.390407Z","end":"2026-02-23T08:49:22.584961Z","steps":["trace[1275886443] 'process raft request' (duration: 135.617369ms)","trace[1275886443] 'compare' (duration: 58.834257ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T08:50:34.988603Z","caller":"traceutil/trace.go:171","msg":"trace[1437253271] transaction","detail":"{read_only:false; response_revision:1582; number_of_response:1; }","duration":"235.926849ms","start":"2026-02-23T08:50:34.752647Z","end":"2026-02-23T08:50:34.988574Z","steps":["trace[1437253271] 'process raft request' (duration: 235.664702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:50:38.693329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.947931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T08:50:38.693445Z","caller":"traceutil/trace.go:171","msg":"trace[95703146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1597; }","duration":"327.103865ms","start":"2026-02-23T08:50:38.366297Z","end":"2026-02-23T08:50:38.693401Z","steps":["trace[95703146] 'range keys from in-memory index tree' (duration: 326.849829ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T08:50:38.693525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T08:50:38.366274Z","time spent":"327.227998ms","remote":"127.0.0.1:45656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-23T08:50:46.002548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.085823ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T08:50:46.00258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.411975ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-02-23T08:50:46.002714Z","caller":"traceutil/trace.go:171","msg":"trace[97036033] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1627; }","duration":"152.265068ms","start":"2026-02-23T08:50:45.850427Z","end":"2026-02-23T08:50:46.002692Z","steps":["trace[97036033] 'range keys from in-memory index tree' (duration: 152.064322ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T08:50:46.002808Z","caller":"traceutil/trace.go:171","msg":"trace[1419918496] linearizableReadLoop","detail":"{readStateIndex:1711; appliedIndex:1710; }","duration":"103.292853ms","start":"2026-02-23T08:50:45.899457Z","end":"2026-02-23T08:50:46.00275Z","steps":["trace[1419918496] 'read index received' (duration: 48.522µs)","trace[1419918496] 'applied index is now lower than readState.Index' (duration: 103.241841ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T08:50:46.003027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.57342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-23T08:50:46.003074Z","caller":"traceutil/trace.go:171","msg":"trace[575214999] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1627; }","duration":"103.664013ms","start":"2026-02-23T08:50:45.899397Z","end":"2026-02-23T08:50:46.003061Z","steps":["trace[575214999] 'agreement among raft nodes before linearized reading' (duration: 103.501358ms)"],"step_count":1}