{"level":"warn","ts":"2026-02-20T18:27:18.262789Z","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-20T18:27:18.262893Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-20T18:27:18.263001Z","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-20T18:27:18.263015Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-20T18:27:18.263036Z","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-20T18:27:18.263999Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-02-20T18:27:18.26411Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-20T18:27:18.269204Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.809178ms"} {"level":"info","ts":"2026-02-20T18:27:18.276963Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-02-20T18:27:18.277037Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:27:18.27706Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-02-20T18:27:18.277073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:27:18.277081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-02-20T18:27:18.277178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-02-20T18:27:18.283127Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:27:18.2845Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:27:18.285828Z","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-20T18:27:18.288113Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:27:18.288353Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:27:18.288724Z","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-20T18:27:18.290671Z","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-20T18:27:18.29079Z","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-20T18:27:18.294583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-02-20T18:27:18.294767Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-02-20T18:27:18.296121Z","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-20T18:27:18.296231Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-20T18:27:18.296344Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-02-20T18:27:18.297872Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:27:18.297908Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:27:18.678183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:27:18.678253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:27:18.678273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-02-20T18:27:18.678311Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:27:18.678323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-20T18:27:18.678334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-02-20T18:27:18.678347Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-02-20T18:27:18.679357Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:27:18.679442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:27:18.679557Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:27:18.681831Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-02-20T18:27:18.682163Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:27:18.682767Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:27:18.682823Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:27:18.685246Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:27:18.686678Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:27:18.686785Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:27:18.686831Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:27:32.824542Z","caller":"traceutil/trace.go:171","msg":"trace[647227502] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"132.050197ms","start":"2026-02-20T18:27:32.692468Z","end":"2026-02-20T18:27:32.824518Z","steps":["trace[647227502] 'process raft request' (duration: 118.129932ms)","trace[647227502] 'compare' (duration: 13.823863ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:33.081211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.205089ms","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-20T18:27:33.081362Z","caller":"traceutil/trace.go:171","msg":"trace[1770795531] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:410; }","duration":"145.414412ms","start":"2026-02-20T18:27:32.935918Z","end":"2026-02-20T18:27:33.081333Z","steps":["trace[1770795531] 'range keys from in-memory index tree' (duration: 145.082656ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:35.288907Z","caller":"traceutil/trace.go:171","msg":"trace[1433538425] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"158.402552ms","start":"2026-02-20T18:27:35.130475Z","end":"2026-02-20T18:27:35.288878Z","steps":["trace[1433538425] 'process raft request' (duration: 158.25396ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:36.101719Z","caller":"traceutil/trace.go:171","msg":"trace[573040461] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:459; }","duration":"242.753023ms","start":"2026-02-20T18:27:35.858941Z","end":"2026-02-20T18:27:36.101694Z","steps":["trace[573040461] 'read index received' (duration: 242.641681ms)","trace[573040461] 'applied index is now lower than readState.Index' (duration: 110.402µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:36.1018Z","caller":"traceutil/trace.go:171","msg":"trace[1936434112] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"248.133441ms","start":"2026-02-20T18:27:35.853634Z","end":"2026-02-20T18:27:36.101768Z","steps":["trace[1936434112] 'process raft request' (duration: 247.928618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:36.101855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.895776ms","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-20T18:27:36.101889Z","caller":"traceutil/trace.go:171","msg":"trace[84841554] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:447; }","duration":"242.985267ms","start":"2026-02-20T18:27:35.858894Z","end":"2026-02-20T18:27:36.101879Z","steps":["trace[84841554] 'agreement among raft nodes before linearized reading' (duration: 242.905726ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:37.300827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.835489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:27:37.301043Z","caller":"traceutil/trace.go:171","msg":"trace[103353788] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"1.195836604s","start":"2026-02-20T18:27:36.105179Z","end":"2026-02-20T18:27:37.301015Z","steps":["trace[103353788] 'process raft request' (duration: 398.057098ms)","trace[103353788] 'compare' (duration: 796.665085ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:37.301361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.10516Z","time spent":"1.196035937s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:37.301376Z","caller":"traceutil/trace.go:171","msg":"trace[426102657] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:460; }","duration":"1.009070039s","start":"2026-02-20T18:27:36.292258Z","end":"2026-02-20T18:27:37.301328Z","steps":["trace[426102657] 'read index received' (duration: 210.901416ms)","trace[426102657] 'applied index is now lower than readState.Index' (duration: 798.164993ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:37.825865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.839098ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-20T18:27:37.825915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533638673s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:27:37.825977Z","caller":"traceutil/trace.go:171","msg":"trace[1071717861] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"524.513032ms","start":"2026-02-20T18:27:37.301445Z","end":"2026-02-20T18:27:37.825958Z","steps":["trace[1071717861] 'read index received' (duration: 55.961µs)","trace[1071717861] 'applied index is now lower than readState.Index' (duration: 524.455721ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:37.825985Z","caller":"traceutil/trace.go:171","msg":"trace[679212728] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:448; }","duration":"1.533722964s","start":"2026-02-20T18:27:36.292239Z","end":"2026-02-20T18:27:37.825962Z","steps":["trace[679212728] 'agreement among raft nodes before linearized reading' (duration: 1.009251253s)","trace[679212728] 'range keys from in-memory index tree' (duration: 524.326428ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:37.826052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.140287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:27:37.826054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.454367ms","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-20T18:27:37.826102Z","caller":"traceutil/trace.go:171","msg":"trace[1140432621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"900.234069ms","start":"2026-02-20T18:27:36.925853Z","end":"2026-02-20T18:27:37.826087Z","steps":["trace[1140432621] 'agreement among raft nodes before linearized reading' (duration: 900.156828ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:37.826134Z","caller":"traceutil/trace.go:171","msg":"trace[2087677824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"532.552429ms","start":"2026-02-20T18:27:37.293543Z","end":"2026-02-20T18:27:37.826096Z","steps":["trace[2087677824] 'agreement among raft nodes before linearized reading' (duration: 532.435317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:37.826157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.925832Z","time spent":"900.310841ms","remote":"127.0.0.1:60932","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-20T18:27:37.82614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:36.292174Z","time spent":"1.533945179s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:27:37.826194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:37.293516Z","time spent":"532.662512ms","remote":"127.0.0.1:32952","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":"info","ts":"2026-02-20T18:27:37.959085Z","caller":"traceutil/trace.go:171","msg":"trace[1175631014] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"121.545825ms","start":"2026-02-20T18:27:37.83751Z","end":"2026-02-20T18:27:37.959056Z","steps":["trace[1175631014] 'process raft request' (duration: 121.457123ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:37.959114Z","caller":"traceutil/trace.go:171","msg":"trace[395039700] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"126.335742ms","start":"2026-02-20T18:27:37.83274Z","end":"2026-02-20T18:27:37.959076Z","steps":["trace[395039700] 'process raft request' (duration: 124.891195ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:38.135771Z","caller":"traceutil/trace.go:171","msg":"trace[1271953518] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"162.704271ms","start":"2026-02-20T18:27:37.973036Z","end":"2026-02-20T18:27:38.13574Z","steps":["trace[1271953518] 'process raft request' (duration: 162.433956ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:40.12078Z","caller":"traceutil/trace.go:171","msg":"trace[1959443607] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"153.573794ms","start":"2026-02-20T18:27:39.967173Z","end":"2026-02-20T18:27:40.120747Z","steps":["trace[1959443607] 'process raft request' (duration: 117.381688ms)","trace[1959443607] 'compare' (duration: 35.923151ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:44.023471Z","caller":"traceutil/trace.go:171","msg":"trace[2078890770] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"131.170572ms","start":"2026-02-20T18:27:43.892278Z","end":"2026-02-20T18:27:44.023449Z","steps":["trace[2078890770] 'read index received' (duration: 130.970447ms)","trace[2078890770] 'applied index is now lower than readState.Index' (duration: 199.275µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:27:44.023672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.379635ms","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-20T18:27:44.023712Z","caller":"traceutil/trace.go:171","msg":"trace[716389929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"131.474327ms","start":"2026-02-20T18:27:43.892226Z","end":"2026-02-20T18:27:44.023701Z","steps":["trace[716389929] 'agreement among raft nodes before linearized reading' (duration: 131.312474ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:44.023861Z","caller":"traceutil/trace.go:171","msg":"trace[889029626] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"667.314738ms","start":"2026-02-20T18:27:43.356536Z","end":"2026-02-20T18:27:44.023851Z","steps":["trace[889029626] 'process raft request' (duration: 666.762787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:44.023946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:43.35651Z","time spent":"667.378009ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:27:50.87316Z","caller":"traceutil/trace.go:171","msg":"trace[1204758151] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"106.150341ms","start":"2026-02-20T18:27:50.766985Z","end":"2026-02-20T18:27:50.873136Z","steps":["trace[1204758151] 'process raft request' (duration: 106.017039ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:54.489941Z","caller":"traceutil/trace.go:171","msg":"trace[1577335469] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"355.042337ms","start":"2026-02-20T18:27:54.134861Z","end":"2026-02-20T18:27:54.489903Z","steps":["trace[1577335469] 'read index received' (duration: 354.936875ms)","trace[1577335469] 'applied index is now lower than readState.Index' (duration: 103.942µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:27:54.489985Z","caller":"traceutil/trace.go:171","msg":"trace[507591828] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"398.237851ms","start":"2026-02-20T18:27:54.091706Z","end":"2026-02-20T18:27:54.489943Z","steps":["trace[507591828] 'process raft request' (duration: 398.003617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.490162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.273701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-20T18:27:54.490181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:54.091659Z","time spent":"398.416794ms","remote":"127.0.0.1:32952","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-20T18:27:54.490201Z","caller":"traceutil/trace.go:171","msg":"trace[1842884937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:487; }","duration":"355.363362ms","start":"2026-02-20T18:27:54.134826Z","end":"2026-02-20T18:27:54.490189Z","steps":["trace[1842884937] 'agreement among raft nodes before linearized reading' (duration: 355.25139ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.490244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:54.134814Z","time spent":"355.421114ms","remote":"127.0.0.1:32952","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-20T18:27:54.494451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.524106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-20T18:27:54.49459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.684369ms","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-20T18:27:54.49467Z","caller":"traceutil/trace.go:171","msg":"trace[1207313978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"244.79562ms","start":"2026-02-20T18:27:54.249854Z","end":"2026-02-20T18:27:54.49465Z","steps":["trace[1207313978] 'agreement among raft nodes before linearized reading' (duration: 244.622387ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:27:54.494583Z","caller":"traceutil/trace.go:171","msg":"trace[1611454551] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"305.685859ms","start":"2026-02-20T18:27:54.188878Z","end":"2026-02-20T18:27:54.494564Z","steps":["trace[1611454551] 'agreement among raft nodes before linearized reading' (duration: 305.495065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:27:54.494814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:27:54.188866Z","time spent":"305.934964ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:28:00.805584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.49244ms","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-20T18:28:00.805748Z","caller":"traceutil/trace.go:171","msg":"trace[911166684] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:567; }","duration":"674.672433ms","start":"2026-02-20T18:28:00.131056Z","end":"2026-02-20T18:28:00.805728Z","steps":["trace[911166684] 'range keys from in-memory index tree' (duration: 674.473139ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:00.807993Z","caller":"traceutil/trace.go:171","msg":"trace[66899926] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"773.360516ms","start":"2026-02-20T18:28:00.034598Z","end":"2026-02-20T18:28:00.807958Z","steps":["trace[66899926] 'process raft request' (duration: 773.210324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.808134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.034574Z","time spent":"773.503839ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:00.82242Z","caller":"traceutil/trace.go:171","msg":"trace[966738960] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"390.57262ms","start":"2026-02-20T18:28:00.431816Z","end":"2026-02-20T18:28:00.822389Z","steps":["trace[966738960] 'read index received' (duration: 376.037772ms)","trace[966738960] 'applied index is now lower than readState.Index' (duration: 14.533418ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:00.822605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.783814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:00.822656Z","caller":"traceutil/trace.go:171","msg":"trace[1277537866] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:569; }","duration":"390.897106ms","start":"2026-02-20T18:28:00.431746Z","end":"2026-02-20T18:28:00.822643Z","steps":["trace[1277537866] 'agreement among raft nodes before linearized reading' (duration: 390.771934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.822692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.431728Z","time spent":"390.956407ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-02-20T18:28:00.82297Z","caller":"traceutil/trace.go:171","msg":"trace[297715823] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"588.476829ms","start":"2026-02-20T18:28:00.233994Z","end":"2026-02-20T18:28:00.82247Z","steps":["trace[297715823] 'process raft request' (duration: 588.247705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.823046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.233963Z","time spent":"589.038789ms","remote":"127.0.0.1:60976","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-20T18:28:00.827894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.383613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:00.827991Z","caller":"traceutil/trace.go:171","msg":"trace[1535327293] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:570; }","duration":"195.551045ms","start":"2026-02-20T18:28:00.632418Z","end":"2026-02-20T18:28:00.827969Z","steps":["trace[1535327293] 'agreement among raft nodes before linearized reading' (duration: 195.33156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.828001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.816482ms","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-20T18:28:00.827973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.430268ms","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-20T18:28:00.82793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.417986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:28:00.828052Z","caller":"traceutil/trace.go:171","msg":"trace[534150453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"305.895404ms","start":"2026-02-20T18:28:00.522142Z","end":"2026-02-20T18:28:00.828038Z","steps":["trace[534150453] 'agreement among raft nodes before linearized reading' (duration: 305.800402ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:00.828076Z","caller":"traceutil/trace.go:171","msg":"trace[2089136949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:570; }","duration":"187.590848ms","start":"2026-02-20T18:28:00.640473Z","end":"2026-02-20T18:28:00.828064Z","steps":["trace[2089136949] 'agreement among raft nodes before linearized reading' (duration: 187.385835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.828079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.52213Z","time spent":"305.942935ms","remote":"127.0.0.1:32952","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-20T18:28:00.828048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.821548ms","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-20T18:28:00.82814Z","caller":"traceutil/trace.go:171","msg":"trace[1696134571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"314.941709ms","start":"2026-02-20T18:28:00.513174Z","end":"2026-02-20T18:28:00.828116Z","steps":["trace[1696134571] 'agreement among raft nodes before linearized reading' (duration: 314.733896ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:00.828068Z","caller":"traceutil/trace.go:171","msg":"trace[1538073584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"309.55626ms","start":"2026-02-20T18:28:00.518492Z","end":"2026-02-20T18:28:00.828049Z","steps":["trace[1538073584] 'agreement among raft nodes before linearized reading' (duration: 309.303076ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.828222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.513162Z","time spent":"315.053121ms","remote":"127.0.0.1:32952","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-20T18:28:00.828226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.636479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:00.828256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.518482Z","time spent":"309.761554ms","remote":"127.0.0.1:32952","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-20T18:28:00.828276Z","caller":"traceutil/trace.go:171","msg":"trace[1563359732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"336.711411ms","start":"2026-02-20T18:28:00.491534Z","end":"2026-02-20T18:28:00.828246Z","steps":["trace[1563359732] 'agreement among raft nodes before linearized reading' (duration: 336.199791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.828299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.491504Z","time spent":"336.790482ms","remote":"127.0.0.1:60932","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-20T18:28:00.829782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.573158ms","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-20T18:28:00.829831Z","caller":"traceutil/trace.go:171","msg":"trace[1295555441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:570; }","duration":"311.68361ms","start":"2026-02-20T18:28:00.518139Z","end":"2026-02-20T18:28:00.829822Z","steps":["trace[1295555441] 'agreement among raft nodes before linearized reading' (duration: 311.594178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:00.829852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:00.518104Z","time spent":"311.744071ms","remote":"127.0.0.1:32952","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-20T18:28:07.365812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265754643,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:28:07.657155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.779176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-c5qn9\" ","response":"range_response_count:1 size:16652"} {"level":"warn","ts":"2026-02-20T18:28:07.657243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.032044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:28:07.657271Z","caller":"traceutil/trace.go:171","msg":"trace[652032719] range","detail":"{range_begin:/registry/pods/kube-system/cilium-c5qn9; range_end:; response_count:1; response_revision:601; }","duration":"875.926299ms","start":"2026-02-20T18:28:06.781326Z","end":"2026-02-20T18:28:07.657252Z","steps":["trace[652032719] 'range keys from in-memory index tree' (duration: 875.607933ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:07.657288Z","caller":"traceutil/trace.go:171","msg":"trace[1148746678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:601; }","duration":"800.161047ms","start":"2026-02-20T18:28:06.857116Z","end":"2026-02-20T18:28:07.657277Z","steps":["trace[1148746678] 'range keys from in-memory index tree' (duration: 799.892992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:07.657311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.781303Z","time spent":"875.99892ms","remote":"127.0.0.1:32864","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-c5qn9\" "} {"level":"warn","ts":"2026-02-20T18:28:07.657336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.857096Z","time spent":"800.213898ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:07.78693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.618543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:07.787199Z","caller":"traceutil/trace.go:171","msg":"trace[1151781281] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"921.983625ms","start":"2026-02-20T18:28:06.865185Z","end":"2026-02-20T18:28:07.787168Z","steps":["trace[1151781281] 'read index received' (duration: 685.050746ms)","trace[1151781281] 'applied index is now lower than readState.Index' (duration: 236.930159ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:07.787259Z","caller":"traceutil/trace.go:171","msg":"trace[1251033523] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"124.56595ms","start":"2026-02-20T18:28:07.662674Z","end":"2026-02-20T18:28:07.78724Z","steps":["trace[1251033523] 'process raft request' (duration: 124.421547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:07.78774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.453673ms","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-20T18:28:07.78797Z","caller":"traceutil/trace.go:171","msg":"trace[1225232254] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:602; }","duration":"922.645058ms","start":"2026-02-20T18:28:06.865159Z","end":"2026-02-20T18:28:07.787804Z","steps":["trace[1225232254] 'agreement among raft nodes before linearized reading' (duration: 922.17653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:07.78804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.865145Z","time spent":"922.877153ms","remote":"127.0.0.1:32952","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-20T18:28:08.028392Z","caller":"traceutil/trace.go:171","msg":"trace[1580730109] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"241.060808ms","start":"2026-02-20T18:28:07.787302Z","end":"2026-02-20T18:28:08.028363Z","steps":["trace[1580730109] 'read index received' (duration: 222.014716ms)","trace[1580730109] 'applied index is now lower than readState.Index' (duration: 19.044962ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:08.028509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.054999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:08.028572Z","caller":"traceutil/trace.go:171","msg":"trace[1023810803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"538.175921ms","start":"2026-02-20T18:28:07.490379Z","end":"2026-02-20T18:28:08.028555Z","steps":["trace[1023810803] 'agreement among raft nodes before linearized reading' (duration: 538.043659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.028618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:07.490318Z","time spent":"538.291713ms","remote":"127.0.0.1:60932","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-20T18:28:08.028691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161312441s","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-20T18:28:08.028731Z","caller":"traceutil/trace.go:171","msg":"trace[275878945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.161354621s","start":"2026-02-20T18:28:06.867366Z","end":"2026-02-20T18:28:08.028721Z","steps":["trace[275878945] 'agreement among raft nodes before linearized reading' (duration: 1.161242798s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.028755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.867337Z","time spent":"1.161413523s","remote":"127.0.0.1:32952","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-20T18:28:08.028787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161364382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:28:08.028876Z","caller":"traceutil/trace.go:171","msg":"trace[940063783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"1.161475814s","start":"2026-02-20T18:28:06.867386Z","end":"2026-02-20T18:28:08.028862Z","steps":["trace[940063783] 'agreement among raft nodes before linearized reading' (duration: 1.161189308s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.028958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.86738Z","time spent":"1.161534665s","remote":"127.0.0.1:32952","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-20T18:28:08.028981Z","caller":"traceutil/trace.go:171","msg":"trace[598264541] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"356.417213ms","start":"2026-02-20T18:28:07.672555Z","end":"2026-02-20T18:28:08.028972Z","steps":["trace[598264541] 'process raft request' (duration: 336.789109ms)","trace[598264541] 'compare' (duration: 18.835328ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:08.029038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:07.672531Z","time spent":"356.470274ms","remote":"127.0.0.1:32864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:08.029386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16236383s","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-20T18:28:08.029434Z","caller":"traceutil/trace.go:171","msg":"trace[449231878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:603; }","duration":"1.162455292s","start":"2026-02-20T18:28:06.866968Z","end":"2026-02-20T18:28:08.029423Z","steps":["trace[449231878] 'agreement among raft nodes before linearized reading' (duration: 1.161516004s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.029529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:06.866949Z","time spent":"1.162569814s","remote":"127.0.0.1:32952","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-20T18:28:08.334259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.201103ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:08.334465Z","caller":"traceutil/trace.go:171","msg":"trace[1844436119] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"542.811401ms","start":"2026-02-20T18:28:07.791598Z","end":"2026-02-20T18:28:08.334409Z","steps":["trace[1844436119] 'process raft request' (duration: 427.360413ms)","trace[1844436119] 'compare' (duration: 115.082641ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:08.334566Z","caller":"traceutil/trace.go:171","msg":"trace[1994962017] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"299.762915ms","start":"2026-02-20T18:28:08.034786Z","end":"2026-02-20T18:28:08.334549Z","steps":["trace[1994962017] 'process raft request' (duration: 299.726845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.33467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:07.791585Z","time spent":"542.990214ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:08.334729Z","caller":"traceutil/trace.go:171","msg":"trace[628176536] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"300.614081ms","start":"2026-02-20T18:28:08.034106Z","end":"2026-02-20T18:28:08.33472Z","steps":["trace[628176536] 'process raft request' (duration: 300.310946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.334812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.034081Z","time spent":"300.670302ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:08.335037Z","caller":"traceutil/trace.go:171","msg":"trace[2076258665] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"304.904272ms","start":"2026-02-20T18:28:08.030115Z","end":"2026-02-20T18:28:08.33502Z","steps":["trace[2076258665] 'read index received' (duration: 188.869453ms)","trace[2076258665] 'applied index is now lower than readState.Index' (duration: 116.032749ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:08.335133Z","caller":"traceutil/trace.go:171","msg":"trace[1267408395] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"300.665661ms","start":"2026-02-20T18:28:08.034416Z","end":"2026-02-20T18:28:08.335081Z","steps":["trace[1267408395] 'process raft request' (duration: 300.06701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.335155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.005084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:08.33521Z","caller":"traceutil/trace.go:171","msg":"trace[1196837214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"305.103306ms","start":"2026-02-20T18:28:08.030091Z","end":"2026-02-20T18:28:08.335194Z","steps":["trace[1196837214] 'agreement among raft nodes before linearized reading' (duration: 304.995604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:08.335262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.030077Z","time spent":"305.174738ms","remote":"127.0.0.1:60948","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-20T18:28:08.335261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:08.034391Z","time spent":"300.791994ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:08.601792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.608015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:08.601934Z","caller":"traceutil/trace.go:171","msg":"trace[1776315433] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"110.804448ms","start":"2026-02-20T18:28:08.491106Z","end":"2026-02-20T18:28:08.60191Z","steps":["trace[1776315433] 'range keys from in-memory index tree' (duration: 110.481023ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:10.146854Z","caller":"traceutil/trace.go:171","msg":"trace[597932279] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"351.665332ms","start":"2026-02-20T18:28:09.795165Z","end":"2026-02-20T18:28:10.146831Z","steps":["trace[597932279] 'process raft request' (duration: 351.454298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:10.151084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:09.79514Z","time spent":"352.061291ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:11.82343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484039932s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:28:11.823509Z","caller":"traceutil/trace.go:171","msg":"trace[334874024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:608; }","duration":"1.484184075s","start":"2026-02-20T18:28:10.339304Z","end":"2026-02-20T18:28:11.823488Z","steps":["trace[334874024] 'range keys from in-memory index tree' (duration: 1.483910289s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:11.823553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:10.339276Z","time spent":"1.484268477s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:28:11.823619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483300448s","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-20T18:28:11.823665Z","caller":"traceutil/trace.go:171","msg":"trace[1040143714] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.483379461s","start":"2026-02-20T18:28:10.34027Z","end":"2026-02-20T18:28:11.82365Z","steps":["trace[1040143714] 'range keys from in-memory index tree' (duration: 1.483202935s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:11.823696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:10.34025Z","time spent":"1.483438091s","remote":"127.0.0.1:32952","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-20T18:28:11.823678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.333818744s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:11.823741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483628015s","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-20T18:28:11.823763Z","caller":"traceutil/trace.go:171","msg":"trace[434133808] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"1.333947356s","start":"2026-02-20T18:28:10.489793Z","end":"2026-02-20T18:28:11.823741Z","steps":["trace[434133808] 'range keys from in-memory index tree' (duration: 1.333729832s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:11.823798Z","caller":"traceutil/trace.go:171","msg":"trace[633537729] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"1.483712366s","start":"2026-02-20T18:28:10.340071Z","end":"2026-02-20T18:28:11.823783Z","steps":["trace[633537729] 'range keys from in-memory index tree' (duration: 1.483493542s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:11.823806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:10.489774Z","time spent":"1.334024137s","remote":"127.0.0.1:60932","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-20T18:28:11.823826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:10.34006Z","time spent":"1.483759527s","remote":"127.0.0.1:32952","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-20T18:28:11.823879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.483751287s","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-20T18:28:11.823912Z","caller":"traceutil/trace.go:171","msg":"trace[1841864835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:608; }","duration":"1.483821538s","start":"2026-02-20T18:28:10.34008Z","end":"2026-02-20T18:28:11.823901Z","steps":["trace[1841864835] 'range keys from in-memory index tree' (duration: 1.483667515s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:11.823938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:10.340067Z","time spent":"1.483865979s","remote":"127.0.0.1:32952","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-20T18:28:11.823951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.963817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:28:11.824307Z","caller":"traceutil/trace.go:171","msg":"trace[203239332] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"721.825426ms","start":"2026-02-20T18:28:11.102466Z","end":"2026-02-20T18:28:11.824291Z","steps":["trace[203239332] 'process raft request' (duration: 574.464772ms)","trace[203239332] 'compare' (duration: 146.282485ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:11.824384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:11.102442Z","time spent":"721.907297ms","remote":"127.0.0.1:32952","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-20T18:28:15.033615Z","caller":"traceutil/trace.go:171","msg":"trace[1965960889] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"210.492887ms","start":"2026-02-20T18:28:14.823083Z","end":"2026-02-20T18:28:15.033576Z","steps":["trace[1965960889] 'process raft request' (duration: 132.498122ms)","trace[1965960889] 'compare' (duration: 77.818891ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:16.373949Z","caller":"traceutil/trace.go:171","msg":"trace[487505033] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"171.049505ms","start":"2026-02-20T18:28:16.202871Z","end":"2026-02-20T18:28:16.373921Z","steps":["trace[487505033] 'process raft request' (duration: 156.399426ms)","trace[487505033] 'compare' (duration: 14.451485ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:18.986521Z","caller":"traceutil/trace.go:171","msg":"trace[1281718408] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"109.80561ms","start":"2026-02-20T18:28:18.876693Z","end":"2026-02-20T18:28:18.986498Z","steps":["trace[1281718408] 'process raft request' (duration: 109.603497ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:21.933234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.743325ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-20T18:28:21.933419Z","caller":"traceutil/trace.go:171","msg":"trace[1416645188] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"126.13013ms","start":"2026-02-20T18:28:21.80727Z","end":"2026-02-20T18:28:21.933401Z","steps":["trace[1416645188] 'read index received' (duration: 13.263963ms)","trace[1416645188] 'applied index is now lower than readState.Index' (duration: 112.864747ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:21.933603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.322084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-drk4v\" ","response":"range_response_count:1 size:3849"} {"level":"info","ts":"2026-02-20T18:28:21.933633Z","caller":"traceutil/trace.go:171","msg":"trace[1813127864] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-drk4v; range_end:; response_count:1; response_revision:677; }","duration":"126.390676ms","start":"2026-02-20T18:28:21.807232Z","end":"2026-02-20T18:28:21.933622Z","steps":["trace[1813127864] 'agreement among raft nodes before linearized reading' (duration: 126.253103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:22.988851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265754822,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:28:23.357627Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.073819065s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:28:23.358013Z","caller":"traceutil/trace.go:171","msg":"trace[819891610] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"870.054817ms","start":"2026-02-20T18:28:22.487933Z","end":"2026-02-20T18:28:23.357987Z","steps":["trace[819891610] 'read index received' (duration: 869.857004ms)","trace[819891610] 'applied index is now lower than readState.Index' (duration: 196.923µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:23.358124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"870.178559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:23.358165Z","caller":"traceutil/trace.go:171","msg":"trace[518067895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"870.263071ms","start":"2026-02-20T18:28:22.487891Z","end":"2026-02-20T18:28:23.358154Z","steps":["trace[518067895] 'agreement among raft nodes before linearized reading' (duration: 870.166739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:23.358201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:22.487876Z","time spent":"870.318122ms","remote":"127.0.0.1:60932","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-20T18:28:23.358531Z","caller":"traceutil/trace.go:171","msg":"trace[952298115] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.074824034s","start":"2026-02-20T18:28:22.283695Z","end":"2026-02-20T18:28:23.358519Z","steps":["trace[952298115] 'process raft request' (duration: 1.074154612s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:23.358665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:22.283673Z","time spent":"1.074938666s","remote":"127.0.0.1:32952","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-20T18:28:24.351362Z","caller":"traceutil/trace.go:171","msg":"trace[1848431321] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"168.384045ms","start":"2026-02-20T18:28:24.182956Z","end":"2026-02-20T18:28:24.351341Z","steps":["trace[1848431321] 'process raft request' (duration: 168.295613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:24.351457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.032379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:28:24.351488Z","caller":"traceutil/trace.go:171","msg":"trace[1686017663] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"169.330032ms","start":"2026-02-20T18:28:24.18215Z","end":"2026-02-20T18:28:24.35148Z","steps":["trace[1686017663] 'process raft request' (duration: 158.126899ms)","trace[1686017663] 'compare' (duration: 10.884276ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:24.351499Z","caller":"traceutil/trace.go:171","msg":"trace[1810813951] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:700; }","duration":"125.149702ms","start":"2026-02-20T18:28:24.226339Z","end":"2026-02-20T18:28:24.351489Z","steps":["trace[1810813951] 'agreement among raft nodes before linearized reading' (duration: 125.06303ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:24.351355Z","caller":"traceutil/trace.go:171","msg":"trace[92190238] linearizableReadLoop","detail":"{readStateIndex:725; appliedIndex:722; }","duration":"124.926767ms","start":"2026-02-20T18:28:24.226407Z","end":"2026-02-20T18:28:24.351334Z","steps":["trace[92190238] 'read index received' (duration: 113.799736ms)","trace[92190238] 'applied index is now lower than readState.Index' (duration: 11.126011ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:24.351602Z","caller":"traceutil/trace.go:171","msg":"trace[798543257] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"165.906278ms","start":"2026-02-20T18:28:24.185685Z","end":"2026-02-20T18:28:24.351591Z","steps":["trace[798543257] 'process raft request' (duration: 165.607542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:31.997099Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265754937,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:28:32.46217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089061397s","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-20T18:28:32.462255Z","caller":"traceutil/trace.go:171","msg":"trace[1766000810] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:748; }","duration":"2.089180358s","start":"2026-02-20T18:28:30.373052Z","end":"2026-02-20T18:28:32.462232Z","steps":["trace[1766000810] 'range keys from in-memory index tree' (duration: 2.088162209s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.462251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.507254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-20T18:28:32.462299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.373036Z","time spent":"2.08925183s","remote":"127.0.0.1:32952","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-20T18:28:32.462336Z","caller":"traceutil/trace.go:171","msg":"trace[341034867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:748; }","duration":"986.660957ms","start":"2026-02-20T18:28:31.475656Z","end":"2026-02-20T18:28:32.462317Z","steps":["trace[341034867] 'range keys from in-memory index tree' (duration: 986.389121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.462302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.973972347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:32.462442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:31.475636Z","time spent":"986.7949ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-20T18:28:32.462465Z","caller":"traceutil/trace.go:171","msg":"trace[1715848091] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"2.974148731s","start":"2026-02-20T18:28:29.488297Z","end":"2026-02-20T18:28:32.462446Z","steps":["trace[1715848091] 'range keys from in-memory index tree' (duration: 2.973915646s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.462514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:29.48826Z","time spent":"2.974244523s","remote":"127.0.0.1:60948","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-20T18:28:32.462538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.089039806s","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-20T18:28:32.462591Z","caller":"traceutil/trace.go:171","msg":"trace[1888129529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"2.089099807s","start":"2026-02-20T18:28:30.373474Z","end":"2026-02-20T18:28:32.462573Z","steps":["trace[1888129529] 'range keys from in-memory index tree' (duration: 2.088930654s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.462633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.373469Z","time spent":"2.089155168s","remote":"127.0.0.1:32952","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-20T18:28:32.462745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.381024393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-20T18:28:32.462791Z","caller":"traceutil/trace.go:171","msg":"trace[230811094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:748; }","duration":"2.381102344s","start":"2026-02-20T18:28:30.081675Z","end":"2026-02-20T18:28:32.462778Z","steps":["trace[230811094] 'range keys from in-memory index tree' (duration: 2.38090081s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.46282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.081656Z","time spent":"2.381157746s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:28:32.462794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.880693172s","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":"info","ts":"2026-02-20T18:28:32.462874Z","caller":"traceutil/trace.go:171","msg":"trace[1708277954] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:748; }","duration":"2.880816005s","start":"2026-02-20T18:28:29.582039Z","end":"2026-02-20T18:28:32.462855Z","steps":["trace[1708277954] 'count revisions from in-memory index tree' (duration: 2.880626271s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.462914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:29.582022Z","time spent":"2.880882165s","remote":"127.0.0.1:33004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:28:32.462985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.332257325s","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-20T18:28:32.463063Z","caller":"traceutil/trace.go:171","msg":"trace[1969312949] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:748; }","duration":"2.332337236s","start":"2026-02-20T18:28:30.130715Z","end":"2026-02-20T18:28:32.463053Z","steps":["trace[1969312949] 'range keys from in-memory index tree' (duration: 2.332248755s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.463309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.233684198s","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-20T18:28:32.463338Z","caller":"traceutil/trace.go:171","msg":"trace[221123294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"2.233750759s","start":"2026-02-20T18:28:30.229579Z","end":"2026-02-20T18:28:32.463329Z","steps":["trace[221123294] 'range keys from in-memory index tree' (duration: 2.233597847s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.463379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:30.229546Z","time spent":"2.233811091s","remote":"127.0.0.1:32952","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":"info","ts":"2026-02-20T18:28:32.463434Z","caller":"traceutil/trace.go:171","msg":"trace[417023305] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:775; }","duration":"967.35393ms","start":"2026-02-20T18:28:31.496067Z","end":"2026-02-20T18:28:32.463421Z","steps":["trace[417023305] 'read index received' (duration: 967.33635ms)","trace[417023305] 'applied index is now lower than readState.Index' (duration: 16.52µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:32.463439Z","caller":"traceutil/trace.go:171","msg":"trace[1320544421] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"969.887567ms","start":"2026-02-20T18:28:31.493539Z","end":"2026-02-20T18:28:32.463427Z","steps":["trace[1320544421] 'process raft request' (duration: 969.50445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.463521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:31.493514Z","time spent":"969.957989ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:28:32.463542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.469392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:32.463569Z","caller":"traceutil/trace.go:171","msg":"trace[1940075899] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:749; }","duration":"967.536613ms","start":"2026-02-20T18:28:31.496024Z","end":"2026-02-20T18:28:32.46356Z","steps":["trace[1940075899] 'agreement among raft nodes before linearized reading' (duration: 967.462212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:32.463595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:31.496009Z","time spent":"967.579684ms","remote":"127.0.0.1:60932","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-20T18:28:32.963767Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265754940,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:28:33.337147Z","caller":"traceutil/trace.go:171","msg":"trace[804731270] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.088648126s","start":"2026-02-20T18:28:32.248481Z","end":"2026-02-20T18:28:33.337129Z","steps":["trace[804731270] 'process raft request' (duration: 1.088535484s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.337261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.248454Z","time spent":"1.088749979s","remote":"127.0.0.1:32952","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-20T18:28:33.481371Z","caller":"traceutil/trace.go:171","msg":"trace[1305487790] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"1.014590617s","start":"2026-02-20T18:28:32.46676Z","end":"2026-02-20T18:28:33.48135Z","steps":["trace[1305487790] 'process raft request' (duration: 1.014427874s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.481504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.466738Z","time spent":"1.01469777s","remote":"127.0.0.1:32952","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-20T18:28:33.481609Z","caller":"traceutil/trace.go:171","msg":"trace[662357302] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"1.014219841s","start":"2026-02-20T18:28:32.467371Z","end":"2026-02-20T18:28:33.481591Z","steps":["trace[662357302] 'process raft request' (duration: 1.013940826s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.481729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.467357Z","time spent":"1.014301952s","remote":"127.0.0.1:32952","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-20T18:28:33.48176Z","caller":"traceutil/trace.go:171","msg":"trace[1782557060] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"1.014305104s","start":"2026-02-20T18:28:32.467444Z","end":"2026-02-20T18:28:33.481749Z","steps":["trace[1782557060] 'process raft request' (duration: 1.014088249s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.481816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.467437Z","time spent":"1.014349824s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:33.481878Z","caller":"traceutil/trace.go:171","msg":"trace[1295705909] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"1.014190391s","start":"2026-02-20T18:28:32.467681Z","end":"2026-02-20T18:28:33.481871Z","steps":["trace[1295705909] 'process raft request' (duration: 1.014024188s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.481917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.467669Z","time spent":"1.014225261s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:33.482127Z","caller":"traceutil/trace.go:171","msg":"trace[934654994] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"1.014420325s","start":"2026-02-20T18:28:32.467691Z","end":"2026-02-20T18:28:33.482111Z","steps":["trace[934654994] 'process raft request' (duration: 1.01413898s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:33.482212Z","caller":"traceutil/trace.go:171","msg":"trace[93099002] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"1.014069008s","start":"2026-02-20T18:28:32.468136Z","end":"2026-02-20T18:28:33.482205Z","steps":["trace[93099002] 'process raft request' (duration: 1.013839364s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.482235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.467681Z","time spent":"1.014501627s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:33.482257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.468128Z","time spent":"1.014104729s","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:28:33.482097Z","caller":"traceutil/trace.go:171","msg":"trace[1951468637] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:776; }","duration":"1.018624395s","start":"2026-02-20T18:28:32.463462Z","end":"2026-02-20T18:28:33.482087Z","steps":["trace[1951468637] 'read index received' (duration: 873.531553ms)","trace[1951468637] 'applied index is now lower than readState.Index' (duration: 145.092082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:33.483112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.696948062s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:28:33.483144Z","caller":"traceutil/trace.go:171","msg":"trace[748534562] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:756; }","duration":"1.697021846s","start":"2026-02-20T18:28:31.786112Z","end":"2026-02-20T18:28:33.483133Z","steps":["trace[748534562] 'agreement among raft nodes before linearized reading' (duration: 1.696899323s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.483169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:31.786091Z","time spent":"1.697072207s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"warn","ts":"2026-02-20T18:28:33.483226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.018183047s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:33.483293Z","caller":"traceutil/trace.go:171","msg":"trace[2099204702] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:756; }","duration":"1.01830247s","start":"2026-02-20T18:28:32.464972Z","end":"2026-02-20T18:28:33.483274Z","steps":["trace[2099204702] 'agreement among raft nodes before linearized reading' (duration: 1.018134856s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:33.483338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:32.464955Z","time spent":"1.018374621s","remote":"127.0.0.1:60948","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-20T18:28:35.599707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.347647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:28:35.599785Z","caller":"traceutil/trace.go:171","msg":"trace[898145234] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:758; }","duration":"113.447489ms","start":"2026-02-20T18:28:35.486323Z","end":"2026-02-20T18:28:35.599771Z","steps":["trace[898145234] 'range keys from in-memory index tree' (duration: 113.113163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:35.599766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.56718ms","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-20T18:28:35.599844Z","caller":"traceutil/trace.go:171","msg":"trace[1508943248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"113.699713ms","start":"2026-02-20T18:28:35.486125Z","end":"2026-02-20T18:28:35.599825Z","steps":["trace[1508943248] 'range keys from in-memory index tree' (duration: 113.405498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:35.599979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.673063ms","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-20T18:28:35.600047Z","caller":"traceutil/trace.go:171","msg":"trace[62577455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:758; }","duration":"113.766965ms","start":"2026-02-20T18:28:35.486264Z","end":"2026-02-20T18:28:35.600031Z","steps":["trace[62577455] 'range keys from in-memory index tree' (duration: 113.396178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:35.600112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.755814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-20T18:28:35.600159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.893978ms","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-20T18:28:35.60039Z","caller":"traceutil/trace.go:171","msg":"trace[5753638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:758; }","duration":"114.167533ms","start":"2026-02-20T18:28:35.486211Z","end":"2026-02-20T18:28:35.600379Z","steps":["trace[5753638] 'range keys from in-memory index tree' (duration: 113.54944ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:35.600166Z","caller":"traceutil/trace.go:171","msg":"trace[199170616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:758; }","duration":"113.825276ms","start":"2026-02-20T18:28:35.486326Z","end":"2026-02-20T18:28:35.600152Z","steps":["trace[199170616] 'range keys from in-memory index tree' (duration: 113.51906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:35.600282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.621604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:35.600541Z","caller":"traceutil/trace.go:171","msg":"trace[58652836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"111.904839ms","start":"2026-02-20T18:28:35.488627Z","end":"2026-02-20T18:28:35.600531Z","steps":["trace[58652836] 'range keys from in-memory index tree' (duration: 111.554263ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:35.842535Z","caller":"traceutil/trace.go:171","msg":"trace[1758870559] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"239.189762ms","start":"2026-02-20T18:28:35.603326Z","end":"2026-02-20T18:28:35.842516Z","steps":["trace[1758870559] 'process raft request' (duration: 239.017439ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:35.842797Z","caller":"traceutil/trace.go:171","msg":"trace[1626941360] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"239.143851ms","start":"2026-02-20T18:28:35.60363Z","end":"2026-02-20T18:28:35.842774Z","steps":["trace[1626941360] 'process raft request' (duration: 238.857886ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:35.842891Z","caller":"traceutil/trace.go:171","msg":"trace[300661265] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"238.964778ms","start":"2026-02-20T18:28:35.603901Z","end":"2026-02-20T18:28:35.842865Z","steps":["trace[300661265] 'process raft request' (duration: 238.783444ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:35.84295Z","caller":"traceutil/trace.go:171","msg":"trace[72916055] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"238.679193ms","start":"2026-02-20T18:28:35.604263Z","end":"2026-02-20T18:28:35.842942Z","steps":["trace[72916055] 'process raft request' (duration: 238.556231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.68593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.222565ms","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-20T18:28:54.685989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.232485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:28:54.686024Z","caller":"traceutil/trace.go:171","msg":"trace[122794648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:813; }","duration":"721.364028ms","start":"2026-02-20T18:28:53.964641Z","end":"2026-02-20T18:28:54.686005Z","steps":["trace[122794648] 'range keys from in-memory index tree' (duration: 721.050492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:53.964629Z","time spent":"721.436739ms","remote":"127.0.0.1:32952","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-20T18:28:54.686082Z","caller":"traceutil/trace.go:171","msg":"trace[574620048] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:813; }","duration":"939.385188ms","start":"2026-02-20T18:28:53.746668Z","end":"2026-02-20T18:28:54.686053Z","steps":["trace[574620048] 'range keys from in-memory index tree' (duration: 939.02822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:53.746637Z","time spent":"939.506599ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:28:54.686141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.940494ms","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-20T18:28:54.686096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.208934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:54.686215Z","caller":"traceutil/trace.go:171","msg":"trace[1817727368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"758.042955ms","start":"2026-02-20T18:28:53.928151Z","end":"2026-02-20T18:28:54.686194Z","steps":["trace[1817727368] 'range keys from in-memory index tree' (duration: 757.775251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:53.928131Z","time spent":"758.137908ms","remote":"127.0.0.1:32952","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-20T18:28:54.686183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.290789ms","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-20T18:28:54.686459Z","caller":"traceutil/trace.go:171","msg":"trace[1405382730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:813; }","duration":"718.620506ms","start":"2026-02-20T18:28:53.967818Z","end":"2026-02-20T18:28:54.686439Z","steps":["trace[1405382730] 'range keys from in-memory index tree' (duration: 718.107996ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:54.686333Z","caller":"traceutil/trace.go:171","msg":"trace[2077063618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"196.53121ms","start":"2026-02-20T18:28:54.489723Z","end":"2026-02-20T18:28:54.686254Z","steps":["trace[2077063618] 'range keys from in-memory index tree' (duration: 196.075662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.121624ms","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-20T18:28:54.686511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:53.967797Z","time spent":"718.703567ms","remote":"127.0.0.1:32952","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-20T18:28:54.686596Z","caller":"traceutil/trace.go:171","msg":"trace[1902705856] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:813; }","duration":"729.218686ms","start":"2026-02-20T18:28:53.957365Z","end":"2026-02-20T18:28:54.686584Z","steps":["trace[1902705856] 'range keys from in-memory index tree' (duration: 728.992352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:53.957344Z","time spent":"729.281178ms","remote":"127.0.0.1:32952","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-20T18:28:54.685859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.878446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:54.686713Z","caller":"traceutil/trace.go:171","msg":"trace[2045699873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:813; }","duration":"497.775952ms","start":"2026-02-20T18:28:54.188919Z","end":"2026-02-20T18:28:54.686695Z","steps":["trace[2045699873] 'range keys from in-memory index tree' (duration: 496.777434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:54.686751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.188905Z","time spent":"497.835475ms","remote":"127.0.0.1:60932","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-20T18:28:55.381482Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265755126,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:28:55.673707Z","caller":"traceutil/trace.go:171","msg":"trace[396035588] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"982.643279ms","start":"2026-02-20T18:28:54.690998Z","end":"2026-02-20T18:28:55.673642Z","steps":["trace[396035588] 'process raft request' (duration: 982.387445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.674089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.690975Z","time spent":"982.869185ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:55.681472Z","caller":"traceutil/trace.go:171","msg":"trace[673618018] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"990.226495ms","start":"2026-02-20T18:28:54.691236Z","end":"2026-02-20T18:28:55.681463Z","steps":["trace[673618018] 'process raft request' (duration: 990.175894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.681529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.691219Z","time spent":"990.282735ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:55.681534Z","caller":"traceutil/trace.go:171","msg":"trace[292630157] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"990.277585ms","start":"2026-02-20T18:28:54.691219Z","end":"2026-02-20T18:28:55.681497Z","steps":["trace[292630157] 'process raft request' (duration: 990.126292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.681728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.691197Z","time spent":"990.420827ms","remote":"127.0.0.1:32952","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-20T18:28:55.681763Z","caller":"traceutil/trace.go:171","msg":"trace[1469966692] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"990.099452ms","start":"2026-02-20T18:28:54.691639Z","end":"2026-02-20T18:28:55.681739Z","steps":["trace[1469966692] 'process raft request' (duration: 989.796936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.681948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.691621Z","time spent":"990.230505ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:55.683129Z","caller":"traceutil/trace.go:171","msg":"trace[2013908658] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:845; }","duration":"801.83063ms","start":"2026-02-20T18:28:54.881283Z","end":"2026-02-20T18:28:55.683113Z","steps":["trace[2013908658] 'read index received' (duration: 792.048874ms)","trace[2013908658] 'applied index is now lower than readState.Index' (duration: 9.781026ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:28:55.68317Z","caller":"traceutil/trace.go:171","msg":"trace[1842153445] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"991.462677ms","start":"2026-02-20T18:28:54.691688Z","end":"2026-02-20T18:28:55.683151Z","steps":["trace[1842153445] 'process raft request' (duration: 991.138122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.683248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.691663Z","time spent":"991.54465ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:55.683329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.928751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:55.683386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.311461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:28:55.683411Z","caller":"traceutil/trace.go:171","msg":"trace[1863876456] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"193.111345ms","start":"2026-02-20T18:28:55.490286Z","end":"2026-02-20T18:28:55.683397Z","steps":["trace[1863876456] 'agreement among raft nodes before linearized reading' (duration: 192.950412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.683342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.061484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:28:55.683429Z","caller":"traceutil/trace.go:171","msg":"trace[764168359] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:819; }","duration":"159.387852ms","start":"2026-02-20T18:28:55.524028Z","end":"2026-02-20T18:28:55.683416Z","steps":["trace[764168359] 'agreement among raft nodes before linearized reading' (duration: 159.315701ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:55.683461Z","caller":"traceutil/trace.go:171","msg":"trace[1445295879] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:819; }","duration":"802.207056ms","start":"2026-02-20T18:28:54.881241Z","end":"2026-02-20T18:28:55.683448Z","steps":["trace[1445295879] 'agreement among raft nodes before linearized reading' (duration: 802.067313ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:55.683515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:54.881171Z","time spent":"802.326759ms","remote":"127.0.0.1:33182","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:28:57.738111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.311542ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:28:57.738228Z","caller":"traceutil/trace.go:171","msg":"trace[651178413] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"383.979477ms","start":"2026-02-20T18:28:57.354226Z","end":"2026-02-20T18:28:57.738205Z","steps":["trace[651178413] 'read index received' (duration: 39.227536ms)","trace[651178413] 'applied index is now lower than readState.Index' (duration: 344.749741ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:57.738473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.236671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:57.738532Z","caller":"traceutil/trace.go:171","msg":"trace[1746984258] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:819; }","duration":"384.308192ms","start":"2026-02-20T18:28:57.35421Z","end":"2026-02-20T18:28:57.738518Z","steps":["trace[1746984258] 'agreement among raft nodes before linearized reading' (duration: 384.099799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:57.738576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.354153Z","time spent":"384.410295ms","remote":"127.0.0.1:32926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:28:57.738454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.770435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:57.738984Z","caller":"traceutil/trace.go:171","msg":"trace[2095680974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:819; }","duration":"249.315154ms","start":"2026-02-20T18:28:57.489634Z","end":"2026-02-20T18:28:57.738949Z","steps":["trace[2095680974] 'agreement among raft nodes before linearized reading' (duration: 248.703953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.315196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265755139,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:28:58.565696Z","caller":"traceutil/trace.go:171","msg":"trace[859790467] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"822.804879ms","start":"2026-02-20T18:28:57.742868Z","end":"2026-02-20T18:28:58.565673Z","steps":["trace[859790467] 'process raft request' (duration: 822.655536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.565861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.742843Z","time spent":"822.927641ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:58.740975Z","caller":"traceutil/trace.go:171","msg":"trace[1071435145] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"996.702098ms","start":"2026-02-20T18:28:57.74425Z","end":"2026-02-20T18:28:58.740952Z","steps":["trace[1071435145] 'process raft request' (duration: 948.317767ms)","trace[1071435145] 'compare' (duration: 48.164947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:58.741101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.744231Z","time spent":"996.80334ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:58.741086Z","caller":"traceutil/trace.go:171","msg":"trace[207737009] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"996.265899ms","start":"2026-02-20T18:28:57.744792Z","end":"2026-02-20T18:28:58.741058Z","steps":["trace[207737009] 'process raft request' (duration: 996.104036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.741297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.744779Z","time spent":"996.420222ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:58.741897Z","caller":"traceutil/trace.go:171","msg":"trace[371120524] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"996.872041ms","start":"2026-02-20T18:28:57.745007Z","end":"2026-02-20T18:28:58.741879Z","steps":["trace[371120524] 'process raft request' (duration: 995.948863ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:28:58.742162Z","caller":"traceutil/trace.go:171","msg":"trace[93411594] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"996.583815ms","start":"2026-02-20T18:28:57.745548Z","end":"2026-02-20T18:28:58.742132Z","steps":["trace[93411594] 'process raft request' (duration: 996.207208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.742324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.744999Z","time spent":"997.190327ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:28:58.742452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.172084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:28:58.742338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.745539Z","time spent":"996.711079ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:28:58.742527Z","caller":"traceutil/trace.go:171","msg":"trace[2015681698] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:824; }","duration":"928.293786ms","start":"2026-02-20T18:28:57.814212Z","end":"2026-02-20T18:28:58.742506Z","steps":["trace[2015681698] 'agreement among raft nodes before linearized reading' (duration: 928.100923ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:28:58.742592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:28:57.814193Z","time spent":"928.382857ms","remote":"127.0.0.1:32922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:28:58.742445Z","caller":"traceutil/trace.go:171","msg":"trace[270886669] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:852; }","duration":"927.812687ms","start":"2026-02-20T18:28:57.814257Z","end":"2026-02-20T18:28:58.74207Z","steps":["trace[270886669] 'read index received' (duration: 751.302278ms)","trace[270886669] 'applied index is now lower than readState.Index' (duration: 176.508579ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:28:58.742747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.321291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:28:58.742799Z","caller":"traceutil/trace.go:171","msg":"trace[1360421864] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"252.436074ms","start":"2026-02-20T18:28:58.490344Z","end":"2026-02-20T18:28:58.74278Z","steps":["trace[1360421864] 'agreement among raft nodes before linearized reading' (duration: 252.366503ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:09.369904Z","caller":"traceutil/trace.go:171","msg":"trace[961916314] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"561.907577ms","start":"2026-02-20T18:29:08.807981Z","end":"2026-02-20T18:29:09.369888Z","steps":["trace[961916314] 'process raft request' (duration: 561.884866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:09.370016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:08.807969Z","time spent":"561.991659ms","remote":"127.0.0.1:32952","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-20T18:29:09.370267Z","caller":"traceutil/trace.go:171","msg":"trace[872932464] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"563.400666ms","start":"2026-02-20T18:29:08.80686Z","end":"2026-02-20T18:29:09.370261Z","steps":["trace[872932464] 'process raft request' (duration: 562.890215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:09.370303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:08.806834Z","time spent":"563.452467ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:09.370468Z","caller":"traceutil/trace.go:171","msg":"trace[298052747] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"563.389695ms","start":"2026-02-20T18:29:08.807073Z","end":"2026-02-20T18:29:09.370462Z","steps":["trace[298052747] 'process raft request' (duration: 562.769782ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:09.370494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:08.807057Z","time spent":"563.423637ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:14.809731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265755342,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:14.963312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.299939634s","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-20T18:29:14.963405Z","caller":"traceutil/trace.go:171","msg":"trace[1150996724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:904; }","duration":"2.300078477s","start":"2026-02-20T18:29:12.663307Z","end":"2026-02-20T18:29:14.963385Z","steps":["trace[1150996724] 'range keys from in-memory index tree' (duration: 2.299797022s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.963446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.663284Z","time spent":"2.300153678s","remote":"127.0.0.1:32952","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-20T18:29:14.963444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.109754213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:14.963493Z","caller":"traceutil/trace.go:171","msg":"trace[2051826502] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:904; }","duration":"4.109834985s","start":"2026-02-20T18:29:10.853643Z","end":"2026-02-20T18:29:14.963478Z","steps":["trace[2051826502] 'range keys from in-memory index tree' (duration: 4.109617391s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.963525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:10.853624Z","time spent":"4.109893527s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:29:14.963502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.217246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:14.963495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.52030014s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:29:14.963576Z","caller":"traceutil/trace.go:171","msg":"trace[984311548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"773.362888ms","start":"2026-02-20T18:29:14.190195Z","end":"2026-02-20T18:29:14.963558Z","steps":["trace[984311548] 'range keys from in-memory index tree' (duration: 773.083473ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:14.963596Z","caller":"traceutil/trace.go:171","msg":"trace[1024382998] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:904; }","duration":"3.520467785s","start":"2026-02-20T18:29:11.443104Z","end":"2026-02-20T18:29:14.963571Z","steps":["trace[1024382998] 'count revisions from in-memory index tree' (duration: 3.520211019s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.963614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.190163Z","time spent":"773.442929ms","remote":"127.0.0.1:60932","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-20T18:29:14.963645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.443085Z","time spent":"3.520548536s","remote":"127.0.0.1:33130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:14.963766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.473539812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:14.963797Z","caller":"traceutil/trace.go:171","msg":"trace[1433790689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:904; }","duration":"2.473601414s","start":"2026-02-20T18:29:12.490185Z","end":"2026-02-20T18:29:14.963786Z","steps":["trace[1433790689] 'range keys from in-memory index tree' (duration: 2.47342009s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.963839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:12.490143Z","time spent":"2.473673655s","remote":"127.0.0.1:60932","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-20T18:29:14.963849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.590028565s","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-20T18:29:14.963892Z","caller":"traceutil/trace.go:171","msg":"trace[988822667] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:904; }","duration":"3.590103495s","start":"2026-02-20T18:29:11.373776Z","end":"2026-02-20T18:29:14.96388Z","steps":["trace[988822667] 'range keys from in-memory index tree' (duration: 3.589908523s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.963919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.373757Z","time spent":"3.590154707s","remote":"127.0.0.1:32952","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-20T18:29:14.963977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.589387872s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:29:14.964002Z","caller":"traceutil/trace.go:171","msg":"trace[598199436] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:904; }","duration":"3.589436454s","start":"2026-02-20T18:29:11.374558Z","end":"2026-02-20T18:29:14.963994Z","steps":["trace[598199436] 'range keys from in-memory index tree' (duration: 3.589167359s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.964019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.374549Z","time spent":"3.589465464s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:14.963963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.175061955s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-20T18:29:14.964016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.590001514s","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-20T18:29:14.964083Z","caller":"traceutil/trace.go:171","msg":"trace[792420973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:904; }","duration":"3.590085356s","start":"2026-02-20T18:29:11.373979Z","end":"2026-02-20T18:29:14.964065Z","steps":["trace[792420973] 'range keys from in-memory index tree' (duration: 3.589852671s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:14.964075Z","caller":"traceutil/trace.go:171","msg":"trace[1471633668] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:904; }","duration":"3.175256859s","start":"2026-02-20T18:29:11.788788Z","end":"2026-02-20T18:29:14.964045Z","steps":["trace[1471633668] 'range keys from in-memory index tree' (duration: 3.174858372s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.964126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.373973Z","time spent":"3.590142947s","remote":"127.0.0.1:32952","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-20T18:29:14.964126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:11.788774Z","time spent":"3.175341201s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-02-20T18:29:14.994184Z","caller":"traceutil/trace.go:171","msg":"trace[1295895904] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"685.185327ms","start":"2026-02-20T18:29:14.308969Z","end":"2026-02-20T18:29:14.994154Z","steps":["trace[1295895904] 'read index received' (duration: 685.027034ms)","trace[1295895904] 'applied index is now lower than readState.Index' (duration: 156.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:14.99427Z","caller":"traceutil/trace.go:171","msg":"trace[1254580549] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"780.742879ms","start":"2026-02-20T18:29:14.213498Z","end":"2026-02-20T18:29:14.994241Z","steps":["trace[1254580549] 'process raft request' (duration: 780.465984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.994603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.555016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T18:29:14.994615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.213463Z","time spent":"781.051306ms","remote":"127.0.0.1:32952","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-20T18:29:14.99472Z","caller":"traceutil/trace.go:171","msg":"trace[1526594894] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:905; }","duration":"685.749459ms","start":"2026-02-20T18:29:14.308923Z","end":"2026-02-20T18:29:14.994672Z","steps":["trace[1526594894] 'agreement among raft nodes before linearized reading' (duration: 685.393842ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:14.994826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.308903Z","time spent":"685.898572ms","remote":"127.0.0.1:60976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":31,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:15.302216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.966415Z","time spent":"335.795352ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-20T18:29:15.302693Z","caller":"traceutil/trace.go:171","msg":"trace[927878514] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"333.556639ms","start":"2026-02-20T18:29:14.969118Z","end":"2026-02-20T18:29:15.302675Z","steps":["trace[927878514] 'process raft request' (duration: 333.075759ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.30289Z","caller":"traceutil/trace.go:171","msg":"trace[1385342012] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"333.767974ms","start":"2026-02-20T18:29:14.969112Z","end":"2026-02-20T18:29:15.30288Z","steps":["trace[1385342012] 'process raft request' (duration: 332.988268ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.302879Z","caller":"traceutil/trace.go:171","msg":"trace[123557507] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"333.631231ms","start":"2026-02-20T18:29:14.969236Z","end":"2026-02-20T18:29:15.302867Z","steps":["trace[123557507] 'process raft request' (duration: 333.018968ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:15.302904Z","caller":"traceutil/trace.go:171","msg":"trace[1701678162] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"333.58928ms","start":"2026-02-20T18:29:14.969288Z","end":"2026-02-20T18:29:15.302877Z","steps":["trace[1701678162] 'process raft request' (duration: 332.994598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.302944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.969097Z","time spent":"333.811374ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:15.302961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.969204Z","time spent":"333.719053ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:15.302956Z","caller":"traceutil/trace.go:171","msg":"trace[872723517] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"333.715433ms","start":"2026-02-20T18:29:14.96921Z","end":"2026-02-20T18:29:15.302925Z","steps":["trace[872723517] 'process raft request' (duration: 333.017569ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.303037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.96928Z","time spent":"333.683111ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:15.303102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.969188Z","time spent":"333.830584ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:15.303166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.474672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:15.303183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.9691Z","time spent":"333.634771ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:15.303228Z","caller":"traceutil/trace.go:171","msg":"trace[1447579892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:910; }","duration":"812.595434ms","start":"2026-02-20T18:29:14.490622Z","end":"2026-02-20T18:29:15.303217Z","steps":["trace[1447579892] 'agreement among raft nodes before linearized reading' (duration: 812.496482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:15.303336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:14.490599Z","time spent":"812.658455ms","remote":"127.0.0.1:60948","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-20T18:29:15.303054Z","caller":"traceutil/trace.go:171","msg":"trace[1175185367] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:941; }","duration":"308.7021ms","start":"2026-02-20T18:29:14.994284Z","end":"2026-02-20T18:29:15.302986Z","steps":["trace[1175185367] 'read index received' (duration: 307.725281ms)","trace[1175185367] 'applied index is now lower than readState.Index' (duration: 975.279µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:17.500616Z","caller":"traceutil/trace.go:171","msg":"trace[1994043517] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"153.756925ms","start":"2026-02-20T18:29:17.346824Z","end":"2026-02-20T18:29:17.500581Z","steps":["trace[1994043517] 'process raft request' (duration: 153.528729ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.535239Z","caller":"traceutil/trace.go:171","msg":"trace[1108592830] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"188.019824ms","start":"2026-02-20T18:29:17.347185Z","end":"2026-02-20T18:29:17.535205Z","steps":["trace[1108592830] 'process raft request' (duration: 187.968623ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.535277Z","caller":"traceutil/trace.go:171","msg":"trace[1642258993] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"188.109726ms","start":"2026-02-20T18:29:17.347136Z","end":"2026-02-20T18:29:17.535246Z","steps":["trace[1642258993] 'process raft request' (duration: 187.840511ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.53554Z","caller":"traceutil/trace.go:171","msg":"trace[1141324712] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"188.3263ms","start":"2026-02-20T18:29:17.347171Z","end":"2026-02-20T18:29:17.535498Z","steps":["trace[1141324712] 'process raft request' (duration: 187.958693ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:17.535711Z","caller":"traceutil/trace.go:171","msg":"trace[1175990976] transaction","detail":"{read_only:false; response_revision:918; number_of_response:1; }","duration":"188.493543ms","start":"2026-02-20T18:29:17.347204Z","end":"2026-02-20T18:29:17.535697Z","steps":["trace[1175990976] 'process raft request' (duration: 187.972403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:31.083767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.686854ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:29:31.083855Z","caller":"traceutil/trace.go:171","msg":"trace[883365772] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"271.84246ms","start":"2026-02-20T18:29:30.811997Z","end":"2026-02-20T18:29:31.08384Z","steps":["trace[883365772] 'read index received' (duration: 49.091µs)","trace[883365772] 'applied index is now lower than readState.Index' (duration: 271.792079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:31.084004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.006093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4741"} {"level":"info","ts":"2026-02-20T18:29:31.084031Z","caller":"traceutil/trace.go:171","msg":"trace[1137976462] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:965; }","duration":"272.079104ms","start":"2026-02-20T18:29:30.811944Z","end":"2026-02-20T18:29:31.084023Z","steps":["trace[1137976462] 'agreement among raft nodes before linearized reading' (duration: 271.933671ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:31.738831Z","caller":"traceutil/trace.go:171","msg":"trace[1841239903] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"102.028787ms","start":"2026-02-20T18:29:31.636773Z","end":"2026-02-20T18:29:31.738802Z","steps":["trace[1841239903] 'process raft request' (duration: 73.917205ms)","trace[1841239903] 'compare' (duration: 27.903178ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.799586Z","caller":"traceutil/trace.go:171","msg":"trace[1422716850] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"386.763975ms","start":"2026-02-20T18:29:35.412802Z","end":"2026-02-20T18:29:35.799566Z","steps":["trace[1422716850] 'read index received' (duration: 386.627353ms)","trace[1422716850] 'applied index is now lower than readState.Index' (duration: 135.692µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:35.799651Z","caller":"traceutil/trace.go:171","msg":"trace[1161545165] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"451.011464ms","start":"2026-02-20T18:29:35.348621Z","end":"2026-02-20T18:29:35.799633Z","steps":["trace[1161545165] 'process raft request' (duration: 450.80035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:35.799737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.608058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:35.799773Z","caller":"traceutil/trace.go:171","msg":"trace[427705769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"308.6999ms","start":"2026-02-20T18:29:35.491062Z","end":"2026-02-20T18:29:35.799762Z","steps":["trace[427705769] 'agreement among raft nodes before linearized reading' (duration: 308.624789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:35.799767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.348599Z","time spent":"451.096535ms","remote":"127.0.0.1:32952","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-20T18:29:35.799762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.962638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T18:29:35.799804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.490987Z","time spent":"308.811182ms","remote":"127.0.0.1:60932","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-20T18:29:35.799907Z","caller":"traceutil/trace.go:171","msg":"trace[1958236400] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:990; }","duration":"387.092641ms","start":"2026-02-20T18:29:35.412725Z","end":"2026-02-20T18:29:35.799818Z","steps":["trace[1958236400] 'agreement among raft nodes before linearized reading' (duration: 386.958369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:35.799954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.412674Z","time spent":"387.267524ms","remote":"127.0.0.1:33036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:35.799983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.997489ms","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-20T18:29:35.800033Z","caller":"traceutil/trace.go:171","msg":"trace[1734131886] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"140.08225ms","start":"2026-02-20T18:29:35.659936Z","end":"2026-02-20T18:29:35.800018Z","steps":["trace[1734131886] 'agreement among raft nodes before linearized reading' (duration: 139.976688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:36.513939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.235422ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:36.514057Z","caller":"traceutil/trace.go:171","msg":"trace[1703647911] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"709.840274ms","start":"2026-02-20T18:29:35.804198Z","end":"2026-02-20T18:29:36.514038Z","steps":["trace[1703647911] 'process raft request' (duration: 402.421928ms)","trace[1703647911] 'compare' (duration: 307.135461ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:36.514126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.80418Z","time spent":"709.921365ms","remote":"127.0.0.1:32952","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-20T18:29:37.334816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.808201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:37.33498Z","caller":"traceutil/trace.go:171","msg":"trace[1301091052] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"1.530382691s","start":"2026-02-20T18:29:35.804579Z","end":"2026-02-20T18:29:37.334962Z","steps":["trace[1301091052] 'process raft request' (duration: 1.081276963s)","trace[1301091052] 'compare' (duration: 448.489575ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:37.335045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.804556Z","time spent":"1.530458642s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:37.33539Z","caller":"traceutil/trace.go:171","msg":"trace[1225541678] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"1.529782299s","start":"2026-02-20T18:29:35.805596Z","end":"2026-02-20T18:29:37.335378Z","steps":["trace[1225541678] 'process raft request' (duration: 1.529559295s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:37.335401Z","caller":"traceutil/trace.go:171","msg":"trace[1406378292] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"1.530408691s","start":"2026-02-20T18:29:35.804961Z","end":"2026-02-20T18:29:37.335369Z","steps":["trace[1406378292] 'process raft request' (duration: 1.529949423s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:37.335427Z","caller":"traceutil/trace.go:171","msg":"trace[1645993803] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"914.883066ms","start":"2026-02-20T18:29:36.42049Z","end":"2026-02-20T18:29:37.335373Z","steps":["trace[1645993803] 'process raft request' (duration: 914.840185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:37.335453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.805587Z","time spent":"1.52983039s","remote":"127.0.0.1:32952","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-20T18:29:37.33546Z","caller":"traceutil/trace.go:171","msg":"trace[556879982] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"1.529208757s","start":"2026-02-20T18:29:35.806205Z","end":"2026-02-20T18:29:37.335414Z","steps":["trace[556879982] 'process raft request' (duration: 1.529062834s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:37.335552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.80495Z","time spent":"1.530517383s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:37.335581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:36.420467Z","time spent":"915.027348ms","remote":"127.0.0.1:32952","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-20T18:29:37.335679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:35.806183Z","time spent":"1.52936561s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:37.549765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.537218ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:29:37.549898Z","caller":"traceutil/trace.go:171","msg":"trace[1362379173] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"162.979511ms","start":"2026-02-20T18:29:37.386898Z","end":"2026-02-20T18:29:37.549878Z","steps":["trace[1362379173] 'read index received' (duration: 44.111µs)","trace[1362379173] 'applied index is now lower than readState.Index' (duration: 162.93398ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:37.550175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.257216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T18:29:37.550256Z","caller":"traceutil/trace.go:171","msg":"trace[408915250] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:996; }","duration":"163.395349ms","start":"2026-02-20T18:29:37.386842Z","end":"2026-02-20T18:29:37.550237Z","steps":["trace[408915250] 'agreement among raft nodes before linearized reading' (duration: 163.131624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:38.873583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.585015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:38.87364Z","caller":"traceutil/trace.go:171","msg":"trace[494482251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"355.676356ms","start":"2026-02-20T18:29:38.517947Z","end":"2026-02-20T18:29:38.873624Z","steps":["trace[494482251] 'range keys from in-memory index tree' (duration: 355.468121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:38.873665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:38.517933Z","time spent":"355.727118ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:29:39.531771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.635683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:29:39.531843Z","caller":"traceutil/trace.go:171","msg":"trace[1079804165] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"192.754146ms","start":"2026-02-20T18:29:39.339072Z","end":"2026-02-20T18:29:39.531826Z","steps":["trace[1079804165] 'range keys from in-memory index tree' (duration: 192.448481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:39.531916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.665074ms","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-20T18:29:39.531989Z","caller":"traceutil/trace.go:171","msg":"trace[2108785335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"192.777676ms","start":"2026-02-20T18:29:39.339193Z","end":"2026-02-20T18:29:39.531971Z","steps":["trace[2108785335] 'range keys from in-memory index tree' (duration: 192.517491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:39.532179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.796377ms","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-20T18:29:39.532227Z","caller":"traceutil/trace.go:171","msg":"trace[1995557148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:999; }","duration":"191.884749ms","start":"2026-02-20T18:29:39.340332Z","end":"2026-02-20T18:29:39.532217Z","steps":["trace[1995557148] 'range keys from in-memory index tree' (duration: 191.667104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:39.532645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.500801ms","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-20T18:29:39.533271Z","caller":"traceutil/trace.go:171","msg":"trace[721615875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"193.043811ms","start":"2026-02-20T18:29:39.340209Z","end":"2026-02-20T18:29:39.533253Z","steps":["trace[721615875] 'range keys from in-memory index tree' (duration: 191.371309ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:40.259978Z","caller":"traceutil/trace.go:171","msg":"trace[2081496541] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"720.636081ms","start":"2026-02-20T18:29:39.539295Z","end":"2026-02-20T18:29:40.259931Z","steps":["trace[2081496541] 'process raft request' (duration: 720.415077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.260139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.539254Z","time spent":"720.798785ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:40.70515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.057833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:40.70539Z","caller":"traceutil/trace.go:171","msg":"trace[1468387861] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"1.165388095s","start":"2026-02-20T18:29:39.539964Z","end":"2026-02-20T18:29:40.705352Z","steps":["trace[1468387861] 'process raft request' (duration: 918.039187ms)","trace[1468387861] 'compare' (duration: 246.887289ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:40.705419Z","caller":"traceutil/trace.go:171","msg":"trace[1337197379] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"1.165304633s","start":"2026-02-20T18:29:39.540091Z","end":"2026-02-20T18:29:40.705396Z","steps":["trace[1337197379] 'process raft request' (duration: 1.165174191s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.705517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.540084Z","time spent":"1.165388905s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:40.705536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.539954Z","time spent":"1.165502707s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:40.705538Z","caller":"traceutil/trace.go:171","msg":"trace[2007971037] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"1.165393494s","start":"2026-02-20T18:29:39.540115Z","end":"2026-02-20T18:29:40.705509Z","steps":["trace[2007971037] 'process raft request' (duration: 1.165227401s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:40.705705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:39.5401Z","time spent":"1.165518587s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:41.176773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.316862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:41.176863Z","caller":"traceutil/trace.go:171","msg":"trace[895512769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1003; }","duration":"198.493806ms","start":"2026-02-20T18:29:40.97835Z","end":"2026-02-20T18:29:41.176843Z","steps":["trace[895512769] 'range keys from in-memory index tree' (duration: 198.106829ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:41.342541Z","caller":"traceutil/trace.go:171","msg":"trace[1791514959] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"233.928509ms","start":"2026-02-20T18:29:41.108587Z","end":"2026-02-20T18:29:41.342516Z","steps":["trace[1791514959] 'read index received' (duration: 233.796417ms)","trace[1791514959] 'applied index is now lower than readState.Index' (duration: 130.942µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:41.342578Z","caller":"traceutil/trace.go:171","msg":"trace[1238088828] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"306.347575ms","start":"2026-02-20T18:29:41.036197Z","end":"2026-02-20T18:29:41.342544Z","steps":["trace[1238088828] 'process raft request' (duration: 306.132151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:41.342684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.081272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:41.342726Z","caller":"traceutil/trace.go:171","msg":"trace[18160551] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1004; }","duration":"234.180524ms","start":"2026-02-20T18:29:41.108534Z","end":"2026-02-20T18:29:41.342714Z","steps":["trace[18160551] 'agreement among raft nodes before linearized reading' (duration: 234.076252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:41.342724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.036171Z","time spent":"306.482308ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:29:42.886505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.441420086s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:42.886686Z","caller":"traceutil/trace.go:171","msg":"trace[136599573] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"941.598461ms","start":"2026-02-20T18:29:41.94507Z","end":"2026-02-20T18:29:42.886669Z","steps":["trace[136599573] 'process raft request' (duration: 941.530149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.886754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.94505Z","time spent":"941.675303ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:42.887003Z","caller":"traceutil/trace.go:171","msg":"trace[706005133] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"1.705860933s","start":"2026-02-20T18:29:41.181125Z","end":"2026-02-20T18:29:42.886985Z","steps":["trace[706005133] 'process raft request' (duration: 263.884576ms)","trace[706005133] 'compare' (duration: 1.440637161s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:42.887088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.1811Z","time spent":"1.705945995s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:42.887202Z","caller":"traceutil/trace.go:171","msg":"trace[595119959] linearizableReadLoop","detail":"{readStateIndex:1048; appliedIndex:1047; }","duration":"1.539255211s","start":"2026-02-20T18:29:41.347932Z","end":"2026-02-20T18:29:42.887187Z","steps":["trace[595119959] 'read index received' (duration: 97.063271ms)","trace[595119959] 'applied index is now lower than readState.Index' (duration: 1.44219065s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:42.887357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539424234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:42.887402Z","caller":"traceutil/trace.go:171","msg":"trace[1180059966] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1006; }","duration":"1.539499576s","start":"2026-02-20T18:29:41.347887Z","end":"2026-02-20T18:29:42.887387Z","steps":["trace[1180059966] 'agreement among raft nodes before linearized reading' (duration: 1.539399624s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.887442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.347866Z","time spent":"1.539565707s","remote":"127.0.0.1:32786","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-20T18:29:42.887517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398161601s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:42.887606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.091578271s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-20T18:29:42.887631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.360098ms","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-20T18:29:42.887667Z","caller":"traceutil/trace.go:171","msg":"trace[365585462] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:1006; }","duration":"1.091672393s","start":"2026-02-20T18:29:41.795979Z","end":"2026-02-20T18:29:42.887651Z","steps":["trace[365585462] 'agreement among raft nodes before linearized reading' (duration: 1.09150391s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.887682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.43226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T18:29:42.887689Z","caller":"traceutil/trace.go:171","msg":"trace[991416251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1006; }","duration":"177.418899ms","start":"2026-02-20T18:29:42.710254Z","end":"2026-02-20T18:29:42.887673Z","steps":["trace[991416251] 'agreement among raft nodes before linearized reading' (duration: 177.178905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.887711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.795957Z","time spent":"1.091744224s","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-02-20T18:29:42.887725Z","caller":"traceutil/trace.go:171","msg":"trace[52465274] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1006; }","duration":"246.524172ms","start":"2026-02-20T18:29:42.641188Z","end":"2026-02-20T18:29:42.887712Z","steps":["trace[52465274] 'agreement among raft nodes before linearized reading' (duration: 246.381349ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.887747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.432121ms","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-20T18:29:42.887609Z","caller":"traceutil/trace.go:171","msg":"trace[2068687885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1006; }","duration":"1.398333854s","start":"2026-02-20T18:29:41.489253Z","end":"2026-02-20T18:29:42.887587Z","steps":["trace[2068687885] 'agreement among raft nodes before linearized reading' (duration: 1.398185101s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:42.887793Z","caller":"traceutil/trace.go:171","msg":"trace[745383885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1006; }","duration":"177.505931ms","start":"2026-02-20T18:29:42.710277Z","end":"2026-02-20T18:29:42.887783Z","steps":["trace[745383885] 'agreement among raft nodes before linearized reading' (duration: 177.380228ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.887834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:41.489226Z","time spent":"1.39859204s","remote":"127.0.0.1:60932","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-20T18:29:42.887869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.853926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-20T18:29:42.887939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.921661ms","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-20T18:29:42.88803Z","caller":"traceutil/trace.go:171","msg":"trace[1410873059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1006; }","duration":"623.0346ms","start":"2026-02-20T18:29:42.264949Z","end":"2026-02-20T18:29:42.887984Z","steps":["trace[1410873059] 'agreement among raft nodes before linearized reading' (duration: 622.496309ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:42.888086Z","caller":"traceutil/trace.go:171","msg":"trace[1478720731] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1006; }","duration":"177.213156ms","start":"2026-02-20T18:29:42.710823Z","end":"2026-02-20T18:29:42.888036Z","steps":["trace[1478720731] 'agreement among raft nodes before linearized reading' (duration: 176.742136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:42.888135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.264928Z","time spent":"623.193593ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:43.405884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.246769ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-20T18:29:43.405985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.88939Z","time spent":"516.590518ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T18:29:44.282977Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077405198s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:29:44.283933Z","caller":"traceutil/trace.go:171","msg":"trace[1742402634] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"1.390478613s","start":"2026-02-20T18:29:42.893401Z","end":"2026-02-20T18:29:44.28388Z","steps":["trace[1742402634] 'process raft request' (duration: 1.390179317s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.284008Z","caller":"traceutil/trace.go:171","msg":"trace[158321284] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.390471413s","start":"2026-02-20T18:29:42.893509Z","end":"2026-02-20T18:29:44.28398Z","steps":["trace[158321284] 'process raft request' (duration: 1.39030968s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.284021Z","caller":"traceutil/trace.go:171","msg":"trace[2060114018] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.390457823s","start":"2026-02-20T18:29:42.893546Z","end":"2026-02-20T18:29:44.284004Z","steps":["trace[2060114018] 'process raft request' (duration: 1.390376641s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.284103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893504Z","time spent":"1.390546224s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:44.284099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893384Z","time spent":"1.390629636s","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:29:44.284114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893541Z","time spent":"1.390523355s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:44.284208Z","caller":"traceutil/trace.go:171","msg":"trace[2099164954] linearizableReadLoop","detail":"{readStateIndex:1057; appliedIndex:1049; }","duration":"1.388432175s","start":"2026-02-20T18:29:42.895752Z","end":"2026-02-20T18:29:44.284184Z","steps":["trace[2099164954] 'read index received' (duration: 309.891215ms)","trace[2099164954] 'applied index is now lower than readState.Index' (duration: 1.07854003s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:44.284233Z","caller":"traceutil/trace.go:171","msg":"trace[836538325] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"1.390699118s","start":"2026-02-20T18:29:42.89352Z","end":"2026-02-20T18:29:44.284219Z","steps":["trace[836538325] 'process raft request' (duration: 1.390358931s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.284321Z","caller":"traceutil/trace.go:171","msg":"trace[1428009916] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"1.39084265s","start":"2026-02-20T18:29:42.893468Z","end":"2026-02-20T18:29:44.284311Z","steps":["trace[1428009916] 'process raft request' (duration: 1.39028546s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.284321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893507Z","time spent":"1.390770209s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:44.284372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893461Z","time spent":"1.390887171s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:29:44.284409Z","caller":"traceutil/trace.go:171","msg":"trace[2037159427] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"1.390532085s","start":"2026-02-20T18:29:42.893862Z","end":"2026-02-20T18:29:44.284394Z","steps":["trace[2037159427] 'process raft request' (duration: 1.390098787s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:44.284484Z","caller":"traceutil/trace.go:171","msg":"trace[598021801] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"1.390554415s","start":"2026-02-20T18:29:42.893902Z","end":"2026-02-20T18:29:44.284457Z","steps":["trace[598021801] 'process raft request' (duration: 1.390205489s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.284511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893855Z","time spent":"1.390583356s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:44.284517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.388761561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:44.284551Z","caller":"traceutil/trace.go:171","msg":"trace[2053075635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1013; }","duration":"1.388808762s","start":"2026-02-20T18:29:42.895734Z","end":"2026-02-20T18:29:44.284542Z","steps":["trace[2053075635] 'agreement among raft nodes before linearized reading' (duration: 1.38871404s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.284578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.895727Z","time spent":"1.388844772s","remote":"127.0.0.1:60932","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-20T18:29:44.284602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:42.893899Z","time spent":"1.390627226s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:44.315068Z","caller":"traceutil/trace.go:171","msg":"trace[1550129140] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"907.764479ms","start":"2026-02-20T18:29:43.407292Z","end":"2026-02-20T18:29:44.315057Z","steps":["trace[1550129140] 'process raft request' (duration: 907.633247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.315138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.407269Z","time spent":"907.82931ms","remote":"127.0.0.1:60960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:44.315183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.810436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:44.315259Z","caller":"traceutil/trace.go:171","msg":"trace[1793502157] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"125.934757ms","start":"2026-02-20T18:29:44.189307Z","end":"2026-02-20T18:29:44.315242Z","steps":["trace[1793502157] 'agreement among raft nodes before linearized reading' (duration: 125.733244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.315617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.847031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:44.315656Z","caller":"traceutil/trace.go:171","msg":"trace[482116462] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1014; }","duration":"504.925113ms","start":"2026-02-20T18:29:43.810721Z","end":"2026-02-20T18:29:44.315646Z","steps":["trace[482116462] 'agreement among raft nodes before linearized reading' (duration: 504.313002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:44.31568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:43.810698Z","time spent":"504.975623ms","remote":"127.0.0.1:35562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:29:46.285755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000050613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:29:46.285889Z","caller":"traceutil/trace.go:171","msg":"trace[224400864] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00037316s","start":"2026-02-20T18:29:44.285492Z","end":"2026-02-20T18:29:46.285866Z","steps":["trace[224400864] 'agreement among raft nodes before linearized reading' (duration: 2.000221017s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:46.285939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.285473Z","time spent":"2.000455921s","remote":"127.0.0.1:60948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:29:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:29:47.067306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.262458661s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:47.067493Z","caller":"traceutil/trace.go:171","msg":"trace[361963518] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"1.145728725s","start":"2026-02-20T18:29:45.921748Z","end":"2026-02-20T18:29:47.067477Z","steps":["trace[361963518] 'process raft request' (duration: 1.145659614s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:47.067562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:45.921724Z","time spent":"1.145802797s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:47.06774Z","caller":"traceutil/trace.go:171","msg":"trace[904225242] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"2.777790885s","start":"2026-02-20T18:29:44.289938Z","end":"2026-02-20T18:29:47.067729Z","steps":["trace[904225242] 'process raft request' (duration: 514.814864ms)","trace[904225242] 'compare' (duration: 2.262300328s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:47.067785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.289909Z","time spent":"2.777853637s","remote":"127.0.0.1:32778","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-20T18:29:47.067868Z","caller":"traceutil/trace.go:171","msg":"trace[1938986727] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1058; }","duration":"2.752826114s","start":"2026-02-20T18:29:44.315017Z","end":"2026-02-20T18:29:47.067843Z","steps":["trace[1938986727] 'read index received' (duration: 489.68485ms)","trace[1938986727] 'applied index is now lower than readState.Index' (duration: 2.263138794s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:47.068113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.779718773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-02-20T18:29:47.068164Z","caller":"traceutil/trace.go:171","msg":"trace[1719428133] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1016; }","duration":"2.779814714s","start":"2026-02-20T18:29:44.288323Z","end":"2026-02-20T18:29:47.068138Z","steps":["trace[1719428133] 'agreement among raft nodes before linearized reading' (duration: 2.779649561s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:47.068201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.288307Z","time spent":"2.779886316s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-20T18:29:47.568903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582725265755592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:29:48.293694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000255108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:29:48.293779Z","caller":"traceutil/trace.go:171","msg":"trace[727021941] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00037519s","start":"2026-02-20T18:29:46.293384Z","end":"2026-02-20T18:29:48.293759Z","steps":["trace[727021941] 'agreement among raft nodes before linearized reading' (duration: 2.000273148s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.293957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.293374Z","time spent":"2.000445501s","remote":"127.0.0.1:60932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:29:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:29:48.817374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087994863s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:29:48.818102Z","caller":"traceutil/trace.go:171","msg":"trace[2087816149] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"1.745350603s","start":"2026-02-20T18:29:47.072731Z","end":"2026-02-20T18:29:48.818082Z","steps":["trace[2087816149] 'process raft request' (duration: 1.744807123s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.818294Z","caller":"traceutil/trace.go:171","msg":"trace[196510394] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1060; }","duration":"1.750331529s","start":"2026-02-20T18:29:47.067935Z","end":"2026-02-20T18:29:48.818267Z","steps":["trace[196510394] 'read index received' (duration: 661.444ms)","trace[196510394] 'applied index is now lower than readState.Index' (duration: 1.088885919s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:48.818378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:47.07271Z","time spent":"1.745436516s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:48.81853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.529132772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:29:48.818597Z","caller":"traceutil/trace.go:171","msg":"trace[1103865799] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1018; }","duration":"2.529237433s","start":"2026-02-20T18:29:46.289342Z","end":"2026-02-20T18:29:48.81858Z","steps":["trace[1103865799] 'agreement among raft nodes before linearized reading' (duration: 2.528978828s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.818606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.530124881s","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-20T18:29:48.818647Z","caller":"traceutil/trace.go:171","msg":"trace[1715442942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1018; }","duration":"2.530217322s","start":"2026-02-20T18:29:46.288417Z","end":"2026-02-20T18:29:48.818634Z","steps":["trace[1715442942] 'agreement among raft nodes before linearized reading' (duration: 2.530027659s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.818652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.289334Z","time spent":"2.529309104s","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:29:48.818682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.288397Z","time spent":"2.530276934s","remote":"127.0.0.1:32952","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-20T18:29:48.818667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.52905984s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.189607d43926a6ad\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-20T18:29:48.818736Z","caller":"traceutil/trace.go:171","msg":"trace[131370132] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.189607d43926a6ad; range_end:; response_count:1; response_revision:1018; }","duration":"2.529248165s","start":"2026-02-20T18:29:46.28947Z","end":"2026-02-20T18:29:48.818718Z","steps":["trace[131370132] 'agreement among raft nodes before linearized reading' (duration: 2.528807536s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.818816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.529417217s","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-20T18:29:48.818832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.922886879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:48.818846Z","caller":"traceutil/trace.go:171","msg":"trace[870727950] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1018; }","duration":"2.529457818s","start":"2026-02-20T18:29:46.289379Z","end":"2026-02-20T18:29:48.818837Z","steps":["trace[870727950] 'agreement among raft nodes before linearized reading' (duration: 2.529379597s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.818859Z","caller":"traceutil/trace.go:171","msg":"trace[1623015863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1018; }","duration":"3.92294939s","start":"2026-02-20T18:29:44.895902Z","end":"2026-02-20T18:29:48.818851Z","steps":["trace[1623015863] 'agreement among raft nodes before linearized reading' (duration: 3.922873619s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.818873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.289374Z","time spent":"2.529493539s","remote":"127.0.0.1:32952","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-20T18:29:48.818885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.895882Z","time spent":"3.922997571s","remote":"127.0.0.1:32952","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-20T18:29:48.818958Z","caller":"traceutil/trace.go:171","msg":"trace[1519185044] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"1.200865328s","start":"2026-02-20T18:29:47.618082Z","end":"2026-02-20T18:29:48.818948Z","steps":["trace[1519185044] 'process raft request' (duration: 1.199905509s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.818935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.763105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:48.818966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.742583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:29:48.818992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.502101334s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T18:29:48.819003Z","caller":"traceutil/trace.go:171","msg":"trace[962650430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"278.775574ms","start":"2026-02-20T18:29:48.540217Z","end":"2026-02-20T18:29:48.818993Z","steps":["trace[962650430] 'agreement among raft nodes before linearized reading' (duration: 278.729403ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.819006Z","caller":"traceutil/trace.go:171","msg":"trace[1420020130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1018; }","duration":"524.882938ms","start":"2026-02-20T18:29:48.294106Z","end":"2026-02-20T18:29:48.818989Z","steps":["trace[1420020130] 'agreement among raft nodes before linearized reading' (duration: 524.756115ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:48.819036Z","caller":"traceutil/trace.go:171","msg":"trace[260930486] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1018; }","duration":"4.502158475s","start":"2026-02-20T18:29:44.316865Z","end":"2026-02-20T18:29:48.819024Z","steps":["trace[260930486] 'agreement among raft nodes before linearized reading' (duration: 4.502078904s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.819052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.294085Z","time spent":"524.959469ms","remote":"127.0.0.1:60948","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-20T18:29:48.819051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:47.618058Z","time spent":"1.200924379s","remote":"127.0.0.1:32952","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-20T18:29:48.81907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:44.316858Z","time spent":"4.502205666s","remote":"127.0.0.1:32842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T18:29:48.81927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.528607092s","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-20T18:29:48.8193Z","caller":"traceutil/trace.go:171","msg":"trace[1017446987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1018; }","duration":"2.528677602s","start":"2026-02-20T18:29:46.290612Z","end":"2026-02-20T18:29:48.81929Z","steps":["trace[1017446987] 'agreement among raft nodes before linearized reading' (duration: 2.528593641s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:48.819325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.290592Z","time spent":"2.528727554s","remote":"127.0.0.1:32952","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-20T18:29:48.818802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:46.289449Z","time spent":"2.529322416s","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.189607d43926a6ad\" "} {"level":"warn","ts":"2026-02-20T18:29:49.734542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.629561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:49.734669Z","caller":"traceutil/trace.go:171","msg":"trace[1579291410] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"444.5692ms","start":"2026-02-20T18:29:49.290079Z","end":"2026-02-20T18:29:49.734648Z","steps":["trace[1579291410] 'read index received' (duration: 45.501µs)","trace[1579291410] 'applied index is now lower than readState.Index' (duration: 444.521749ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:49.73478Z","caller":"traceutil/trace.go:171","msg":"trace[862594259] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"873.472248ms","start":"2026-02-20T18:29:48.861288Z","end":"2026-02-20T18:29:49.73476Z","steps":["trace[862594259] 'process raft request' (duration: 178.532061ms)","trace[862594259] 'compare' (duration: 694.459017ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:49.734868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:48.861266Z","time spent":"873.554929ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:49.734899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.695657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:49.734973Z","caller":"traceutil/trace.go:171","msg":"trace[1633557831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"244.80933ms","start":"2026-02-20T18:29:49.49015Z","end":"2026-02-20T18:29:49.73496Z","steps":["trace[1633557831] 'agreement among raft nodes before linearized reading' (duration: 244.649697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:49.735507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.421746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-20T18:29:49.735581Z","caller":"traceutil/trace.go:171","msg":"trace[2066414737] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1025; }","duration":"445.548248ms","start":"2026-02-20T18:29:49.290016Z","end":"2026-02-20T18:29:49.735564Z","steps":["trace[2066414737] 'agreement among raft nodes before linearized reading' (duration: 445.381235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:49.73566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:49.289992Z","time spent":"445.65492ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-20T18:29:49.933891Z","caller":"traceutil/trace.go:171","msg":"trace[709817670] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"193.552521ms","start":"2026-02-20T18:29:49.740313Z","end":"2026-02-20T18:29:49.933865Z","steps":["trace[709817670] 'process raft request' (duration: 142.66882ms)","trace[709817670] 'compare' (duration: 50.772979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:52.951722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.983968ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:52.951955Z","caller":"traceutil/trace.go:171","msg":"trace[1160139117] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"692.226875ms","start":"2026-02-20T18:29:52.259691Z","end":"2026-02-20T18:29:52.951918Z","steps":["trace[1160139117] 'process raft request' (duration: 80.967462ms)","trace[1160139117] 'compare' (duration: 610.789253ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:52.952152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:52.259679Z","time spent":"692.367387ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:52.952188Z","caller":"traceutil/trace.go:171","msg":"trace[1185805339] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"456.179782ms","start":"2026-02-20T18:29:52.495645Z","end":"2026-02-20T18:29:52.951825Z","steps":["trace[1185805339] 'read index received' (duration: 36.95µs)","trace[1185805339] 'applied index is now lower than readState.Index' (duration: 456.141622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:52.952311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.659283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:52.952349Z","caller":"traceutil/trace.go:171","msg":"trace[1132874535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"456.729504ms","start":"2026-02-20T18:29:52.495609Z","end":"2026-02-20T18:29:52.952338Z","steps":["trace[1132874535] 'agreement among raft nodes before linearized reading' (duration: 456.665893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:52.95238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:52.495592Z","time spent":"456.780754ms","remote":"127.0.0.1:60932","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-20T18:29:52.952579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.376471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-02-20T18:29:52.952627Z","caller":"traceutil/trace.go:171","msg":"trace[1986354389] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1039; }","duration":"410.448722ms","start":"2026-02-20T18:29:52.542166Z","end":"2026-02-20T18:29:52.952615Z","steps":["trace[1986354389] 'agreement among raft nodes before linearized reading' (duration: 410.261528ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:52.952661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:52.542152Z","time spent":"410.501063ms","remote":"127.0.0.1:32778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":137,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:29:55.12114Z","caller":"traceutil/trace.go:171","msg":"trace[313991126] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1092; }","duration":"156.446376ms","start":"2026-02-20T18:29:54.96467Z","end":"2026-02-20T18:29:55.121116Z","steps":["trace[313991126] 'read index received' (duration: 156.343324ms)","trace[313991126] 'applied index is now lower than readState.Index' (duration: 101.772µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:55.121325Z","caller":"traceutil/trace.go:171","msg":"trace[214434770] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"156.793152ms","start":"2026-02-20T18:29:54.96452Z","end":"2026-02-20T18:29:55.121313Z","steps":["trace[214434770] 'process raft request' (duration: 156.448415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.121546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.854824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-20T18:29:55.121624Z","caller":"traceutil/trace.go:171","msg":"trace[41397517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1047; }","duration":"156.961595ms","start":"2026-02-20T18:29:54.964644Z","end":"2026-02-20T18:29:55.121605Z","steps":["trace[41397517] 'agreement among raft nodes before linearized reading' (duration: 156.770922ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:55.138038Z","caller":"traceutil/trace.go:171","msg":"trace[1136090988] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"172.481754ms","start":"2026-02-20T18:29:54.965514Z","end":"2026-02-20T18:29:55.137996Z","steps":["trace[1136090988] 'process raft request' (duration: 172.323891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.138205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.986394ms","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-20T18:29:55.13826Z","caller":"traceutil/trace.go:171","msg":"trace[450786258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1048; }","duration":"173.090757ms","start":"2026-02-20T18:29:54.965156Z","end":"2026-02-20T18:29:55.138247Z","steps":["trace[450786258] 'agreement among raft nodes before linearized reading' (duration: 172.813562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.138676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.212628ms","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-20T18:29:55.138727Z","caller":"traceutil/trace.go:171","msg":"trace[463672465] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1048; }","duration":"173.32289ms","start":"2026-02-20T18:29:54.965393Z","end":"2026-02-20T18:29:55.138716Z","steps":["trace[463672465] 'agreement among raft nodes before linearized reading' (duration: 173.076635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.45099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.982684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:55.45118Z","caller":"traceutil/trace.go:171","msg":"trace[998662707] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"307.644441ms","start":"2026-02-20T18:29:55.143517Z","end":"2026-02-20T18:29:55.451161Z","steps":["trace[998662707] 'process raft request' (duration: 93.414671ms)","trace[998662707] 'compare' (duration: 213.74702ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:55.451262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.143495Z","time spent":"307.727632ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:55.451406Z","caller":"traceutil/trace.go:171","msg":"trace[810565996] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"307.05456ms","start":"2026-02-20T18:29:55.14432Z","end":"2026-02-20T18:29:55.451375Z","steps":["trace[810565996] 'process raft request' (duration: 306.769383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:55.451616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:55.144298Z","time spent":"307.191182ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:55.898591Z","caller":"traceutil/trace.go:171","msg":"trace[392922160] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"181.676622ms","start":"2026-02-20T18:29:55.716881Z","end":"2026-02-20T18:29:55.898557Z","steps":["trace[392922160] 'process raft request' (duration: 181.467218ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:57.920894Z","caller":"traceutil/trace.go:171","msg":"trace[1308802647] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"417.353295ms","start":"2026-02-20T18:29:57.503501Z","end":"2026-02-20T18:29:57.920854Z","steps":["trace[1308802647] 'process raft request' (duration: 344.9455ms)","trace[1308802647] 'compare' (duration: 72.277332ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:57.921086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:57.503483Z","time spent":"417.509078ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:58.812989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.547563ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-20T18:29:58.813073Z","caller":"traceutil/trace.go:171","msg":"trace[67527204] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"322.878134ms","start":"2026-02-20T18:29:58.490179Z","end":"2026-02-20T18:29:58.813057Z","steps":["trace[67527204] 'read index received' (duration: 22.206708ms)","trace[67527204] 'applied index is now lower than readState.Index' (duration: 300.670316ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:58.813129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.952105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:58.813153Z","caller":"traceutil/trace.go:171","msg":"trace[1428650399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1078; }","duration":"323.026227ms","start":"2026-02-20T18:29:58.490118Z","end":"2026-02-20T18:29:58.813144Z","steps":["trace[1428650399] 'agreement among raft nodes before linearized reading' (duration: 322.975706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:58.813185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.4901Z","time spent":"323.079718ms","remote":"127.0.0.1:60932","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-20T18:29:59.169531Z","caller":"traceutil/trace.go:171","msg":"trace[517913129] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"330.413429ms","start":"2026-02-20T18:29:58.839084Z","end":"2026-02-20T18:29:59.169497Z","steps":["trace[517913129] 'process raft request' (duration: 330.200415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.169729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.839066Z","time spent":"330.561712ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:59.38852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.558007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:29:59.388625Z","caller":"traceutil/trace.go:171","msg":"trace[467924301] linearizableReadLoop","detail":"{readStateIndex:1127; appliedIndex:1125; }","duration":"517.432555ms","start":"2026-02-20T18:29:58.871174Z","end":"2026-02-20T18:29:59.388607Z","steps":["trace[467924301] 'read index received' (duration: 298.161967ms)","trace[467924301] 'applied index is now lower than readState.Index' (duration: 219.269348ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:29:59.388705Z","caller":"traceutil/trace.go:171","msg":"trace[162679594] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"531.325033ms","start":"2026-02-20T18:29:58.857349Z","end":"2026-02-20T18:29:59.388674Z","steps":["trace[162679594] 'process raft request' (duration: 386.526771ms)","trace[162679594] 'compare' (duration: 144.362813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.388865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.857332Z","time spent":"531.461605ms","remote":"127.0.0.1:32952","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-20T18:29:59.38897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.792071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:29:59.389065Z","caller":"traceutil/trace.go:171","msg":"trace[1545103265] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1080; }","duration":"517.912344ms","start":"2026-02-20T18:29:58.871119Z","end":"2026-02-20T18:29:59.389032Z","steps":["trace[1545103265] 'agreement among raft nodes before linearized reading' (duration: 517.664369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.389116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:58.871095Z","time spent":"518.010076ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-20T18:29:59.389215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.169519ms","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-20T18:29:59.389301Z","caller":"traceutil/trace.go:171","msg":"trace[1135678821] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1080; }","duration":"254.307321ms","start":"2026-02-20T18:29:59.134975Z","end":"2026-02-20T18:29:59.389282Z","steps":["trace[1135678821] 'agreement among raft nodes before linearized reading' (duration: 254.077257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.389584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.839406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-20T18:29:59.389676Z","caller":"traceutil/trace.go:171","msg":"trace[1898610154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"235.985878ms","start":"2026-02-20T18:29:59.153665Z","end":"2026-02-20T18:29:59.389651Z","steps":["trace[1898610154] 'agreement among raft nodes before linearized reading' (duration: 235.518209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.38977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.152251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:29:59.389902Z","caller":"traceutil/trace.go:171","msg":"trace[1837915746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1080; }","duration":"236.486309ms","start":"2026-02-20T18:29:59.153384Z","end":"2026-02-20T18:29:59.389871Z","steps":["trace[1837915746] 'agreement among raft nodes before linearized reading' (duration: 235.881527ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.968749Z","caller":"traceutil/trace.go:171","msg":"trace[1737386674] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"572.552067ms","start":"2026-02-20T18:29:59.396171Z","end":"2026-02-20T18:29:59.968723Z","steps":["trace[1737386674] 'process raft request' (duration: 488.392095ms)","trace[1737386674] 'compare' (duration: 83.915387ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.968902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.396135Z","time spent":"572.685599ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:59.995557Z","caller":"traceutil/trace.go:171","msg":"trace[1497205945] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"598.497096ms","start":"2026-02-20T18:29:59.397037Z","end":"2026-02-20T18:29:59.995535Z","steps":["trace[1497205945] 'process raft request' (duration: 598.423915ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:29:59.995639Z","caller":"traceutil/trace.go:171","msg":"trace[1257202019] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1127; }","duration":"504.515914ms","start":"2026-02-20T18:29:59.491114Z","end":"2026-02-20T18:29:59.99563Z","steps":["trace[1257202019] 'read index received' (duration: 393.464804ms)","trace[1257202019] 'applied index is now lower than readState.Index' (duration: 111.05023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:29:59.995688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.397022Z","time spent":"598.597708ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:59.995706Z","caller":"traceutil/trace.go:171","msg":"trace[1386520046] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"596.973927ms","start":"2026-02-20T18:29:59.398711Z","end":"2026-02-20T18:29:59.995685Z","steps":["trace[1386520046] 'process raft request' (duration: 596.789694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.995727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.610377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:29:59.995757Z","caller":"traceutil/trace.go:171","msg":"trace[768951876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1084; }","duration":"504.676327ms","start":"2026-02-20T18:29:59.491071Z","end":"2026-02-20T18:29:59.995747Z","steps":["trace[768951876] 'agreement among raft nodes before linearized reading' (duration: 504.622816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.995791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.491052Z","time spent":"504.732319ms","remote":"127.0.0.1:60932","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-20T18:29:59.995803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.398706Z","time spent":"597.045788ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:29:59.995569Z","caller":"traceutil/trace.go:171","msg":"trace[672608263] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"599.1719ms","start":"2026-02-20T18:29:59.396379Z","end":"2026-02-20T18:29:59.995551Z","steps":["trace[672608263] 'process raft request' (duration: 598.991266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.995975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.396368Z","time spent":"599.545316ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:29:59.996018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.287232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:29:59.99627Z","caller":"traceutil/trace.go:171","msg":"trace[1039541589] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1084; }","duration":"282.571447ms","start":"2026-02-20T18:29:59.713675Z","end":"2026-02-20T18:29:59.996246Z","steps":["trace[1039541589] 'agreement among raft nodes before linearized reading' (duration: 282.295222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.996027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.359499ms","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-20T18:29:59.996381Z","caller":"traceutil/trace.go:171","msg":"trace[1933581968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"467.835508ms","start":"2026-02-20T18:29:59.528535Z","end":"2026-02-20T18:29:59.996371Z","steps":["trace[1933581968] 'agreement among raft nodes before linearized reading' (duration: 467.215295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:29:59.996414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:29:59.528515Z","time spent":"467.888819ms","remote":"127.0.0.1:32952","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-20T18:30:00.150417Z","caller":"traceutil/trace.go:171","msg":"trace[2094160014] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"149.721577ms","start":"2026-02-20T18:30:00.000662Z","end":"2026-02-20T18:30:00.150384Z","steps":["trace[2094160014] 'process raft request' (duration: 101.458006ms)","trace[2094160014] 'compare' (duration: 48.056007ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:00.150629Z","caller":"traceutil/trace.go:171","msg":"trace[705807192] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1131; }","duration":"116.816822ms","start":"2026-02-20T18:30:00.033785Z","end":"2026-02-20T18:30:00.150602Z","steps":["trace[705807192] 'read index received' (duration: 68.353377ms)","trace[705807192] 'applied index is now lower than readState.Index' (duration: 48.462535ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:00.150672Z","caller":"traceutil/trace.go:171","msg":"trace[221453579] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"148.447392ms","start":"2026-02-20T18:30:00.002201Z","end":"2026-02-20T18:30:00.150648Z","steps":["trace[221453579] 'process raft request' (duration: 148.274859ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:00.150707Z","caller":"traceutil/trace.go:171","msg":"trace[166215491] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"148.816119ms","start":"2026-02-20T18:30:00.001875Z","end":"2026-02-20T18:30:00.150691Z","steps":["trace[166215491] 'process raft request' (duration: 148.424291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:00.150813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.950174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:00.150846Z","caller":"traceutil/trace.go:171","msg":"trace[139234493] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1087; }","duration":"117.095447ms","start":"2026-02-20T18:30:00.033743Z","end":"2026-02-20T18:30:00.150838Z","steps":["trace[139234493] 'agreement among raft nodes before linearized reading' (duration: 116.952985ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:17.229795Z","caller":"traceutil/trace.go:171","msg":"trace[655414401] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"443.558108ms","start":"2026-02-20T18:30:16.78621Z","end":"2026-02-20T18:30:17.229768Z","steps":["trace[655414401] 'process raft request' (duration: 443.387085ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:17.229983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:16.786184Z","time spent":"443.695671ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:20.29354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.155724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:20.293758Z","caller":"traceutil/trace.go:171","msg":"trace[423077958] linearizableReadLoop","detail":"{readStateIndex:1217; appliedIndex:1214; }","duration":"160.347097ms","start":"2026-02-20T18:30:20.133393Z","end":"2026-02-20T18:30:20.29374Z","steps":["trace[423077958] 'read index received' (duration: 43.927427ms)","trace[423077958] 'applied index is now lower than readState.Index' (duration: 116.41856ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:20.29402Z","caller":"traceutil/trace.go:171","msg":"trace[1144602442] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"161.599742ms","start":"2026-02-20T18:30:20.132407Z","end":"2026-02-20T18:30:20.294006Z","steps":["trace[1144602442] 'process raft request' (duration: 44.837436ms)","trace[1144602442] 'compare' (duration: 116.043221ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:20.294201Z","caller":"traceutil/trace.go:171","msg":"trace[545453577] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"161.129281ms","start":"2026-02-20T18:30:20.133062Z","end":"2026-02-20T18:30:20.294192Z","steps":["trace[545453577] 'process raft request' (duration: 160.58587ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:20.294416Z","caller":"traceutil/trace.go:171","msg":"trace[370821829] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"161.225453ms","start":"2026-02-20T18:30:20.133176Z","end":"2026-02-20T18:30:20.294401Z","steps":["trace[370821829] 'process raft request' (duration: 160.52359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:20.294639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.239203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-02-20T18:30:20.294692Z","caller":"traceutil/trace.go:171","msg":"trace[1518556796] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1166; }","duration":"161.289774ms","start":"2026-02-20T18:30:20.133385Z","end":"2026-02-20T18:30:20.294675Z","steps":["trace[1518556796] 'agreement among raft nodes before linearized reading' (duration: 161.199133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:20.295008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.285135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:16843"} {"level":"info","ts":"2026-02-20T18:30:20.295055Z","caller":"traceutil/trace.go:171","msg":"trace[1433333974] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1166; }","duration":"161.346505ms","start":"2026-02-20T18:30:20.133697Z","end":"2026-02-20T18:30:20.295043Z","steps":["trace[1433333974] 'agreement among raft nodes before linearized reading' (duration: 161.185642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:20.295899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.327858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-20T18:30:20.295952Z","caller":"traceutil/trace.go:171","msg":"trace[1574817582] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1166; }","duration":"109.4324ms","start":"2026-02-20T18:30:20.186507Z","end":"2026-02-20T18:30:20.29594Z","steps":["trace[1574817582] 'agreement among raft nodes before linearized reading' (duration: 109.316488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:42.015229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.172201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:42.015364Z","caller":"traceutil/trace.go:171","msg":"trace[710694728] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"190.750456ms","start":"2026-02-20T18:30:41.824589Z","end":"2026-02-20T18:30:42.01534Z","steps":["trace[710694728] 'process raft request' (duration: 65.384481ms)","trace[710694728] 'compare' (duration: 125.028579ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:44.617256Z","caller":"traceutil/trace.go:171","msg":"trace[1721014568] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"163.117721ms","start":"2026-02-20T18:30:44.454098Z","end":"2026-02-20T18:30:44.617216Z","steps":["trace[1721014568] 'process raft request' (duration: 136.358273ms)","trace[1721014568] 'compare' (duration: 26.613896ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:44.664012Z","caller":"traceutil/trace.go:171","msg":"trace[1652388867] linearizableReadLoop","detail":"{readStateIndex:1323; appliedIndex:1320; }","duration":"151.99416ms","start":"2026-02-20T18:30:44.511982Z","end":"2026-02-20T18:30:44.663977Z","steps":["trace[1652388867] 'read index received' (duration: 78.658283ms)","trace[1652388867] 'applied index is now lower than readState.Index' (duration: 73.334167ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:30:44.664107Z","caller":"traceutil/trace.go:171","msg":"trace[342892894] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"208.537357ms","start":"2026-02-20T18:30:44.455544Z","end":"2026-02-20T18:30:44.664081Z","steps":["trace[342892894] 'process raft request' (duration: 208.372104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:44.664309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.98529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:30:44.664366Z","caller":"traceutil/trace.go:171","msg":"trace[589617569] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1267; }","duration":"152.125903ms","start":"2026-02-20T18:30:44.512224Z","end":"2026-02-20T18:30:44.66435Z","steps":["trace[589617569] 'agreement among raft nodes before linearized reading' (duration: 151.95659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:44.664412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.411648ms","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-20T18:30:44.664566Z","caller":"traceutil/trace.go:171","msg":"trace[1599622709] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1267; }","duration":"152.562852ms","start":"2026-02-20T18:30:44.511971Z","end":"2026-02-20T18:30:44.664533Z","steps":["trace[1599622709] 'agreement among raft nodes before linearized reading' (duration: 152.195554ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:44.664755Z","caller":"traceutil/trace.go:171","msg":"trace[563044008] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"208.609519ms","start":"2026-02-20T18:30:44.455399Z","end":"2026-02-20T18:30:44.664008Z","steps":["trace[563044008] 'process raft request' (duration: 208.333604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:30:45.081328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.746132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:30:45.081443Z","caller":"traceutil/trace.go:171","msg":"trace[702017541] linearizableReadLoop","detail":"{readStateIndex:1324; appliedIndex:1323; }","duration":"147.205706ms","start":"2026-02-20T18:30:44.934218Z","end":"2026-02-20T18:30:45.081424Z","steps":["trace[702017541] 'read index received' (duration: 43.151µs)","trace[702017541] 'applied index is now lower than readState.Index' (duration: 147.160965ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:45.081522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.300858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:45.081548Z","caller":"traceutil/trace.go:171","msg":"trace[79214101] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1268; }","duration":"147.362119ms","start":"2026-02-20T18:30:44.934177Z","end":"2026-02-20T18:30:45.081539Z","steps":["trace[79214101] 'agreement among raft nodes before linearized reading' (duration: 147.295538ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:30:45.081714Z","caller":"traceutil/trace.go:171","msg":"trace[341586577] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"411.7819ms","start":"2026-02-20T18:30:44.669889Z","end":"2026-02-20T18:30:45.081671Z","steps":["trace[341586577] 'process raft request' (duration: 47.595379ms)","trace[341586577] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 363.456407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:30:45.081906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:30:44.669862Z","time spent":"411.937794ms","remote":"127.0.0.1:32952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:30:45.600889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.862968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:30:45.600966Z","caller":"traceutil/trace.go:171","msg":"trace[110672225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1269; }","duration":"112.97539ms","start":"2026-02-20T18:30:45.487972Z","end":"2026-02-20T18:30:45.600947Z","steps":["trace[110672225] 'range keys from in-memory index tree' (duration: 112.785027ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:03.378894Z","caller":"traceutil/trace.go:171","msg":"trace[125239714] linearizableReadLoop","detail":"{readStateIndex:1403; appliedIndex:1402; }","duration":"150.4064ms","start":"2026-02-20T18:31:03.228466Z","end":"2026-02-20T18:31:03.378873Z","steps":["trace[125239714] 'read index received' (duration: 91.462496ms)","trace[125239714] 'applied index is now lower than readState.Index' (duration: 58.943104ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:03.379032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.546902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:31:03.379061Z","caller":"traceutil/trace.go:171","msg":"trace[53910121] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1343; }","duration":"150.628204ms","start":"2026-02-20T18:31:03.228425Z","end":"2026-02-20T18:31:03.379053Z","steps":["trace[53910121] 'agreement among raft nodes before linearized reading' (duration: 150.526912ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:31:03.379192Z","caller":"traceutil/trace.go:171","msg":"trace[381148057] transaction","detail":"{read_only:false; response_revision:1343; number_of_response:1; }","duration":"207.608698ms","start":"2026-02-20T18:31:03.171552Z","end":"2026-02-20T18:31:03.379161Z","steps":["trace[381148057] 'process raft request' (duration: 148.39691ms)","trace[381148057] 'compare' (duration: 58.834891ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:12.904729Z","caller":"traceutil/trace.go:171","msg":"trace[286566301] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"131.581808ms","start":"2026-02-20T18:31:12.773103Z","end":"2026-02-20T18:31:12.904685Z","steps":["trace[286566301] 'process raft request' (duration: 87.269744ms)","trace[286566301] 'compare' (duration: 44.10741ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:31:27.166752Z","caller":"traceutil/trace.go:171","msg":"trace[1596824347] linearizableReadLoop","detail":"{readStateIndex:1500; appliedIndex:1499; }","duration":"112.512806ms","start":"2026-02-20T18:31:27.054212Z","end":"2026-02-20T18:31:27.166724Z","steps":["trace[1596824347] 'read index received' (duration: 38.987881ms)","trace[1596824347] 'applied index is now lower than readState.Index' (duration: 73.523115ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:31:27.166953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.714741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-20T18:31:27.167018Z","caller":"traceutil/trace.go:171","msg":"trace[1015029317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1435; }","duration":"112.848663ms","start":"2026-02-20T18:31:27.054159Z","end":"2026-02-20T18:31:27.167007Z","steps":["trace[1015029317] 'agreement among raft nodes before linearized reading' (duration: 112.621708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:27.167152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.943947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-20T18:31:27.167219Z","caller":"traceutil/trace.go:171","msg":"trace[1265006417] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1435; }","duration":"109.045039ms","start":"2026-02-20T18:31:27.058152Z","end":"2026-02-20T18:31:27.167197Z","steps":["trace[1265006417] 'agreement among raft nodes before linearized reading' (duration: 108.813235ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:31:53.016884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.026092ms","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-20T18:31:53.017072Z","caller":"traceutil/trace.go:171","msg":"trace[556266204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1542; }","duration":"100.268727ms","start":"2026-02-20T18:31:52.916775Z","end":"2026-02-20T18:31:53.017043Z","steps":["trace[556266204] 'range keys from in-memory index tree' (duration: 99.830148ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:32:11.01314Z","caller":"traceutil/trace.go:171","msg":"trace[127218741] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"112.865823ms","start":"2026-02-20T18:32:10.900241Z","end":"2026-02-20T18:32:11.013107Z","steps":["trace[127218741] 'process raft request' (duration: 112.692939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:03.687273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.640737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:33:03.687408Z","caller":"traceutil/trace.go:171","msg":"trace[1066082342] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1939; }","duration":"196.595279ms","start":"2026-02-20T18:33:03.49078Z","end":"2026-02-20T18:33:03.687375Z","steps":["trace[1066082342] 'read index received' (duration: 38.231µs)","trace[1066082342] 'applied index is now lower than readState.Index' (duration: 196.555848ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:03.687558Z","caller":"traceutil/trace.go:171","msg":"trace[653674413] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"290.703708ms","start":"2026-02-20T18:33:03.39679Z","end":"2026-02-20T18:33:03.687494Z","steps":["trace[653674413] 'process raft request' (duration: 49.725105ms)","trace[653674413] 'compare' (duration: 240.504405ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:33:03.687681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.442738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-20T18:33:03.687721Z","caller":"traceutil/trace.go:171","msg":"trace[1571004779] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1855; }","duration":"169.511008ms","start":"2026-02-20T18:33:03.518201Z","end":"2026-02-20T18:33:03.687712Z","steps":["trace[1571004779] 'agreement among raft nodes before linearized reading' (duration: 169.358035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:03.687844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.040447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:33:03.687941Z","caller":"traceutil/trace.go:171","msg":"trace[915568954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1855; }","duration":"197.19516ms","start":"2026-02-20T18:33:03.49072Z","end":"2026-02-20T18:33:03.687915Z","steps":["trace[915568954] 'agreement among raft nodes before linearized reading' (duration: 196.937285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:12.097908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.789008ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-02-20T18:33:12.098052Z","caller":"traceutil/trace.go:171","msg":"trace[185804857] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"215.952472ms","start":"2026-02-20T18:33:11.882081Z","end":"2026-02-20T18:33:12.098033Z","steps":["trace[185804857] 'read index received' (duration: 76.93881ms)","trace[185804857] 'applied index is now lower than readState.Index' (duration: 139.012252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:33:12.098514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.433421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-20T18:33:12.098558Z","caller":"traceutil/trace.go:171","msg":"trace[1997717408] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1893; }","duration":"216.531464ms","start":"2026-02-20T18:33:11.882013Z","end":"2026-02-20T18:33:12.098544Z","steps":["trace[1997717408] 'agreement among raft nodes before linearized reading' (duration: 216.37244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:12.099693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.605386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-20T18:33:12.099855Z","caller":"traceutil/trace.go:171","msg":"trace[1160064076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1893; }","duration":"201.82169ms","start":"2026-02-20T18:33:11.89799Z","end":"2026-02-20T18:33:12.099812Z","steps":["trace[1160064076] 'agreement among raft nodes before linearized reading' (duration: 201.373812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:12.100286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.638438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-20T18:33:12.100725Z","caller":"traceutil/trace.go:171","msg":"trace[726021015] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1893; }","duration":"148.833392ms","start":"2026-02-20T18:33:11.951547Z","end":"2026-02-20T18:33:12.10038Z","steps":["trace[726021015] 'agreement among raft nodes before linearized reading' (duration: 148.283941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:12.37547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.743197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:33:12.375603Z","caller":"traceutil/trace.go:171","msg":"trace[1589145663] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"275.985116ms","start":"2026-02-20T18:33:12.099592Z","end":"2026-02-20T18:33:12.375577Z","steps":["trace[1589145663] 'process raft request' (duration: 124.993184ms)","trace[1589145663] 'compare' (duration: 150.593024ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:12.38709Z","caller":"traceutil/trace.go:171","msg":"trace[2087119500] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"283.311387ms","start":"2026-02-20T18:33:12.103759Z","end":"2026-02-20T18:33:12.38707Z","steps":["trace[2087119500] 'process raft request' (duration: 283.045201ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:12.387099Z","caller":"traceutil/trace.go:171","msg":"trace[201724298] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"281.716827ms","start":"2026-02-20T18:33:12.105354Z","end":"2026-02-20T18:33:12.387071Z","steps":["trace[201724298] 'process raft request' (duration: 281.635405ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:12.387338Z","caller":"traceutil/trace.go:171","msg":"trace[1084083479] linearizableReadLoop","detail":"{readStateIndex:1984; appliedIndex:1980; }","duration":"194.429988ms","start":"2026-02-20T18:33:12.192867Z","end":"2026-02-20T18:33:12.387297Z","steps":["trace[1084083479] 'read index received' (duration: 31.74368ms)","trace[1084083479] 'applied index is now lower than readState.Index' (duration: 162.683738ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:12.387411Z","caller":"traceutil/trace.go:171","msg":"trace[1306064138] transaction","detail":"{read_only:false; response_revision:1897; number_of_response:1; }","duration":"280.957562ms","start":"2026-02-20T18:33:12.106385Z","end":"2026-02-20T18:33:12.387343Z","steps":["trace[1306064138] 'process raft request' (duration: 280.646236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:12.387589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.717403ms","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-20T18:33:12.38764Z","caller":"traceutil/trace.go:171","msg":"trace[1820300189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1897; }","duration":"194.827595ms","start":"2026-02-20T18:33:12.192801Z","end":"2026-02-20T18:33:12.387628Z","steps":["trace[1820300189] 'agreement among raft nodes before linearized reading' (duration: 194.615111ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:46.192845Z","caller":"traceutil/trace.go:171","msg":"trace[354741097] linearizableReadLoop","detail":"{readStateIndex:2136; appliedIndex:2135; }","duration":"180.102652ms","start":"2026-02-20T18:33:46.012707Z","end":"2026-02-20T18:33:46.192809Z","steps":["trace[354741097] 'read index received' (duration: 179.854138ms)","trace[354741097] 'applied index is now lower than readState.Index' (duration: 247.164µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:46.192882Z","caller":"traceutil/trace.go:171","msg":"trace[2064304191] transaction","detail":"{read_only:false; response_revision:2042; number_of_response:1; }","duration":"185.914405ms","start":"2026-02-20T18:33:46.00694Z","end":"2026-02-20T18:33:46.192854Z","steps":["trace[2064304191] 'process raft request' (duration: 185.716931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:33:46.193126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.400468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-66wmq\" ","response":"range_response_count:1 size:13268"} {"level":"info","ts":"2026-02-20T18:33:46.193172Z","caller":"traceutil/trace.go:171","msg":"trace[789492794] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-66wmq; range_end:; response_count:1; response_revision:2042; }","duration":"180.477509ms","start":"2026-02-20T18:33:46.012681Z","end":"2026-02-20T18:33:46.193159Z","steps":["trace[789492794] 'agreement among raft nodes before linearized reading' (duration: 180.245854ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:33:46.731383Z","caller":"traceutil/trace.go:171","msg":"trace[831110400] transaction","detail":"{read_only:false; response_revision:2046; number_of_response:1; }","duration":"127.717505ms","start":"2026-02-20T18:33:46.603626Z","end":"2026-02-20T18:33:46.731344Z","steps":["trace[831110400] 'process raft request' (duration: 95.71982ms)","trace[831110400] 'compare' (duration: 31.688569ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:33:47.733379Z","caller":"traceutil/trace.go:171","msg":"trace[405885809] transaction","detail":"{read_only:false; response_revision:2047; number_of_response:1; }","duration":"108.13339ms","start":"2026-02-20T18:33:47.625213Z","end":"2026-02-20T18:33:47.733346Z","steps":["trace[405885809] 'process raft request' (duration: 107.903265ms)"],"step_count":1}