{"level":"warn","ts":"2026-02-25T10:07:03.407223Z","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-25T10:07:03.407373Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67: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-25T10:07:03.407488Z","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-25T10:07:03.407518Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-02-25T10:07:03.407658Z","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-25T10:07:03.408976Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-02-25T10:07:03.409292Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67: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-25T10:07:03.413361Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.564087ms"} {"level":"info","ts":"2026-02-25T10:07:03.422007Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-02-25T10:07:03.422255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T10:07:03.422291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-02-25T10:07:03.422391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T10:07:03.422401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.42257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-02-25T10:07:03.428963Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T10:07:03.430693Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T10:07:03.432122Z","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-25T10:07:03.43554Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T10:07:03.435907Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T10:07:03.43604Z","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-25T10:07:03.436357Z","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-25T10:07:03.436425Z","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-25T10:07:03.440314Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-02-25T10:07:03.4406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-02-25T10:07:03.446998Z","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-25T10:07:03.447215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-02-25T10:07:03.447276Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-02-25T10:07:03.447528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T10:07:03.447575Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T10:07:03.62659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.626655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.626681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-02-25T10:07:03.626697Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.626702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.626711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.626724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-02-25T10:07:03.633224Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T10:07:03.633304Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:07:03.633282Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T10:07:03.633609Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T10:07:03.633695Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T10:07:03.633705Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.634352Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.634438Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.634483Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T10:07:03.6367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"info","ts":"2026-02-25T10:07:03.637302Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-25T10:07:12.677648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.94549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-25T10:07:12.677801Z","caller":"traceutil/trace.go:171","msg":"trace[1899048085] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:329; }","duration":"124.186326ms","start":"2026-02-25T10:07:12.55359Z","end":"2026-02-25T10:07:12.677776Z","steps":["trace[1899048085] 'range keys from in-memory index tree' (duration: 123.790627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:13.467218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722288383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:13.967836Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722288383,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:14.057705Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.374882609s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:14.058268Z","caller":"traceutil/trace.go:171","msg":"trace[44978713] linearizableReadLoop","detail":"{readStateIndex:344; appliedIndex:343; }","duration":"1.091843939s","start":"2026-02-25T10:07:12.966394Z","end":"2026-02-25T10:07:14.058238Z","steps":["trace[44978713] 'read index received' (duration: 1.091544862s)","trace[44978713] 'applied index is now lower than readState.Index' (duration: 297.517µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:14.058306Z","caller":"traceutil/trace.go:171","msg":"trace[1101447482] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.375609327s","start":"2026-02-25T10:07:12.682674Z","end":"2026-02-25T10:07:14.058283Z","steps":["trace[1101447482] 'process raft request' (duration: 1.375394901s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.058585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.748432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T10:07:14.058592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.702239ms","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-25T10:07:14.058648Z","caller":"traceutil/trace.go:171","msg":"trace[1135418273] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:330; }","duration":"113.873795ms","start":"2026-02-25T10:07:13.944754Z","end":"2026-02-25T10:07:14.058628Z","steps":["trace[1135418273] 'agreement among raft nodes before linearized reading' (duration: 113.716911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.058631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.642615ms","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-25T10:07:14.058697Z","caller":"traceutil/trace.go:171","msg":"trace[629673968] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:330; }","duration":"663.902833ms","start":"2026-02-25T10:07:13.394769Z","end":"2026-02-25T10:07:14.058672Z","steps":["trace[629673968] 'agreement among raft nodes before linearized reading' (duration: 663.704719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.058594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092133616s","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-25T10:07:14.05876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:13.394735Z","time spent":"664.014755ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:07:14.05882Z","caller":"traceutil/trace.go:171","msg":"trace[22044845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:330; }","duration":"1.092434683s","start":"2026-02-25T10:07:12.966339Z","end":"2026-02-25T10:07:14.058773Z","steps":["trace[22044845] 'agreement among raft nodes before linearized reading' (duration: 1.092033104s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:14.058707Z","caller":"traceutil/trace.go:171","msg":"trace[1317500285] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:330; }","duration":"210.781748ms","start":"2026-02-25T10:07:13.847901Z","end":"2026-02-25T10:07:14.058683Z","steps":["trace[1317500285] 'agreement among raft nodes before linearized reading' (duration: 210.579124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:14.058896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.966312Z","time spent":"1.092561455s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:14.059137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:12.682639Z","time spent":"1.375778549s","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:20.680356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722288528,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:20.945439Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.391410982s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:20.946112Z","caller":"traceutil/trace.go:171","msg":"trace[441904539] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"766.254908ms","start":"2026-02-25T10:07:20.179825Z","end":"2026-02-25T10:07:20.94608Z","steps":["trace[441904539] 'read index received' (duration: 765.804518ms)","trace[441904539] 'applied index is now lower than readState.Index' (duration: 449.5µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:20.946402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.553464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T10:07:20.946459Z","caller":"traceutil/trace.go:171","msg":"trace[1438976365] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"766.690177ms","start":"2026-02-25T10:07:20.17975Z","end":"2026-02-25T10:07:20.94644Z","steps":["trace[1438976365] 'agreement among raft nodes before linearized reading' (duration: 766.530614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:20.946605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.179725Z","time spent":"766.83893ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:20.94663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.248862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T10:07:20.946667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.221715ms","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-25T10:07:20.946412Z","caller":"traceutil/trace.go:171","msg":"trace[1982674838] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"1.392490355s","start":"2026-02-25T10:07:19.553879Z","end":"2026-02-25T10:07:20.94637Z","steps":["trace[1982674838] 'process raft request' (duration: 1.391873882s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:20.946754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.000717ms","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-25T10:07:20.946727Z","caller":"traceutil/trace.go:171","msg":"trace[1823603074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:405; }","duration":"704.400946ms","start":"2026-02-25T10:07:20.242306Z","end":"2026-02-25T10:07:20.946707Z","steps":["trace[1823603074] 'agreement among raft nodes before linearized reading' (duration: 704.172791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:20.946886Z","caller":"traceutil/trace.go:171","msg":"trace[315189327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"733.215552ms","start":"2026-02-25T10:07:20.213639Z","end":"2026-02-25T10:07:20.946855Z","steps":["trace[315189327] 'agreement among raft nodes before linearized reading' (duration: 732.882544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:20.946898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.24229Z","time spent":"704.58667ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T10:07:20.946813Z","caller":"traceutil/trace.go:171","msg":"trace[1661141477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:405; }","duration":"764.440359ms","start":"2026-02-25T10:07:20.182342Z","end":"2026-02-25T10:07:20.946782Z","steps":["trace[1661141477] 'agreement among raft nodes before linearized reading' (duration: 764.133623ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:20.946944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.21361Z","time spent":"733.324325ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:20.946948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.182315Z","time spent":"764.623874ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:20.947369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:19.553848Z","time spent":"1.392987284s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:21.73729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.534137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:21.73748Z","caller":"traceutil/trace.go:171","msg":"trace[243918604] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"784.143012ms","start":"2026-02-25T10:07:20.953323Z","end":"2026-02-25T10:07:21.737466Z","steps":["trace[243918604] 'process raft request' (duration: 784.121362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:21.737526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.95331Z","time spent":"784.195963ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:21.73757Z","caller":"traceutil/trace.go:171","msg":"trace[585807270] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"785.471941ms","start":"2026-02-25T10:07:20.95208Z","end":"2026-02-25T10:07:21.737552Z","steps":["trace[585807270] 'process raft request' (duration: 54.486235ms)","trace[585807270] 'compare' (duration: 730.381413ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:21.737633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.95206Z","time spent":"785.540372ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:21.737603Z","caller":"traceutil/trace.go:171","msg":"trace[17736804] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"785.217795ms","start":"2026-02-25T10:07:20.952305Z","end":"2026-02-25T10:07:21.737523Z","steps":["trace[17736804] 'process raft request' (duration: 785.080592ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:21.73775Z","caller":"traceutil/trace.go:171","msg":"trace[800504210] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"785.266105ms","start":"2026-02-25T10:07:20.952447Z","end":"2026-02-25T10:07:21.737713Z","steps":["trace[800504210] 'process raft request' (duration: 784.977779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:21.737848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.952277Z","time spent":"785.44722ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:21.738011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:20.952411Z","time spent":"785.43656ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:27.111068Z","caller":"traceutil/trace.go:171","msg":"trace[691828492] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"823.218207ms","start":"2026-02-25T10:07:26.287821Z","end":"2026-02-25T10:07:27.111039Z","steps":["trace[691828492] 'process raft request' (duration: 823.008662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:27.111315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:26.287775Z","time spent":"823.415231ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:27.566794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.38662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:27.566824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.34666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:07:27.566909Z","caller":"traceutil/trace.go:171","msg":"trace[953488591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"430.566484ms","start":"2026-02-25T10:07:27.13631Z","end":"2026-02-25T10:07:27.566877Z","steps":["trace[953488591] 'range keys from in-memory index tree' (duration: 430.286277ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:27.566933Z","caller":"traceutil/trace.go:171","msg":"trace[833392512] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:452; }","duration":"126.541205ms","start":"2026-02-25T10:07:27.440369Z","end":"2026-02-25T10:07:27.56691Z","steps":["trace[833392512] 'range keys from in-memory index tree' (duration: 126.174797ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:27.566965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:27.136286Z","time spent":"430.668335ms","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:27.936591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.398441ms","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-25T10:07:27.93665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.186579ms","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-25T10:07:27.936706Z","caller":"traceutil/trace.go:171","msg":"trace[684528763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:453; }","duration":"171.247931ms","start":"2026-02-25T10:07:27.765444Z","end":"2026-02-25T10:07:27.936692Z","steps":["trace[684528763] 'range keys from in-memory index tree' (duration: 171.019325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:27.936703Z","caller":"traceutil/trace.go:171","msg":"trace[640513566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"168.531633ms","start":"2026-02-25T10:07:27.768144Z","end":"2026-02-25T10:07:27.936676Z","steps":["trace[640513566] 'range keys from in-memory index tree' (duration: 168.264887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:27.936713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.302428ms","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-25T10:07:27.936845Z","caller":"traceutil/trace.go:171","msg":"trace[453521259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"175.467781ms","start":"2026-02-25T10:07:27.761338Z","end":"2026-02-25T10:07:27.936806Z","steps":["trace[453521259] 'range keys from in-memory index tree' (duration: 175.120274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:27.936914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.049166ms","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-25T10:07:27.937035Z","caller":"traceutil/trace.go:171","msg":"trace[1534467168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:453; }","duration":"171.24568ms","start":"2026-02-25T10:07:27.76576Z","end":"2026-02-25T10:07:27.937006Z","steps":["trace[1534467168] 'range keys from in-memory index tree' (duration: 170.666778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:38.025656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.733243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:07:38.025771Z","caller":"traceutil/trace.go:171","msg":"trace[2065636610] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:475; }","duration":"584.925427ms","start":"2026-02-25T10:07:37.440823Z","end":"2026-02-25T10:07:38.025749Z","steps":["trace[2065636610] 'range keys from in-memory index tree' (duration: 584.60344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:38.025828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:37.440798Z","time spent":"585.017649ms","remote":"127.0.0.1:45214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.67\" "} {"level":"warn","ts":"2026-02-25T10:07:38.025673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.416959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12673"} {"level":"info","ts":"2026-02-25T10:07:38.025985Z","caller":"traceutil/trace.go:171","msg":"trace[1694933145] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:475; }","duration":"325.799717ms","start":"2026-02-25T10:07:37.70016Z","end":"2026-02-25T10:07:38.02596Z","steps":["trace[1694933145] 'range keys from in-memory index tree' (duration: 325.228545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:38.026038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:37.700134Z","time spent":"325.892389ms","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12697,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-25T10:07:41.885429Z","caller":"traceutil/trace.go:171","msg":"trace[819449584] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"461.985642ms","start":"2026-02-25T10:07:41.423403Z","end":"2026-02-25T10:07:41.885388Z","steps":["trace[819449584] 'process raft request' (duration: 461.771788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:41.88564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.423378Z","time spent":"462.156135ms","remote":"127.0.0.1:45358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:42.000744Z","caller":"traceutil/trace.go:171","msg":"trace[164677635] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"350.11301ms","start":"2026-02-25T10:07:41.650595Z","end":"2026-02-25T10:07:42.000708Z","steps":["trace[164677635] 'process raft request' (duration: 341.055379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:42.000939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:41.650531Z","time spent":"350.305395ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:47.869004Z","caller":"traceutil/trace.go:171","msg":"trace[137828337] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"101.767018ms","start":"2026-02-25T10:07:47.767201Z","end":"2026-02-25T10:07:47.868968Z","steps":["trace[137828337] 'process raft request' (duration: 101.575534ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.086239Z","caller":"traceutil/trace.go:171","msg":"trace[1168303089] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"128.652685ms","start":"2026-02-25T10:07:50.957556Z","end":"2026-02-25T10:07:51.086209Z","steps":["trace[1168303089] 'process raft request' (duration: 91.723546ms)","trace[1168303089] 'compare' (duration: 36.754185ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.288785Z","caller":"traceutil/trace.go:171","msg":"trace[964818800] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"165.207547ms","start":"2026-02-25T10:07:51.123535Z","end":"2026-02-25T10:07:51.288742Z","steps":["trace[964818800] 'process raft request' (duration: 165.021643ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.288857Z","caller":"traceutil/trace.go:171","msg":"trace[1410722093] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:597; }","duration":"152.644543ms","start":"2026-02-25T10:07:51.136182Z","end":"2026-02-25T10:07:51.288827Z","steps":["trace[1410722093] 'read index received' (duration: 105.62165ms)","trace[1410722093] 'applied index is now lower than readState.Index' (duration: 47.021753ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.288785Z","caller":"traceutil/trace.go:171","msg":"trace[1519926215] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"167.501615ms","start":"2026-02-25T10:07:51.12122Z","end":"2026-02-25T10:07:51.288722Z","steps":["trace[1519926215] 'process raft request' (duration: 120.566504ms)","trace[1519926215] 'compare' (duration: 46.583913ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:51.28908Z","caller":"traceutil/trace.go:171","msg":"trace[1776568222] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"157.485155ms","start":"2026-02-25T10:07:51.131572Z","end":"2026-02-25T10:07:51.289057Z","steps":["trace[1776568222] 'process raft request' (duration: 157.197029ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.289107Z","caller":"traceutil/trace.go:171","msg":"trace[688786456] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"160.038008ms","start":"2026-02-25T10:07:51.12904Z","end":"2026-02-25T10:07:51.289078Z","steps":["trace[688786456] 'process raft request' (duration: 159.577008ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.289095Z","caller":"traceutil/trace.go:171","msg":"trace[68027080] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"158.184409ms","start":"2026-02-25T10:07:51.130866Z","end":"2026-02-25T10:07:51.289051Z","steps":["trace[68027080] 'process raft request' (duration: 157.823342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:51.289315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.808276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:51.289389Z","caller":"traceutil/trace.go:171","msg":"trace[139826594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"153.191614ms","start":"2026-02-25T10:07:51.136152Z","end":"2026-02-25T10:07:51.289344Z","steps":["trace[139826594] 'agreement among raft nodes before linearized reading' (duration: 152.780196ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.291249Z","caller":"traceutil/trace.go:171","msg":"trace[1867785696] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"150.855933ms","start":"2026-02-25T10:07:51.140209Z","end":"2026-02-25T10:07:51.291065Z","steps":["trace[1867785696] 'process raft request' (duration: 150.305192ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.291898Z","caller":"traceutil/trace.go:171","msg":"trace[107747617] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"148.7679ms","start":"2026-02-25T10:07:51.143099Z","end":"2026-02-25T10:07:51.291866Z","steps":["trace[107747617] 'process raft request' (duration: 147.87286ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:51.293749Z","caller":"traceutil/trace.go:171","msg":"trace[10523910] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"152.612861ms","start":"2026-02-25T10:07:51.137983Z","end":"2026-02-25T10:07:51.290596Z","steps":["trace[10523910] 'process raft request' (duration: 151.334065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.684798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.824742ms","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-25T10:07:52.684975Z","caller":"traceutil/trace.go:171","msg":"trace[567488898] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:664; }","duration":"817.013186ms","start":"2026-02-25T10:07:51.867938Z","end":"2026-02-25T10:07:52.684951Z","steps":["trace[567488898] 'range keys from in-memory index tree' (duration: 816.803172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.68512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.130496193s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:52.685291Z","caller":"traceutil/trace.go:171","msg":"trace[630438697] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.194990273s","start":"2026-02-25T10:07:51.490271Z","end":"2026-02-25T10:07:52.685261Z","steps":["trace[630438697] 'process raft request' (duration: 64.211285ms)","trace[630438697] 'compare' (duration: 1.130329949s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:52.685467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.490256Z","time spent":"1.195093315s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:52.72758Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.065843807s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T10:07:52.728265Z","caller":"traceutil/trace.go:171","msg":"trace[1025858712] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.237711035s","start":"2026-02-25T10:07:51.490518Z","end":"2026-02-25T10:07:52.728229Z","steps":["trace[1025858712] 'process raft request' (duration: 1.23743654s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.728556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.490508Z","time spent":"1.237861478s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:52.72866Z","caller":"traceutil/trace.go:171","msg":"trace[411633402] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.237667145s","start":"2026-02-25T10:07:51.490904Z","end":"2026-02-25T10:07:52.728571Z","steps":["trace[411633402] 'process raft request' (duration: 1.237266746s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.728739Z","caller":"traceutil/trace.go:171","msg":"trace[1786278369] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.237143594s","start":"2026-02-25T10:07:51.491564Z","end":"2026-02-25T10:07:52.728708Z","steps":["trace[1786278369] 'process raft request' (duration: 1.236917249s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.728897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.490876Z","time spent":"1.237909041s","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":727,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:52.728893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.491557Z","time spent":"1.237240876s","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":872,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:52.728892Z","caller":"traceutil/trace.go:171","msg":"trace[787865825] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.234044699s","start":"2026-02-25T10:07:51.494776Z","end":"2026-02-25T10:07:52.72882Z","steps":["trace[787865825] 'process raft request' (duration: 1.233842965s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.728941Z","caller":"traceutil/trace.go:171","msg":"trace[1301773663] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"1.229948172s","start":"2026-02-25T10:07:51.498957Z","end":"2026-02-25T10:07:52.728905Z","steps":["trace[1301773663] 'process raft request' (duration: 1.229751918s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:52.729111Z","caller":"traceutil/trace.go:171","msg":"trace[1635435603] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:687; }","duration":"1.182217925s","start":"2026-02-25T10:07:51.546874Z","end":"2026-02-25T10:07:52.729092Z","steps":["trace[1635435603] 'read index received' (duration: 7.724544ms)","trace[1635435603] 'applied index is now lower than readState.Index' (duration: 1.174491781s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:52.729136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.494749Z","time spent":"1.234263553s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3828,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:52.729127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.496522Z","time spent":"1.232503077s","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73573,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:52.729269Z","caller":"traceutil/trace.go:171","msg":"trace[863437216] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"1.230024794s","start":"2026-02-25T10:07:51.499164Z","end":"2026-02-25T10:07:52.729188Z","steps":["trace[863437216] 'process raft request' (duration: 1.229664327s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.729388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.182509121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kr6d4\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T10:07:52.729438Z","caller":"traceutil/trace.go:171","msg":"trace[1337504669] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kr6d4; range_end:; response_count:1; response_revision:671; }","duration":"1.182612693s","start":"2026-02-25T10:07:51.546811Z","end":"2026-02-25T10:07:52.729424Z","steps":["trace[1337504669] 'agreement among raft nodes before linearized reading' (duration: 1.182408609s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:52.729473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.546789Z","time spent":"1.182676544s","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16676,"request content":"key:\"/registry/pods/kube-system/cilium-kr6d4\" "} {"level":"warn","ts":"2026-02-25T10:07:52.729533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.499147Z","time spent":"1.230244408s","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:53.229945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722289031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:53.730827Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722289031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:07:54.138756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000215612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-25T10:07:54.139038Z","caller":"traceutil/trace.go:171","msg":"trace[27635160] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000561809s","start":"2026-02-25T10:07:52.138451Z","end":"2026-02-25T10:07:54.139013Z","steps":["trace[27635160] 'agreement among raft nodes before linearized reading' (duration: 2.000249512s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.139145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.138431Z","time spent":"2.000698721s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:07:54 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:07:54.176023Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.316334035s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:07:54.47593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.428071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:54.476255Z","caller":"traceutil/trace.go:171","msg":"trace[1575468639] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"2.913069539s","start":"2026-02-25T10:07:51.56313Z","end":"2026-02-25T10:07:54.4762Z","steps":["trace[1575468639] 'process raft request' (duration: 2.61323164s)","trace[1575468639] 'compare' (duration: 299.063023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:54.476498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:51.563101Z","time spent":"2.913260433s","remote":"127.0.0.1:45272","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":211522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:54.476684Z","caller":"traceutil/trace.go:171","msg":"trace[733823769] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.783452646s","start":"2026-02-25T10:07:52.693208Z","end":"2026-02-25T10:07:54.47666Z","steps":["trace[733823769] 'process raft request' (duration: 1.783372725s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:54.476734Z","caller":"traceutil/trace.go:171","msg":"trace[1576704314] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"2.452967228s","start":"2026-02-25T10:07:52.023712Z","end":"2026-02-25T10:07:54.476679Z","steps":["trace[1576704314] 'process raft request' (duration: 2.452354934s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:54.477015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.693182Z","time spent":"1.783779693s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4665,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:54.477098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.023677Z","time spent":"2.453377727s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:55.229347Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052998148s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:07:56.139444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999816053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:07:56.139548Z","caller":"traceutil/trace.go:171","msg":"trace[1265106823] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000008247s","start":"2026-02-25T10:07:54.139515Z","end":"2026-02-25T10:07:56.139523Z","steps":["trace[1265106823] 'agreement among raft nodes before linearized reading' (duration: 1.999876055s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.139616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:54.139484Z","time spent":"2.000118709s","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:07:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:07:56.386513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.156840599s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:56.386875Z","caller":"traceutil/trace.go:171","msg":"trace[1960280014] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"3.651598939s","start":"2026-02-25T10:07:52.735241Z","end":"2026-02-25T10:07:56.38684Z","steps":["trace[1960280014] 'process raft request' (duration: 2.494350651s)","trace[1960280014] 'compare' (duration: 1.156654875s)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:07:56.387077Z","caller":"traceutil/trace.go:171","msg":"trace[1597490455] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"3.642555889s","start":"2026-02-25T10:07:52.744492Z","end":"2026-02-25T10:07:56.387048Z","steps":["trace[1597490455] 'process raft request' (duration: 3.642274302s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.387072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.735212Z","time spent":"3.651780164s","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":881,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:56.387757Z","caller":"traceutil/trace.go:171","msg":"trace[1888110175] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"3.649638439s","start":"2026-02-25T10:07:52.737392Z","end":"2026-02-25T10:07:56.387031Z","steps":["trace[1888110175] 'process raft request' (duration: 3.64925804s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.387846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.744469Z","time spent":"3.642667992s","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:56.387544Z","caller":"traceutil/trace.go:171","msg":"trace[294251173] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"3.649089917s","start":"2026-02-25T10:07:52.737974Z","end":"2026-02-25T10:07:56.387063Z","steps":["trace[294251173] 'process raft request' (duration: 3.648733669s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:56.387921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.737382Z","time spent":"3.650446955s","remote":"127.0.0.1:45586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4952,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:56.388033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.737938Z","time spent":"3.650015246s","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3093,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:07:56.635471Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.405831015s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:07:57.385736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998727095s","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-25T10:07:57.385828Z","caller":"traceutil/trace.go:171","msg":"trace[1336403792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.998902349s","start":"2026-02-25T10:07:52.386904Z","end":"2026-02-25T10:07:57.385806Z","steps":["trace[1336403792] 'agreement among raft nodes before linearized reading' (duration: 4.998767936s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:57.385877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.386878Z","time spent":"4.99898856s","remote":"127.0.0.1:45412","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/25 10:07:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:07:57.437342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.99943641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:07:57.437453Z","caller":"traceutil/trace.go:171","msg":"trace[1788410903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999698255s","start":"2026-02-25T10:07:52.437732Z","end":"2026-02-25T10:07:57.43743Z","steps":["trace[1788410903] 'agreement among raft nodes before linearized reading' (duration: 4.999554212s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:57.437499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.437703Z","time spent":"4.999787448s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/25 10:07:57 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:07:58.146332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000072738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-25T10:07:58.146431Z","caller":"traceutil/trace.go:171","msg":"trace[1274515111] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000267292s","start":"2026-02-25T10:07:56.146144Z","end":"2026-02-25T10:07:58.146411Z","steps":["trace[1274515111] 'agreement among raft nodes before linearized reading' (duration: 2.000110669s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:58.146512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:56.146114Z","time spent":"2.000360815s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/25 10:07:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-25T10:07:59.736313Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.38093349s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-25T10:07:59.76214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.375311707s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T10:07:59.762525Z","caller":"traceutil/trace.go:171","msg":"trace[1555243864] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:694; }","duration":"7.03328719s","start":"2026-02-25T10:07:52.729175Z","end":"2026-02-25T10:07:59.762463Z","steps":["trace[1555243864] 'read index received' (duration: 1.447225787s)","trace[1555243864] 'applied index is now lower than readState.Index' (duration: 5.586060193s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:59.763712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.258546896s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T10:07:59.763832Z","caller":"traceutil/trace.go:171","msg":"trace[558634093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:678; }","duration":"7.258693409s","start":"2026-02-25T10:07:52.505112Z","end":"2026-02-25T10:07:59.763805Z","steps":["trace[558634093] 'agreement among raft nodes before linearized reading' (duration: 7.258384293s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.763903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.505106Z","time spent":"7.258780961s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T10:07:59.764318Z","caller":"traceutil/trace.go:171","msg":"trace[2026987064] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"5.268811266s","start":"2026-02-25T10:07:54.495482Z","end":"2026-02-25T10:07:59.764293Z","steps":["trace[2026987064] 'process raft request' (duration: 5.266855755s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.76446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:54.495449Z","time spent":"5.268933408s","remote":"127.0.0.1:45374","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1039,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:07:59.895906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.393888123s","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-25T10:07:59.896047Z","caller":"traceutil/trace.go:171","msg":"trace[602146733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:679; }","duration":"7.394076277s","start":"2026-02-25T10:07:52.501929Z","end":"2026-02-25T10:07:59.896005Z","steps":["trace[602146733] 'agreement among raft nodes before linearized reading' (duration: 7.261905657s)","trace[602146733] 'range keys from in-memory index tree' (duration: 131.847904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:59.896262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:52.501904Z","time spent":"7.394338753s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T10:07:59.896074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.70592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:07:59.896589Z","caller":"traceutil/trace.go:171","msg":"trace[1608793188] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"3.752923707s","start":"2026-02-25T10:07:56.143633Z","end":"2026-02-25T10:07:59.896557Z","steps":["trace[1608793188] 'process raft request' (duration: 3.620642195s)","trace[1608793188] 'compare' (duration: 131.492656ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:59.896698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:56.143596Z","time spent":"3.75306457s","remote":"127.0.0.1:45260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:07:59.896726Z","caller":"traceutil/trace.go:171","msg":"trace[949088958] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"126.720184ms","start":"2026-02-25T10:07:59.769978Z","end":"2026-02-25T10:07:59.896698Z","steps":["trace[949088958] 'process raft request' (duration: 126.660463ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.896789Z","caller":"traceutil/trace.go:171","msg":"trace[211159365] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"1.732244834s","start":"2026-02-25T10:07:58.164508Z","end":"2026-02-25T10:07:59.896753Z","steps":["trace[211159365] 'process raft request' (duration: 1.731967478s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.897043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:58.164484Z","time spent":"1.732455369s","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:07:59.896988Z","caller":"traceutil/trace.go:171","msg":"trace[1043343708] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"133.805394ms","start":"2026-02-25T10:07:59.763066Z","end":"2026-02-25T10:07:59.896871Z","steps":["trace[1043343708] 'read index received' (duration: 1.492291ms)","trace[1043343708] 'applied index is now lower than readState.Index' (duration: 132.297992ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:07:59.89716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.750482899s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:07:59.89718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.729761ms","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-25T10:07:59.89721Z","caller":"traceutil/trace.go:171","msg":"trace[1133169114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"1.750597382s","start":"2026-02-25T10:07:58.146598Z","end":"2026-02-25T10:07:59.897196Z","steps":["trace[1133169114] 'agreement among raft nodes before linearized reading' (duration: 1.750510191s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.897198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.587558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-kr6d4\" ","response":"range_response_count:1 size:16814"} {"level":"warn","ts":"2026-02-25T10:07:59.897195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.663307ms","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-25T10:07:59.897237Z","caller":"traceutil/trace.go:171","msg":"trace[520189213] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"456.851214ms","start":"2026-02-25T10:07:59.440368Z","end":"2026-02-25T10:07:59.897219Z","steps":["trace[520189213] 'agreement among raft nodes before linearized reading' (duration: 456.618748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.897249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:58.14657Z","time spent":"1.750672643s","remote":"127.0.0.1:45196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:07:59.897251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.455622454s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T10:07:59.897334Z","caller":"traceutil/trace.go:171","msg":"trace[401510018] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:682; }","duration":"2.455693656s","start":"2026-02-25T10:07:57.441586Z","end":"2026-02-25T10:07:59.89728Z","steps":["trace[401510018] 'agreement among raft nodes before linearized reading' (duration: 2.455569283s)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.897336Z","caller":"traceutil/trace.go:171","msg":"trace[1174663039] range","detail":"{range_begin:/registry/pods/kube-system/cilium-kr6d4; range_end:; response_count:1; response_revision:682; }","duration":"491.733681ms","start":"2026-02-25T10:07:59.405522Z","end":"2026-02-25T10:07:59.897256Z","steps":["trace[1174663039] 'agreement among raft nodes before linearized reading' (duration: 491.369162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.897347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:59.440337Z","time spent":"456.939286ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:07:59.897344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.068576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:07:59.897613Z","caller":"traceutil/trace.go:171","msg":"trace[1533442263] range","detail":"{range_begin:/registry/roles/cert-manager/cert-manager-startupapicheck:create-cert; range_end:; response_count:0; response_revision:682; }","duration":"121.438833ms","start":"2026-02-25T10:07:59.776149Z","end":"2026-02-25T10:07:59.897588Z","steps":["trace[1533442263] 'agreement among raft nodes before linearized reading' (duration: 121.071836ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:07:59.897349Z","caller":"traceutil/trace.go:171","msg":"trace[1343389382] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"508.78314ms","start":"2026-02-25T10:07:59.388486Z","end":"2026-02-25T10:07:59.897269Z","steps":["trace[1343389382] 'agreement among raft nodes before linearized reading' (duration: 508.465712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:07:59.89776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:59.388414Z","time spent":"509.317751ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:07:59.8974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:59.405488Z","time spent":"491.898043ms","remote":"127.0.0.1:45364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16838,"request content":"key:\"/registry/pods/kube-system/cilium-kr6d4\" "} {"level":"warn","ts":"2026-02-25T10:07:59.897379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:07:57.441558Z","time spent":"2.455809058s","remote":"127.0.0.1:45214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.67\" "} {"level":"warn","ts":"2026-02-25T10:08:02.368391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.141888ms","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-25T10:08:02.36867Z","caller":"traceutil/trace.go:171","msg":"trace[1566844190] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:718; }","duration":"500.470144ms","start":"2026-02-25T10:08:01.868167Z","end":"2026-02-25T10:08:02.368637Z","steps":["trace[1566844190] 'range keys from in-memory index tree' (duration: 500.095327ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.368434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.512306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T10:08:02.369026Z","caller":"traceutil/trace.go:171","msg":"trace[487553382] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"633.835199ms","start":"2026-02-25T10:08:01.735144Z","end":"2026-02-25T10:08:02.36898Z","steps":["trace[487553382] 'process raft request' (duration: 493.705811ms)","trace[487553382] 'compare' (duration: 139.398873ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:08:02.369158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.735121Z","time spent":"633.951741ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T10:08:02.370202Z","caller":"traceutil/trace.go:171","msg":"trace[322546187] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"634.627745ms","start":"2026-02-25T10:08:01.735554Z","end":"2026-02-25T10:08:02.370182Z","steps":["trace[322546187] 'process raft request' (duration: 634.403181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.370332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.735543Z","time spent":"634.710288ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:08:02.370558Z","caller":"traceutil/trace.go:171","msg":"trace[1499811728] transaction","detail":"{read_only:false; number_of_response:0; response_revision:720; }","duration":"633.472962ms","start":"2026-02-25T10:08:01.737031Z","end":"2026-02-25T10:08:02.370504Z","steps":["trace[1499811728] 'process raft request' (duration: 633.105544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:02.370642Z","caller":"traceutil/trace.go:171","msg":"trace[1134840910] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"450.582521ms","start":"2026-02-25T10:08:01.920041Z","end":"2026-02-25T10:08:02.370623Z","steps":["trace[1134840910] 'process raft request' (duration: 450.398637ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:02.370627Z","caller":"traceutil/trace.go:171","msg":"trace[215838113] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:743; }","duration":"468.354996ms","start":"2026-02-25T10:08:01.902244Z","end":"2026-02-25T10:08:02.370599Z","steps":["trace[215838113] 'read index received' (duration: 326.619995ms)","trace[215838113] 'applied index is now lower than readState.Index' (duration: 141.733561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:08:02.370718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.920021Z","time spent":"450.664963ms","remote":"127.0.0.1:45220","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:08:02.370733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.737013Z","time spent":"633.638795ms","remote":"127.0.0.1:45674","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T10:08:02.370945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.673103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T10:08:02.371007Z","caller":"traceutil/trace.go:171","msg":"trace[2085513514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"468.795906ms","start":"2026-02-25T10:08:01.902192Z","end":"2026-02-25T10:08:02.370988Z","steps":["trace[2085513514] 'agreement among raft nodes before linearized reading' (duration: 468.577642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.371007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.017954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T10:08:02.371021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.95995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:08:02.37106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.90217Z","time spent":"468.877008ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T10:08:02.371087Z","caller":"traceutil/trace.go:171","msg":"trace[1822288537] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:721; }","duration":"253.079343ms","start":"2026-02-25T10:08:02.117986Z","end":"2026-02-25T10:08:02.371065Z","steps":["trace[1822288537] 'agreement among raft nodes before linearized reading' (duration: 252.974851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.371159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.360939ms","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-25T10:08:02.371203Z","caller":"traceutil/trace.go:171","msg":"trace[1310123658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:721; }","duration":"452.454471ms","start":"2026-02-25T10:08:01.918741Z","end":"2026-02-25T10:08:02.371196Z","steps":["trace[1310123658] 'agreement among raft nodes before linearized reading' (duration: 452.278618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.371232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.918717Z","time spent":"452.502692ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:08:02.371275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.495673ms","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-25T10:08:02.371068Z","caller":"traceutil/trace.go:171","msg":"trace[17894216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"454.100125ms","start":"2026-02-25T10:08:01.916947Z","end":"2026-02-25T10:08:02.371047Z","steps":["trace[17894216] 'agreement among raft nodes before linearized reading' (duration: 453.896891ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:02.371329Z","caller":"traceutil/trace.go:171","msg":"trace[2112066272] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"453.570714ms","start":"2026-02-25T10:08:01.917743Z","end":"2026-02-25T10:08:02.371314Z","steps":["trace[2112066272] 'agreement among raft nodes before linearized reading' (duration: 453.355229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:02.371389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.917733Z","time spent":"453.643826ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:08:02.371388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.692842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:08:02.37134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:01.916936Z","time spent":"454.394561ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:08:02.371445Z","caller":"traceutil/trace.go:171","msg":"trace[328468118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:721; }","duration":"232.779523ms","start":"2026-02-25T10:08:02.138652Z","end":"2026-02-25T10:08:02.371431Z","steps":["trace[328468118] 'agreement among raft nodes before linearized reading' (duration: 232.690651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:17.971397Z","caller":"traceutil/trace.go:171","msg":"trace[1921257519] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"134.780075ms","start":"2026-02-25T10:08:17.836586Z","end":"2026-02-25T10:08:17.971366Z","steps":["trace[1921257519] 'process raft request' (duration: 134.595881ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:20.994782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722289552,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T10:08:21.225724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.68905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T10:08:21.225781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087642507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T10:08:21.225829Z","caller":"traceutil/trace.go:171","msg":"trace[1936391818] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:949; }","duration":"926.863904ms","start":"2026-02-25T10:08:20.298944Z","end":"2026-02-25T10:08:21.225807Z","steps":["trace[1936391818] 'count revisions from in-memory index tree' (duration: 926.538658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:21.225871Z","caller":"traceutil/trace.go:171","msg":"trace[373738154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"1.087841772s","start":"2026-02-25T10:08:20.138008Z","end":"2026-02-25T10:08:21.22585Z","steps":["trace[373738154] 'range keys from in-memory index tree' (duration: 1.087495945s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.225884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.298922Z","time spent":"926.949706ms","remote":"127.0.0.1:45458","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-25T10:08:21.225912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.137981Z","time spent":"1.087918794s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T10:08:21.254687Z","caller":"traceutil/trace.go:171","msg":"trace[331811616] linearizableReadLoop","detail":"{readStateIndex:983; appliedIndex:982; }","duration":"760.772198ms","start":"2026-02-25T10:08:20.493884Z","end":"2026-02-25T10:08:21.254656Z","steps":["trace[331811616] 'read index received' (duration: 760.563523ms)","trace[331811616] 'applied index is now lower than readState.Index' (duration: 207.325µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:08:21.254903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.639313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T10:08:21.25487Z","caller":"traceutil/trace.go:171","msg":"trace[573833591] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"872.189061ms","start":"2026-02-25T10:08:20.382619Z","end":"2026-02-25T10:08:21.254808Z","steps":["trace[573833591] 'process raft request' (duration: 871.868343ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:21.254956Z","caller":"traceutil/trace.go:171","msg":"trace[1267710412] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:950; }","duration":"730.737755ms","start":"2026-02-25T10:08:20.524201Z","end":"2026-02-25T10:08:21.254939Z","steps":["trace[1267710412] 'agreement among raft nodes before linearized reading' (duration: 730.582451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.254979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.524171Z","time spent":"730.801695ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:08:21.254983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.080005ms","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-25T10:08:21.25509Z","caller":"traceutil/trace.go:171","msg":"trace[2102378612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:950; }","duration":"761.244508ms","start":"2026-02-25T10:08:20.493818Z","end":"2026-02-25T10:08:21.255063Z","steps":["trace[2102378612] 'agreement among raft nodes before linearized reading' (duration: 760.959082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.255122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.619168ms","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-25T10:08:21.255225Z","caller":"traceutil/trace.go:171","msg":"trace[667403209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:950; }","duration":"726.750091ms","start":"2026-02-25T10:08:20.528457Z","end":"2026-02-25T10:08:21.255207Z","steps":["trace[667403209] 'agreement among raft nodes before linearized reading' (duration: 726.510666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.255269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.528384Z","time spent":"726.875473ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:08:21.255261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.435918ms","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-25T10:08:21.255142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.493786Z","time spent":"761.347111ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:08:21.25539Z","caller":"traceutil/trace.go:171","msg":"trace[2054221650] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:950; }","duration":"730.628632ms","start":"2026-02-25T10:08:20.524733Z","end":"2026-02-25T10:08:21.255362Z","steps":["trace[2054221650] 'agreement among raft nodes before linearized reading' (duration: 730.285805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.255458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.524704Z","time spent":"730.733364ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T10:08:21.255499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.382578Z","time spent":"872.401435ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:08:21.255736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.581749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T10:08:21.255828Z","caller":"traceutil/trace.go:171","msg":"trace[1154497638] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:950; }","duration":"592.675781ms","start":"2026-02-25T10:08:20.663127Z","end":"2026-02-25T10:08:21.255803Z","steps":["trace[1154497638] 'agreement among raft nodes before linearized reading' (duration: 591.759632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:08:21.255881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:08:20.663048Z","time spent":"592.816784ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":79,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-02-25T10:08:41.096859Z","caller":"traceutil/trace.go:171","msg":"trace[516964524] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"179.287994ms","start":"2026-02-25T10:08:40.917535Z","end":"2026-02-25T10:08:41.096823Z","steps":["trace[516964524] 'process raft request' (duration: 179.063989ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:08:53.076854Z","caller":"traceutil/trace.go:171","msg":"trace[636790082] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1244; }","duration":"119.576217ms","start":"2026-02-25T10:08:52.95724Z","end":"2026-02-25T10:08:53.076817Z","steps":["trace[636790082] 'process raft request' (duration: 109.519365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:23.114874Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403968461722290887,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T10:09:23.167459Z","caller":"traceutil/trace.go:171","msg":"trace[1509936297] transaction","detail":"{read_only:false; response_revision:1575; number_of_response:1; }","duration":"591.786049ms","start":"2026-02-25T10:09:22.57564Z","end":"2026-02-25T10:09:23.167426Z","steps":["trace[1509936297] 'process raft request' (duration: 591.571864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:23.167689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:22.575618Z","time spent":"591.957082ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T10:09:23.489872Z","caller":"traceutil/trace.go:171","msg":"trace[338426979] transaction","detail":"{read_only:false; response_revision:1576; number_of_response:1; }","duration":"906.505713ms","start":"2026-02-25T10:09:22.583332Z","end":"2026-02-25T10:09:23.489838Z","steps":["trace[338426979] 'process raft request' (duration: 872.64972ms)","trace[338426979] 'compare' (duration: 33.612698ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:09:23.490086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:22.583317Z","time spent":"906.638377ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T10:09:24.491781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001983084s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T10:09:24.491919Z","caller":"traceutil/trace.go:171","msg":"trace[693109777] linearizableReadLoop","detail":"{readStateIndex:1650; appliedIndex:1647; }","duration":"1.877154927s","start":"2026-02-25T10:09:22.614737Z","end":"2026-02-25T10:09:24.491892Z","steps":["trace[693109777] 'read index received' (duration: 552.44566ms)","trace[693109777] 'applied index is now lower than readState.Index' (duration: 1.324706717s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T10:09:24.492252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.877485064s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:3875"} {"level":"info","ts":"2026-02-25T10:09:24.49233Z","caller":"traceutil/trace.go:171","msg":"trace[1071278098] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1576; }","duration":"1.877599507s","start":"2026-02-25T10:09:22.614713Z","end":"2026-02-25T10:09:24.492294Z","steps":["trace[1071278098] 'agreement among raft nodes before linearized reading' (duration: 1.877341292s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.492344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.353949213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T10:09:24.492377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:22.614693Z","time spent":"1.877672278s","remote":"127.0.0.1:52136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":3899,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T10:09:24.492428Z","caller":"traceutil/trace.go:171","msg":"trace[880179502] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1576; }","duration":"1.354105386s","start":"2026-02-25T10:09:23.138301Z","end":"2026-02-25T10:09:24.492406Z","steps":["trace[880179502] 'agreement among raft nodes before linearized reading' (duration: 1.353968253s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.492474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:23.138277Z","time spent":"1.354189788s","remote":"127.0.0.1:45190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T10:09:24.492521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.908832ms","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-25T10:09:24.492582Z","caller":"traceutil/trace.go:171","msg":"trace[1175737476] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1576; }","duration":"545.987753ms","start":"2026-02-25T10:09:23.946576Z","end":"2026-02-25T10:09:24.492564Z","steps":["trace[1175737476] 'agreement among raft nodes before linearized reading' (duration: 545.82737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.492623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:23.946567Z","time spent":"546.045274ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T10:09:24.492697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.825303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T10:09:24.492729Z","caller":"traceutil/trace.go:171","msg":"trace[1343487240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1576; }","duration":"576.908195ms","start":"2026-02-25T10:09:23.915814Z","end":"2026-02-25T10:09:24.492722Z","steps":["trace[1343487240] 'agreement among raft nodes before linearized reading' (duration: 576.828373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.492755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:23.915787Z","time spent":"576.962305ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T10:09:24.492722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.530135ms","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-25T10:09:24.492814Z","caller":"traceutil/trace.go:171","msg":"trace[1015541058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1576; }","duration":"546.71446ms","start":"2026-02-25T10:09:23.946085Z","end":"2026-02-25T10:09:24.4928Z","steps":["trace[1015541058] 'agreement among raft nodes before linearized reading' (duration: 546.407863ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.493024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.260839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T10:09:24.493048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:23.94606Z","time spent":"546.974096ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T10:09:24.493075Z","caller":"traceutil/trace.go:171","msg":"trace[1298615227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1576; }","duration":"546.324261ms","start":"2026-02-25T10:09:23.946736Z","end":"2026-02-25T10:09:24.49306Z","steps":["trace[1298615227] 'agreement among raft nodes before linearized reading' (duration: 546.025434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T10:09:24.493126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T10:09:23.946729Z","time spent":"546.375982ms","remote":"127.0.0.1:45412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T10:10:34.566733Z","caller":"traceutil/trace.go:171","msg":"trace[2035887105] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"114.237484ms","start":"2026-02-25T10:10:34.452461Z","end":"2026-02-25T10:10:34.566698Z","steps":["trace[2035887105] 'process raft request' (duration: 89.160544ms)","trace[2035887105] 'compare' (duration: 24.894786ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T10:11:59.528221Z","caller":"traceutil/trace.go:171","msg":"trace[58681802] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"116.670384ms","start":"2026-02-25T10:11:59.411522Z","end":"2026-02-25T10:11:59.528192Z","steps":["trace[58681802] 'process raft request' (duration: 116.550761ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:11:59.528331Z","caller":"traceutil/trace.go:171","msg":"trace[894794961] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"116.96005ms","start":"2026-02-25T10:11:59.411318Z","end":"2026-02-25T10:11:59.528278Z","steps":["trace[894794961] 'process raft request' (duration: 113.963487ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T10:17:03.961258Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2136} {"level":"info","ts":"2026-02-25T10:17:04.07859Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2136,"took":"109.175124ms","hash":4031492974,"current-db-size-bytes":14229504,"current-db-size":"14 MB","current-db-size-in-use-bytes":4321280,"current-db-size-in-use":"4.3 MB"} {"level":"info","ts":"2026-02-25T10:17:04.078694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4031492974,"revision":2136,"compact-revision":-1} {"level":"info","ts":"2026-02-25T10:22:03.971023Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3067} {"level":"info","ts":"2026-02-25T10:22:03.992152Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3067,"took":"20.208686ms","hash":1388700127,"current-db-size-bytes":14229504,"current-db-size":"14 MB","current-db-size-in-use-bytes":4714496,"current-db-size-in-use":"4.7 MB"} {"level":"info","ts":"2026-02-25T10:22:03.992234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1388700127,"revision":3067,"compact-revision":2136}