{"level":"warn","ts":"2026-02-17T19:46:07.054978Z","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-17T19:46:07.055229Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195: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.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-17T19:46:07.05536Z","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-17T19:46:07.055446Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-17T19:46:07.055491Z","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-17T19:46:07.05669Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-02-17T19:46:07.056859Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-17T19:46:07.060592Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.411224ms"} {"level":"info","ts":"2026-02-17T19:46:07.065652Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-02-17T19:46:07.065758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T19:46:07.065817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-02-17T19:46:07.065854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T19:46:07.065916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-02-17T19:46:07.065968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-02-17T19:46:07.075876Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T19:46:07.089683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T19:46:07.12046Z","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-17T19:46:07.122381Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T19:46:07.124661Z","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-17T19:46:07.126717Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T19:46:07.126809Z","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-17T19:46:07.128105Z","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-17T19:46:07.128186Z","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-17T19:46:07.127369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-02-17T19:46:07.128483Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-02-17T19:46:07.127443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-17T19:46:07.131922Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-02-17T19:46:07.132549Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T19:46:07.133046Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T19:46:07.766316Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T19:46:07.766393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T19:46:07.766412Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-02-17T19:46:07.766423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-02-17T19:46:07.766428Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-17T19:46:07.766436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-02-17T19:46:07.766443Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-02-17T19:46:07.773043Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T19:46:07.773064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:46:07.773851Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:46:07.777322Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T19:46:07.778332Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T19:46:07.778352Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T19:46:07.778427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:46:07.778559Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:46:07.778581Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T19:46:07.788669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T19:46:07.808676Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-02-17T19:46:27.623387Z","caller":"traceutil/trace.go:171","msg":"trace[2066585267] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"161.180059ms","start":"2026-02-17T19:46:27.462193Z","end":"2026-02-17T19:46:27.623373Z","steps":["trace[2066585267] 'process raft request' (duration: 160.788912ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:33.775469Z","caller":"traceutil/trace.go:171","msg":"trace[745542051] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"123.370324ms","start":"2026-02-17T19:46:33.652074Z","end":"2026-02-17T19:46:33.775445Z","steps":["trace[745542051] 'process raft request' (duration: 123.241262ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:33.776158Z","caller":"traceutil/trace.go:171","msg":"trace[1406783057] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:489; }","duration":"123.503287ms","start":"2026-02-17T19:46:33.652643Z","end":"2026-02-17T19:46:33.776146Z","steps":["trace[1406783057] 'read index received' (duration: 123.499127ms)","trace[1406783057] 'applied index is now lower than readState.Index' (duration: 3.4µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:46:33.776252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.595009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:46:33.776283Z","caller":"traceutil/trace.go:171","msg":"trace[468101544] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:472; }","duration":"123.648679ms","start":"2026-02-17T19:46:33.652629Z","end":"2026-02-17T19:46:33.776278Z","steps":["trace[468101544] 'agreement among raft nodes before linearized reading' (duration: 123.569448ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:33.840021Z","caller":"traceutil/trace.go:171","msg":"trace[2036826752] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"128.488085ms","start":"2026-02-17T19:46:33.711517Z","end":"2026-02-17T19:46:33.840006Z","steps":["trace[2036826752] 'process raft request' (duration: 128.394574ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:37.848101Z","caller":"traceutil/trace.go:171","msg":"trace[1493351901] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"100.928942ms","start":"2026-02-17T19:46:37.747116Z","end":"2026-02-17T19:46:37.848045Z","steps":["trace[1493351901] 'process raft request' (duration: 100.769839ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:38.296Z","caller":"traceutil/trace.go:171","msg":"trace[1298204577] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"406.063923ms","start":"2026-02-17T19:46:37.889917Z","end":"2026-02-17T19:46:38.295981Z","steps":["trace[1298204577] 'read index received' (duration: 400.388221ms)","trace[1298204577] 'applied index is now lower than readState.Index' (duration: 5.675092ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:46:38.296157Z","caller":"traceutil/trace.go:171","msg":"trace[1091549700] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"442.532942ms","start":"2026-02-17T19:46:37.853617Z","end":"2026-02-17T19:46:38.29615Z","steps":["trace[1091549700] 'process raft request' (duration: 436.689957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:38.296372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.021405ms","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-17T19:46:38.296418Z","caller":"traceutil/trace.go:171","msg":"trace[372859502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"391.096887ms","start":"2026-02-17T19:46:37.905308Z","end":"2026-02-17T19:46:38.296405Z","steps":["trace[372859502] 'agreement among raft nodes before linearized reading' (duration: 390.966615ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:38.296451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:37.905293Z","time spent":"391.152378ms","remote":"127.0.0.1:40978","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-17T19:46:38.296586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.668165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:46:38.296601Z","caller":"traceutil/trace.go:171","msg":"trace[1245861802] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"406.708275ms","start":"2026-02-17T19:46:37.889888Z","end":"2026-02-17T19:46:38.296596Z","steps":["trace[1245861802] 'agreement among raft nodes before linearized reading' (duration: 406.671274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:38.296598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:37.853599Z","time spent":"442.580364ms","remote":"127.0.0.1:40978","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-17T19:46:38.296615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:37.88987Z","time spent":"406.740916ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:46:38.296681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.028811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:46:38.296695Z","caller":"traceutil/trace.go:171","msg":"trace[1882556465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"222.066493ms","start":"2026-02-17T19:46:38.074624Z","end":"2026-02-17T19:46:38.296691Z","steps":["trace[1882556465] 'agreement among raft nodes before linearized reading' (duration: 222.042891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:38.296781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.426344ms","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-17T19:46:38.296794Z","caller":"traceutil/trace.go:171","msg":"trace[272875570] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"391.445805ms","start":"2026-02-17T19:46:37.905343Z","end":"2026-02-17T19:46:38.296789Z","steps":["trace[272875570] 'agreement among raft nodes before linearized reading' (duration: 391.406824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:38.296805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:37.905341Z","time spent":"391.461135ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:46:40.050248Z","caller":"traceutil/trace.go:171","msg":"trace[1823173291] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"202.788171ms","start":"2026-02-17T19:46:39.847445Z","end":"2026-02-17T19:46:40.050233Z","steps":["trace[1823173291] 'process raft request' (duration: 202.676159ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:40.654169Z","caller":"traceutil/trace.go:171","msg":"trace[1190415172] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:540; }","duration":"347.175811ms","start":"2026-02-17T19:46:40.306978Z","end":"2026-02-17T19:46:40.654154Z","steps":["trace[1190415172] 'read index received' (duration: 347.046378ms)","trace[1190415172] 'applied index is now lower than readState.Index' (duration: 128.813µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:46:40.654237Z","caller":"traceutil/trace.go:171","msg":"trace[793653864] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"352.411844ms","start":"2026-02-17T19:46:40.301821Z","end":"2026-02-17T19:46:40.654232Z","steps":["trace[793653864] 'process raft request' (duration: 352.239981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:40.654301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:40.301812Z","time spent":"352.440435ms","remote":"127.0.0.1:40978","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-17T19:46:40.654456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.227852ms","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-17T19:46:40.654505Z","caller":"traceutil/trace.go:171","msg":"trace[1106558214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"347.282013ms","start":"2026-02-17T19:46:40.307211Z","end":"2026-02-17T19:46:40.654493Z","steps":["trace[1106558214] 'agreement among raft nodes before linearized reading' (duration: 347.155871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:40.654527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:40.307172Z","time spent":"347.350974ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:46:40.654564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.589529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:46:40.654584Z","caller":"traceutil/trace.go:171","msg":"trace[1741179008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"347.63475ms","start":"2026-02-17T19:46:40.306942Z","end":"2026-02-17T19:46:40.654577Z","steps":["trace[1741179008] 'agreement among raft nodes before linearized reading' (duration: 347.561398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:40.6546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:40.306935Z","time spent":"347.6609ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:46:40.654645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.024298ms","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-17T19:46:40.654662Z","caller":"traceutil/trace.go:171","msg":"trace[341011019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"347.050458ms","start":"2026-02-17T19:46:40.307607Z","end":"2026-02-17T19:46:40.654658Z","steps":["trace[341011019] 'agreement among raft nodes before linearized reading' (duration: 347.008988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:40.654678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:40.307602Z","time spent":"347.069619ms","remote":"127.0.0.1:40978","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-17T19:46:40.87969Z","caller":"traceutil/trace.go:171","msg":"trace[1808307322] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"220.049682ms","start":"2026-02-17T19:46:40.659623Z","end":"2026-02-17T19:46:40.879673Z","steps":["trace[1808307322] 'process raft request' (duration: 220.026472ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:40.879924Z","caller":"traceutil/trace.go:171","msg":"trace[1633205409] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"222.259226ms","start":"2026-02-17T19:46:40.657657Z","end":"2026-02-17T19:46:40.879916Z","steps":["trace[1633205409] 'process raft request' (duration: 193.276733ms)","trace[1633205409] 'compare' (duration: 28.339739ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:46:40.880005Z","caller":"traceutil/trace.go:171","msg":"trace[833041663] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"221.190624ms","start":"2026-02-17T19:46:40.658808Z","end":"2026-02-17T19:46:40.879999Z","steps":["trace[833041663] 'process raft request' (duration: 220.808647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:46.656544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.362285ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T19:46:46.656678Z","caller":"traceutil/trace.go:171","msg":"trace[775467275] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"326.33004ms","start":"2026-02-17T19:46:46.330331Z","end":"2026-02-17T19:46:46.656661Z","steps":["trace[775467275] 'read index received' (duration: 52.65344ms)","trace[775467275] 'applied index is now lower than readState.Index' (duration: 273.67508ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:46:46.656836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.492443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6bw7b\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-17T19:46:46.656862Z","caller":"traceutil/trace.go:171","msg":"trace[346132092] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6bw7b; range_end:; response_count:1; response_revision:538; }","duration":"326.564264ms","start":"2026-02-17T19:46:46.330289Z","end":"2026-02-17T19:46:46.656853Z","steps":["trace[346132092] 'agreement among raft nodes before linearized reading' (duration: 326.417641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:46.656895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:46.330274Z","time spent":"326.613156ms","remote":"127.0.0.1:40898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16680,"request content":"key:\"/registry/pods/kube-system/cilium-6bw7b\" "} {"level":"info","ts":"2026-02-17T19:46:46.782678Z","caller":"traceutil/trace.go:171","msg":"trace[576354132] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"108.973671ms","start":"2026-02-17T19:46:46.673684Z","end":"2026-02-17T19:46:46.782658Z","steps":["trace[576354132] 'process raft request' (duration: 108.467261ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:57.229398Z","caller":"traceutil/trace.go:171","msg":"trace[1966291256] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:686; }","duration":"236.63956ms","start":"2026-02-17T19:46:56.992743Z","end":"2026-02-17T19:46:57.229383Z","steps":["trace[1966291256] 'read index received' (duration: 236.464766ms)","trace[1966291256] 'applied index is now lower than readState.Index' (duration: 174.264µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:46:57.229659Z","caller":"traceutil/trace.go:171","msg":"trace[983076889] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"239.833183ms","start":"2026-02-17T19:46:56.989819Z","end":"2026-02-17T19:46:57.229652Z","steps":["trace[983076889] 'process raft request' (duration: 239.454786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:57.229844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.092259ms","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-17T19:46:57.229867Z","caller":"traceutil/trace.go:171","msg":"trace[772770543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:663; }","duration":"237.1358ms","start":"2026-02-17T19:46:56.992725Z","end":"2026-02-17T19:46:57.229861Z","steps":["trace[772770543] 'agreement among raft nodes before linearized reading' (duration: 237.055188ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:46:57.62722Z","caller":"traceutil/trace.go:171","msg":"trace[1352878197] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"486.707815ms","start":"2026-02-17T19:46:57.140496Z","end":"2026-02-17T19:46:57.627204Z","steps":["trace[1352878197] 'process raft request' (duration: 486.623824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:57.627332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:57.140478Z","time spent":"486.810967ms","remote":"127.0.0.1:47900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-17T19:46:57.627597Z","caller":"traceutil/trace.go:171","msg":"trace[810193836] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:688; }","duration":"398.129306ms","start":"2026-02-17T19:46:57.229451Z","end":"2026-02-17T19:46:57.62758Z","steps":["trace[810193836] 'read index received' (duration: 398.123006ms)","trace[810193836] 'applied index is now lower than readState.Index' (duration: 5.19µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:46:57.627727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.682635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:46:57.627756Z","caller":"traceutil/trace.go:171","msg":"trace[1951937027] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"559.737226ms","start":"2026-02-17T19:46:57.068011Z","end":"2026-02-17T19:46:57.627749Z","steps":["trace[1951937027] 'agreement among raft nodes before linearized reading' (duration: 559.689945ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:57.627774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:57.067996Z","time spent":"559.774966ms","remote":"127.0.0.1:40660","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-17T19:46:57.629502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.167859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-17T19:46:57.629582Z","caller":"traceutil/trace.go:171","msg":"trace[1228505965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"615.279172ms","start":"2026-02-17T19:46:57.014293Z","end":"2026-02-17T19:46:57.629573Z","steps":["trace[1228505965] 'agreement among raft nodes before linearized reading' (duration: 613.368354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:57.629623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:57.014285Z","time spent":"615.331603ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:46:57.698944Z","caller":"traceutil/trace.go:171","msg":"trace[1023020976] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"465.435224ms","start":"2026-02-17T19:46:57.233495Z","end":"2026-02-17T19:46:57.69893Z","steps":["trace[1023020976] 'process raft request' (duration: 465.334572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:57.699127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:57.233483Z","time spent":"465.575498ms","remote":"127.0.0.1:40978","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-17T19:46:59.92849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.423114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:46:59.9286Z","caller":"traceutil/trace.go:171","msg":"trace[90931740] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:705; }","duration":"204.516015ms","start":"2026-02-17T19:46:59.724023Z","end":"2026-02-17T19:46:59.928539Z","steps":["trace[90931740] 'range keys from in-memory index tree' (duration: 204.288032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:46:59.928612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.433318ms","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-17T19:46:59.928633Z","caller":"traceutil/trace.go:171","msg":"trace[239717579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:705; }","duration":"226.480298ms","start":"2026-02-17T19:46:59.702147Z","end":"2026-02-17T19:46:59.928627Z","steps":["trace[239717579] 'range keys from in-memory index tree' (duration: 226.373536ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:00.25448Z","caller":"traceutil/trace.go:171","msg":"trace[2099383034] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"182.069613ms","start":"2026-02-17T19:47:00.072371Z","end":"2026-02-17T19:47:00.254441Z","steps":["trace[2099383034] 'read index received' (duration: 108.230116ms)","trace[2099383034] 'applied index is now lower than readState.Index' (duration: 73.838507ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:47:00.254753Z","caller":"traceutil/trace.go:171","msg":"trace[362727726] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"322.542825ms","start":"2026-02-17T19:46:59.93219Z","end":"2026-02-17T19:47:00.254732Z","steps":["trace[362727726] 'process raft request' (duration: 248.460743ms)","trace[362727726] 'compare' (duration: 73.041401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:00.254823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.45065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:00.255523Z","caller":"traceutil/trace.go:171","msg":"trace[314764827] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:707; }","duration":"183.182434ms","start":"2026-02-17T19:47:00.072333Z","end":"2026-02-17T19:47:00.255515Z","steps":["trace[314764827] 'agreement among raft nodes before linearized reading' (duration: 182.46065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:00.255569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:46:59.932184Z","time spent":"323.268609ms","remote":"127.0.0.1:40978","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-17T19:47:05.737548Z","caller":"traceutil/trace.go:171","msg":"trace[527381316] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"457.761663ms","start":"2026-02-17T19:47:05.279764Z","end":"2026-02-17T19:47:05.737526Z","steps":["trace[527381316] 'process raft request' (duration: 457.63985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:05.737705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:05.279749Z","time spent":"457.890995ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:47:06.433609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.613538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:47:06.433685Z","caller":"traceutil/trace.go:171","msg":"trace[1760907701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:729; }","duration":"390.736232ms","start":"2026-02-17T19:47:06.04293Z","end":"2026-02-17T19:47:06.433667Z","steps":["trace[1760907701] 'range keys from in-memory index tree' (duration: 390.435315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:06.433741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:06.042913Z","time spent":"390.819493ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:47:06.433985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.103867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:06.434015Z","caller":"traceutil/trace.go:171","msg":"trace[1654673906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:729; }","duration":"362.152467ms","start":"2026-02-17T19:47:06.071852Z","end":"2026-02-17T19:47:06.434004Z","steps":["trace[1654673906] 'range keys from in-memory index tree' (duration: 362.038525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:06.434041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:06.071841Z","time spent":"362.194148ms","remote":"127.0.0.1:40660","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-17T19:47:06.437472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.01337ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T19:47:06.437534Z","caller":"traceutil/trace.go:171","msg":"trace[185144527] linearizableReadLoop","detail":"{readStateIndex:755; appliedIndex:754; }","duration":"138.983523ms","start":"2026-02-17T19:47:06.298539Z","end":"2026-02-17T19:47:06.437522Z","steps":["trace[185144527] 'read index received' (duration: 19.371µs)","trace[185144527] 'applied index is now lower than readState.Index' (duration: 138.963152ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:06.437777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.232278ms","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-17T19:47:06.437809Z","caller":"traceutil/trace.go:171","msg":"trace[453531093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:729; }","duration":"139.264589ms","start":"2026-02-17T19:47:06.298534Z","end":"2026-02-17T19:47:06.437799Z","steps":["trace[453531093] 'agreement among raft nodes before linearized reading' (duration: 139.102905ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:14.210896Z","caller":"traceutil/trace.go:171","msg":"trace[1723667789] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"426.954406ms","start":"2026-02-17T19:47:13.783924Z","end":"2026-02-17T19:47:14.210878Z","steps":["trace[1723667789] 'process raft request' (duration: 426.847653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:14.211016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:13.783911Z","time spent":"427.034318ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:47:14.700971Z","caller":"traceutil/trace.go:171","msg":"trace[411242448] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"133.738689ms","start":"2026-02-17T19:47:14.567219Z","end":"2026-02-17T19:47:14.700958Z","steps":["trace[411242448] 'process raft request' (duration: 133.679648ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:14.701176Z","caller":"traceutil/trace.go:171","msg":"trace[1371283720] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"134.063576ms","start":"2026-02-17T19:47:14.567106Z","end":"2026-02-17T19:47:14.70117Z","steps":["trace[1371283720] 'process raft request' (duration: 99.35195ms)","trace[1371283720] 'compare' (duration: 34.332108ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:15.349755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.319726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:47:15.349819Z","caller":"traceutil/trace.go:171","msg":"trace[1909951224] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"281.78999ms","start":"2026-02-17T19:47:15.068016Z","end":"2026-02-17T19:47:15.349806Z","steps":["trace[1909951224] 'read index received' (duration: 17.92µs)","trace[1909951224] 'applied index is now lower than readState.Index' (duration: 281.77149ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:47:15.349926Z","caller":"traceutil/trace.go:171","msg":"trace[412148158] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"356.472894ms","start":"2026-02-17T19:47:14.993447Z","end":"2026-02-17T19:47:15.34992Z","steps":["trace[412148158] 'process raft request' (duration: 16.935714ms)","trace[412148158] 'compare' (duration: 339.201123ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:15.349959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:14.993435Z","time spent":"356.508134ms","remote":"127.0.0.1:40978","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-17T19:47:15.350073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.056475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:15.350087Z","caller":"traceutil/trace.go:171","msg":"trace[530570210] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"282.071296ms","start":"2026-02-17T19:47:15.06801Z","end":"2026-02-17T19:47:15.350082Z","steps":["trace[530570210] 'agreement among raft nodes before linearized reading' (duration: 282.045316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:15.350204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.155292ms","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-17T19:47:15.350218Z","caller":"traceutil/trace.go:171","msg":"trace[704934784] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:761; }","duration":"223.203214ms","start":"2026-02-17T19:47:15.127009Z","end":"2026-02-17T19:47:15.350212Z","steps":["trace[704934784] 'agreement among raft nodes before linearized reading' (duration: 223.144403ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:28.420454Z","caller":"traceutil/trace.go:171","msg":"trace[1398188834] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"142.310746ms","start":"2026-02-17T19:47:28.278124Z","end":"2026-02-17T19:47:28.420435Z","steps":["trace[1398188834] 'process raft request' (duration: 142.193604ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:30.932634Z","caller":"traceutil/trace.go:171","msg":"trace[485456159] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"112.108282ms","start":"2026-02-17T19:47:30.820504Z","end":"2026-02-17T19:47:30.932612Z","steps":["trace[485456159] 'process raft request' (duration: 111.98658ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:30.979647Z","caller":"traceutil/trace.go:171","msg":"trace[1853214626] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"158.669385ms","start":"2026-02-17T19:47:30.820953Z","end":"2026-02-17T19:47:30.979622Z","steps":["trace[1853214626] 'process raft request' (duration: 158.525712ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:31.179718Z","caller":"traceutil/trace.go:171","msg":"trace[1290238208] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"112.753105ms","start":"2026-02-17T19:47:31.066942Z","end":"2026-02-17T19:47:31.179695Z","steps":["trace[1290238208] 'process raft request' (duration: 112.166033ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:32.693459Z","caller":"traceutil/trace.go:171","msg":"trace[2094661303] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"245.451566ms","start":"2026-02-17T19:47:32.447984Z","end":"2026-02-17T19:47:32.693436Z","steps":["trace[2094661303] 'process raft request' (duration: 245.262912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.107687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.555447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:47:33.107778Z","caller":"traceutil/trace.go:171","msg":"trace[2144745026] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:808; }","duration":"191.686109ms","start":"2026-02-17T19:47:32.916074Z","end":"2026-02-17T19:47:33.10776Z","steps":["trace[2144745026] 'count revisions from in-memory index tree' (duration: 191.497755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.107841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.995528ms","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-17T19:47:33.10791Z","caller":"traceutil/trace.go:171","msg":"trace[1408040471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"171.09347ms","start":"2026-02-17T19:47:32.936796Z","end":"2026-02-17T19:47:33.107889Z","steps":["trace[1408040471] 'range keys from in-memory index tree' (duration: 170.855955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.108101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.012316ms","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-17T19:47:33.108129Z","caller":"traceutil/trace.go:171","msg":"trace[880407587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"124.088767ms","start":"2026-02-17T19:47:32.984031Z","end":"2026-02-17T19:47:33.10812Z","steps":["trace[880407587] 'range keys from in-memory index tree' (duration: 123.887413ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:33.463602Z","caller":"traceutil/trace.go:171","msg":"trace[1586128782] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"349.23477ms","start":"2026-02-17T19:47:33.11435Z","end":"2026-02-17T19:47:33.463585Z","steps":["trace[1586128782] 'process raft request' (duration: 349.145728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.463701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:33.114339Z","time spent":"349.309841ms","remote":"127.0.0.1:40978","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-17T19:47:33.876844Z","caller":"traceutil/trace.go:171","msg":"trace[1779745722] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"448.186062ms","start":"2026-02-17T19:47:33.428635Z","end":"2026-02-17T19:47:33.876821Z","steps":["trace[1779745722] 'read index received' (duration: 35.327113ms)","trace[1779745722] 'applied index is now lower than readState.Index' (duration: 412.858289ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:33.877158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.510189ms","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-17T19:47:33.877185Z","caller":"traceutil/trace.go:171","msg":"trace[1122905089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:810; }","duration":"448.572089ms","start":"2026-02-17T19:47:33.428604Z","end":"2026-02-17T19:47:33.877176Z","steps":["trace[1122905089] 'agreement among raft nodes before linearized reading' (duration: 448.477427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.877206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:33.428588Z","time spent":"448.612641ms","remote":"127.0.0.1:40978","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-17T19:47:33.87731Z","caller":"traceutil/trace.go:171","msg":"trace[1207093366] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"762.862814ms","start":"2026-02-17T19:47:33.114438Z","end":"2026-02-17T19:47:33.877301Z","steps":["trace[1207093366] 'process raft request' (duration: 717.534718ms)","trace[1207093366] 'compare' (duration: 44.325635ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:33.877361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:33.114409Z","time spent":"762.916166ms","remote":"127.0.0.1:40978","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-17T19:47:33.8798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.259868ms","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-17T19:47:33.880025Z","caller":"traceutil/trace.go:171","msg":"trace[364516453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"377.521144ms","start":"2026-02-17T19:47:33.502484Z","end":"2026-02-17T19:47:33.880005Z","steps":["trace[364516453] 'agreement among raft nodes before linearized reading' (duration: 377.076785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:33.880167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:33.502441Z","time spent":"377.711548ms","remote":"127.0.0.1:40978","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-17T19:47:33.880822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.208434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-17T19:47:33.880912Z","caller":"traceutil/trace.go:171","msg":"trace[1292823404] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:810; }","duration":"292.369848ms","start":"2026-02-17T19:47:33.58853Z","end":"2026-02-17T19:47:33.8809Z","steps":["trace[1292823404] 'agreement among raft nodes before linearized reading' (duration: 292.086212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:46.217298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.079731ms","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-17T19:47:46.217353Z","caller":"traceutil/trace.go:171","msg":"trace[221886505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:895; }","duration":"217.188633ms","start":"2026-02-17T19:47:46.000152Z","end":"2026-02-17T19:47:46.21734Z","steps":["trace[221886505] 'range keys from in-memory index tree' (duration: 216.897057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:46.217595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.435298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:46.217617Z","caller":"traceutil/trace.go:171","msg":"trace[1711067036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:895; }","duration":"149.480529ms","start":"2026-02-17T19:47:46.068132Z","end":"2026-02-17T19:47:46.217612Z","steps":["trace[1711067036] 'range keys from in-memory index tree' (duration: 149.401387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:46.217815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.510122ms","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-17T19:47:46.217869Z","caller":"traceutil/trace.go:171","msg":"trace[1293515528] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:895; }","duration":"226.600175ms","start":"2026-02-17T19:47:45.991255Z","end":"2026-02-17T19:47:46.217855Z","steps":["trace[1293515528] 'range keys from in-memory index tree' (duration: 226.36925ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:46.426871Z","caller":"traceutil/trace.go:171","msg":"trace[1939714686] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"206.112978ms","start":"2026-02-17T19:47:46.220743Z","end":"2026-02-17T19:47:46.426856Z","steps":["trace[1939714686] 'process raft request' (duration: 205.959884ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:46.954405Z","caller":"traceutil/trace.go:171","msg":"trace[2067201279] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"733.136191ms","start":"2026-02-17T19:47:46.221247Z","end":"2026-02-17T19:47:46.954383Z","steps":["trace[2067201279] 'process raft request' (duration: 648.458554ms)","trace[2067201279] 'compare' (duration: 84.495703ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:46.954509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:46.221224Z","time spent":"733.235784ms","remote":"127.0.0.1:40978","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-17T19:47:47.221636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.338294ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T19:47:47.221724Z","caller":"traceutil/trace.go:171","msg":"trace[1989427480] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"704.317296ms","start":"2026-02-17T19:47:46.517379Z","end":"2026-02-17T19:47:47.221697Z","steps":["trace[1989427480] 'read index received' (duration: 352.285356ms)","trace[1989427480] 'applied index is now lower than readState.Index' (duration: 352.03088ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:47.222034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.642373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:47.222063Z","caller":"traceutil/trace.go:171","msg":"trace[391306815] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:897; }","duration":"704.701245ms","start":"2026-02-17T19:47:46.517353Z","end":"2026-02-17T19:47:47.222055Z","steps":["trace[391306815] 'agreement among raft nodes before linearized reading' (duration: 704.647474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:47.222085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:46.517339Z","time spent":"704.739905ms","remote":"127.0.0.1:40952","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":"warn","ts":"2026-02-17T19:47:47.22222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.814399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:47.222248Z","caller":"traceutil/trace.go:171","msg":"trace[1679272242] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:897; }","duration":"698.8446ms","start":"2026-02-17T19:47:46.523388Z","end":"2026-02-17T19:47:47.222232Z","steps":["trace[1679272242] 'agreement among raft nodes before linearized reading' (duration: 698.816149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:47.222435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.569606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:47.222461Z","caller":"traceutil/trace.go:171","msg":"trace[449570757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"152.621166ms","start":"2026-02-17T19:47:47.069833Z","end":"2026-02-17T19:47:47.222454Z","steps":["trace[449570757] 'agreement among raft nodes before linearized reading' (duration: 152.582935ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:47.222794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.996236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:47:47.222819Z","caller":"traceutil/trace.go:171","msg":"trace[32587637] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"410.056158ms","start":"2026-02-17T19:47:46.812757Z","end":"2026-02-17T19:47:47.222813Z","steps":["trace[32587637] 'agreement among raft nodes before linearized reading' (duration: 409.975206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:47.222837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:46.812742Z","time spent":"410.090748ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:47:47.222265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:46.523381Z","time spent":"698.87944ms","remote":"127.0.0.1:41010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:47:47.826385Z","caller":"traceutil/trace.go:171","msg":"trace[416258500] linearizableReadLoop","detail":"{readStateIndex:935; appliedIndex:934; }","duration":"293.273646ms","start":"2026-02-17T19:47:47.533095Z","end":"2026-02-17T19:47:47.826369Z","steps":["trace[416258500] 'read index received' (duration: 293.128063ms)","trace[416258500] 'applied index is now lower than readState.Index' (duration: 145.063µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:47.826523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.408829ms","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-17T19:47:47.826538Z","caller":"traceutil/trace.go:171","msg":"trace[220186223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:898; }","duration":"293.46386ms","start":"2026-02-17T19:47:47.53307Z","end":"2026-02-17T19:47:47.826534Z","steps":["trace[220186223] 'agreement among raft nodes before linearized reading' (duration: 293.355488ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:47.826738Z","caller":"traceutil/trace.go:171","msg":"trace[1678779750] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"596.820562ms","start":"2026-02-17T19:47:47.229911Z","end":"2026-02-17T19:47:47.826732Z","steps":["trace[1678779750] 'process raft request' (duration: 596.365242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:47.826784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:47.229895Z","time spent":"596.855375ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:47:48.406152Z","caller":"traceutil/trace.go:171","msg":"trace[1611075151] linearizableReadLoop","detail":"{readStateIndex:936; appliedIndex:935; }","duration":"471.293224ms","start":"2026-02-17T19:47:47.934836Z","end":"2026-02-17T19:47:48.406129Z","steps":["trace[1611075151] 'read index received' (duration: 396.840316ms)","trace[1611075151] 'applied index is now lower than readState.Index' (duration: 74.452048ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:48.406382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.528639ms","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-17T19:47:48.406413Z","caller":"traceutil/trace.go:171","msg":"trace[2124762235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:899; }","duration":"471.595391ms","start":"2026-02-17T19:47:47.934809Z","end":"2026-02-17T19:47:48.406404Z","steps":["trace[2124762235] 'agreement among raft nodes before linearized reading' (duration: 471.402657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:48.406445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:47.934794Z","time spent":"471.644102ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T19:47:48.406684Z","caller":"traceutil/trace.go:171","msg":"trace[1376003117] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"575.247643ms","start":"2026-02-17T19:47:47.831423Z","end":"2026-02-17T19:47:48.406671Z","steps":["trace[1376003117] 'process raft request' (duration: 500.293833ms)","trace[1376003117] 'compare' (duration: 74.083971ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:48.406763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:47.831402Z","time spent":"575.308715ms","remote":"127.0.0.1:40978","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-17T19:47:50.136813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43759723s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:47:50.136898Z","caller":"traceutil/trace.go:171","msg":"trace[1015040650] linearizableReadLoop","detail":"{readStateIndex:937; appliedIndex:936; }","duration":"1.707523148s","start":"2026-02-17T19:47:48.429363Z","end":"2026-02-17T19:47:50.136886Z","steps":["trace[1015040650] 'read index received' (duration: 269.737584ms)","trace[1015040650] 'applied index is now lower than readState.Index' (duration: 1.437784724s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:50.137076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299255559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:50.137096Z","caller":"traceutil/trace.go:171","msg":"trace[649885608] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"1.29931133s","start":"2026-02-17T19:47:48.837779Z","end":"2026-02-17T19:47:50.13709Z","steps":["trace[649885608] 'agreement among raft nodes before linearized reading' (duration: 1.299272319s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:50.137119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:48.837766Z","time spent":"1.299346201s","remote":"127.0.0.1:40660","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-17T19:47:50.137146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.707773243s","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-17T19:47:50.137212Z","caller":"traceutil/trace.go:171","msg":"trace[1588071211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:900; }","duration":"1.707872725s","start":"2026-02-17T19:47:48.429322Z","end":"2026-02-17T19:47:50.137194Z","steps":["trace[1588071211] 'agreement among raft nodes before linearized reading' (duration: 1.7076294s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:50.13725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:48.429305Z","time spent":"1.707936786s","remote":"127.0.0.1:40978","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-17T19:47:50.137369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.842916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:47:50.137399Z","caller":"traceutil/trace.go:171","msg":"trace[1165730102] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:900; }","duration":"306.893776ms","start":"2026-02-17T19:47:49.830495Z","end":"2026-02-17T19:47:50.137389Z","steps":["trace[1165730102] 'agreement among raft nodes before linearized reading' (duration: 306.775094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:50.137426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:49.830481Z","time spent":"306.940068ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-17T19:47:50.137489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.066318838s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:50.137511Z","caller":"traceutil/trace.go:171","msg":"trace[159230061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:900; }","duration":"1.06636092s","start":"2026-02-17T19:47:49.071142Z","end":"2026-02-17T19:47:50.137503Z","steps":["trace[159230061] 'agreement among raft nodes before linearized reading' (duration: 1.066330129s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:50.137535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:49.07113Z","time spent":"1.06640083s","remote":"127.0.0.1:40676","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-17T19:47:50.137782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180521066s","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-17T19:47:50.137809Z","caller":"traceutil/trace.go:171","msg":"trace[1500410504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:900; }","duration":"1.180584347s","start":"2026-02-17T19:47:48.957216Z","end":"2026-02-17T19:47:50.137801Z","steps":["trace[1500410504] 'agreement among raft nodes before linearized reading' (duration: 1.180497514s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:50.137835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:48.957205Z","time spent":"1.180624609s","remote":"127.0.0.1:40978","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-17T19:47:50.138094Z","caller":"traceutil/trace.go:171","msg":"trace[1665410721] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"1.724222645s","start":"2026-02-17T19:47:48.413858Z","end":"2026-02-17T19:47:50.138081Z","steps":["trace[1665410721] 'process raft request' (duration: 285.289706ms)","trace[1665410721] 'compare' (duration: 1.437101619s)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:50.138157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:48.413841Z","time spent":"1.724281707s","remote":"127.0.0.1:40978","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-17T19:47:52.27984Z","caller":"traceutil/trace.go:171","msg":"trace[1175721074] linearizableReadLoop","detail":"{readStateIndex:945; appliedIndex:944; }","duration":"107.456102ms","start":"2026-02-17T19:47:52.172369Z","end":"2026-02-17T19:47:52.279826Z","steps":["trace[1175721074] 'read index received' (duration: 107.32378ms)","trace[1175721074] 'applied index is now lower than readState.Index' (duration: 131.812µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:47:52.280033Z","caller":"traceutil/trace.go:171","msg":"trace[795280667] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"127.105692ms","start":"2026-02-17T19:47:52.152922Z","end":"2026-02-17T19:47:52.280028Z","steps":["trace[795280667] 'process raft request' (duration: 126.812485ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:52.280189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.81397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:47:52.280205Z","caller":"traceutil/trace.go:171","msg":"trace[310696609] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:907; }","duration":"107.86401ms","start":"2026-02-17T19:47:52.172337Z","end":"2026-02-17T19:47:52.280201Z","steps":["trace[310696609] 'agreement among raft nodes before linearized reading' (duration: 107.792159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:52.280296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.61576ms","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-17T19:47:52.280308Z","caller":"traceutil/trace.go:171","msg":"trace[653804606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:907; }","duration":"103.638321ms","start":"2026-02-17T19:47:52.176666Z","end":"2026-02-17T19:47:52.280305Z","steps":["trace[653804606] 'agreement among raft nodes before linearized reading' (duration: 103.59554ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:52.280421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.015437ms","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-17T19:47:52.280433Z","caller":"traceutil/trace.go:171","msg":"trace[144535010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:907; }","duration":"103.037887ms","start":"2026-02-17T19:47:52.177392Z","end":"2026-02-17T19:47:52.28043Z","steps":["trace[144535010] 'agreement among raft nodes before linearized reading' (duration: 102.998636ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:52.873084Z","caller":"traceutil/trace.go:171","msg":"trace[299680448] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"589.009526ms","start":"2026-02-17T19:47:52.284046Z","end":"2026-02-17T19:47:52.873056Z","steps":["trace[299680448] 'process raft request' (duration: 557.257688ms)","trace[299680448] 'compare' (duration: 31.39716ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:52.873222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:52.284035Z","time spent":"589.121658ms","remote":"127.0.0.1:40978","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-17T19:47:53.014274Z","caller":"traceutil/trace.go:171","msg":"trace[1318588896] linearizableReadLoop","detail":"{readStateIndex:948; appliedIndex:945; }","duration":"596.101967ms","start":"2026-02-17T19:47:52.418154Z","end":"2026-02-17T19:47:53.014256Z","steps":["trace[1318588896] 'read index received' (duration: 423.158538ms)","trace[1318588896] 'applied index is now lower than readState.Index' (duration: 172.942829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:53.014439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.26748ms","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-17T19:47:53.014469Z","caller":"traceutil/trace.go:171","msg":"trace[1666921100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:910; }","duration":"596.348062ms","start":"2026-02-17T19:47:52.418112Z","end":"2026-02-17T19:47:53.01446Z","steps":["trace[1666921100] 'agreement among raft nodes before linearized reading' (duration: 596.212189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:53.014498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:52.418099Z","time spent":"596.392213ms","remote":"127.0.0.1:40978","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-17T19:47:53.014619Z","caller":"traceutil/trace.go:171","msg":"trace[179293014] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"729.796429ms","start":"2026-02-17T19:47:52.284791Z","end":"2026-02-17T19:47:53.014587Z","steps":["trace[179293014] 'process raft request' (duration: 704.353206ms)","trace[179293014] 'compare' (duration: 24.982683ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:47:53.014674Z","caller":"traceutil/trace.go:171","msg":"trace[1394201626] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"726.721103ms","start":"2026-02-17T19:47:52.287943Z","end":"2026-02-17T19:47:53.014664Z","steps":["trace[1394201626] 'process raft request' (duration: 726.275184ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:53.014734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:52.287937Z","time spent":"726.756354ms","remote":"127.0.0.1:40978","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-17T19:47:53.017645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:52.284785Z","time spent":"729.902062ms","remote":"127.0.0.1:40978","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-17T19:47:58.445683Z","caller":"traceutil/trace.go:171","msg":"trace[2092186012] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"130.232138ms","start":"2026-02-17T19:47:58.315427Z","end":"2026-02-17T19:47:58.445659Z","steps":["trace[2092186012] 'process raft request' (duration: 130.070335ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:59.252074Z","caller":"traceutil/trace.go:171","msg":"trace[1387158747] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"199.71717ms","start":"2026-02-17T19:47:59.05235Z","end":"2026-02-17T19:47:59.252067Z","steps":["trace[1387158747] 'process raft request' (duration: 120.659394ms)","trace[1387158747] 'compare' (duration: 78.866342ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:47:59.251985Z","caller":"traceutil/trace.go:171","msg":"trace[1197108235] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"183.530366ms","start":"2026-02-17T19:47:59.068437Z","end":"2026-02-17T19:47:59.251968Z","steps":["trace[1197108235] 'read index received' (duration: 104.532211ms)","trace[1197108235] 'applied index is now lower than readState.Index' (duration: 78.997595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:59.252377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.927764ms","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-17T19:47:59.252402Z","caller":"traceutil/trace.go:171","msg":"trace[1677280955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:925; }","duration":"183.985705ms","start":"2026-02-17T19:47:59.068409Z","end":"2026-02-17T19:47:59.252395Z","steps":["trace[1677280955] 'agreement among raft nodes before linearized reading' (duration: 183.901633ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:59.252642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.220519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:47:59.252692Z","caller":"traceutil/trace.go:171","msg":"trace[10006727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:925; }","duration":"183.29744ms","start":"2026-02-17T19:47:59.069381Z","end":"2026-02-17T19:47:59.252678Z","steps":["trace[10006727] 'agreement among raft nodes before linearized reading' (duration: 183.196258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:47:59.25293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.969179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:47:59.252945Z","caller":"traceutil/trace.go:171","msg":"trace[252180265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:925; }","duration":"158.009421ms","start":"2026-02-17T19:47:59.094931Z","end":"2026-02-17T19:47:59.25294Z","steps":["trace[252180265] 'agreement among raft nodes before linearized reading' (duration: 157.939699ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:47:59.462035Z","caller":"traceutil/trace.go:171","msg":"trace[1248156667] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"206.111807ms","start":"2026-02-17T19:47:59.255898Z","end":"2026-02-17T19:47:59.46201Z","steps":["trace[1248156667] 'process raft request' (duration: 113.173895ms)","trace[1248156667] 'compare' (duration: 92.678807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:59.950302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.504539ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T19:47:59.950371Z","caller":"traceutil/trace.go:171","msg":"trace[625482855] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"693.970344ms","start":"2026-02-17T19:47:59.256388Z","end":"2026-02-17T19:47:59.950358Z","steps":["trace[625482855] 'process raft request' (duration: 447.353363ms)","trace[625482855] 'compare' (duration: 246.408057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:47:59.950403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:47:59.256381Z","time spent":"694.008415ms","remote":"127.0.0.1:40978","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-17T19:48:00.746326Z","caller":"traceutil/trace.go:171","msg":"trace[42117882] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"103.473878ms","start":"2026-02-17T19:48:00.642836Z","end":"2026-02-17T19:48:00.74631Z","steps":["trace[42117882] 'read index received' (duration: 103.352125ms)","trace[42117882] 'applied index is now lower than readState.Index' (duration: 121.123µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:00.746432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.58595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:00.746452Z","caller":"traceutil/trace.go:171","msg":"trace[1371480400] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:928; }","duration":"103.649291ms","start":"2026-02-17T19:48:00.642797Z","end":"2026-02-17T19:48:00.746446Z","steps":["trace[1371480400] 'agreement among raft nodes before linearized reading' (duration: 103.577759ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:00.746632Z","caller":"traceutil/trace.go:171","msg":"trace[1441741784] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"272.039514ms","start":"2026-02-17T19:48:00.474587Z","end":"2026-02-17T19:48:00.746626Z","steps":["trace[1441741784] 'process raft request' (duration: 271.642815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.786201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.882675ms","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-17T19:48:01.786272Z","caller":"traceutil/trace.go:171","msg":"trace[1566428474] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"530.002248ms","start":"2026-02-17T19:48:01.256253Z","end":"2026-02-17T19:48:01.786255Z","steps":["trace[1566428474] 'range keys from in-memory index tree' (duration: 529.746642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.786314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.25624Z","time spent":"530.065659ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:48:01.786329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.686293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:01.786376Z","caller":"traceutil/trace.go:171","msg":"trace[1835251925] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:928; }","duration":"396.779005ms","start":"2026-02-17T19:48:01.389586Z","end":"2026-02-17T19:48:01.786365Z","steps":["trace[1835251925] 'count revisions from in-memory index tree' (duration: 396.641723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.786399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.389573Z","time spent":"396.821136ms","remote":"127.0.0.1:40836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-17T19:48:01.786573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:00.860965Z","time spent":"925.606358ms","remote":"127.0.0.1:49066","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-17T19:48:01.786624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.334775ms","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-17T19:48:01.78664Z","caller":"traceutil/trace.go:171","msg":"trace[290304336] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"320.372376ms","start":"2026-02-17T19:48:01.466262Z","end":"2026-02-17T19:48:01.786635Z","steps":["trace[290304336] 'range keys from in-memory index tree' (duration: 320.199892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.786654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.466249Z","time spent":"320.401306ms","remote":"127.0.0.1:40978","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-17T19:48:01.786951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.682895ms","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-17T19:48:01.786986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.248212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:01.786989Z","caller":"traceutil/trace.go:171","msg":"trace[480398135] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"818.719436ms","start":"2026-02-17T19:48:00.968259Z","end":"2026-02-17T19:48:01.786978Z","steps":["trace[480398135] 'range keys from in-memory index tree' (duration: 818.596683ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:01.787004Z","caller":"traceutil/trace.go:171","msg":"trace[88341651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:928; }","duration":"717.286863ms","start":"2026-02-17T19:48:01.069712Z","end":"2026-02-17T19:48:01.786999Z","steps":["trace[88341651] 'range keys from in-memory index tree' (duration: 717.216731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.787018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.0697Z","time spent":"717.315113ms","remote":"127.0.0.1:40660","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-17T19:48:01.787019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:00.968217Z","time spent":"818.795548ms","remote":"127.0.0.1:40978","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-17T19:48:01.787104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.444098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T19:48:01.787118Z","caller":"traceutil/trace.go:171","msg":"trace[14350210] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:928; }","duration":"681.486499ms","start":"2026-02-17T19:48:01.105627Z","end":"2026-02-17T19:48:01.787114Z","steps":["trace[14350210] 'range keys from in-memory index tree' (duration: 681.359216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:01.787129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.105612Z","time spent":"681.514809ms","remote":"127.0.0.1:40716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-02-17T19:48:02.298088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.791051Z","time spent":"507.032907ms","remote":"127.0.0.1:40716","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-17T19:48:02.305392Z","caller":"traceutil/trace.go:171","msg":"trace[936623014] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"512.234899ms","start":"2026-02-17T19:48:01.793138Z","end":"2026-02-17T19:48:02.305373Z","steps":["trace[936623014] 'process raft request' (duration: 511.618376ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:02.30551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.793126Z","time spent":"512.32086ms","remote":"127.0.0.1:40978","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-17T19:48:02.306316Z","caller":"traceutil/trace.go:171","msg":"trace[351671685] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"513.042035ms","start":"2026-02-17T19:48:01.793255Z","end":"2026-02-17T19:48:02.306297Z","steps":["trace[351671685] 'process raft request' (duration: 511.802929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:02.306412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.793251Z","time spent":"513.109557ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T19:48:02.306676Z","caller":"traceutil/trace.go:171","msg":"trace[1729293747] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"509.99232ms","start":"2026-02-17T19:48:01.796678Z","end":"2026-02-17T19:48:02.306671Z","steps":["trace[1729293747] 'process raft request' (duration: 508.666222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:02.306708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.796671Z","time spent":"510.018911ms","remote":"127.0.0.1:40978","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-17T19:48:02.306995Z","caller":"traceutil/trace.go:171","msg":"trace[2071505393] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"249.33075ms","start":"2026-02-17T19:48:02.057657Z","end":"2026-02-17T19:48:02.306988Z","steps":["trace[2071505393] 'process raft request' (duration: 248.537762ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:02.309209Z","caller":"traceutil/trace.go:171","msg":"trace[346399390] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:968; }","duration":"354.941952ms","start":"2026-02-17T19:48:01.95425Z","end":"2026-02-17T19:48:02.309192Z","steps":["trace[346399390] 'read index received' (duration: 344.215023ms)","trace[346399390] 'applied index is now lower than readState.Index' (duration: 10.725879ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:02.309463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.202617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:48:02.309498Z","caller":"traceutil/trace.go:171","msg":"trace[1312610931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:933; }","duration":"355.270369ms","start":"2026-02-17T19:48:01.954218Z","end":"2026-02-17T19:48:02.309488Z","steps":["trace[1312610931] 'agreement among raft nodes before linearized reading' (duration: 355.098476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:02.309526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:01.954202Z","time spent":"355.31661ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-17T19:48:04.414009Z","caller":"traceutil/trace.go:171","msg":"trace[621840216] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"102.716731ms","start":"2026-02-17T19:48:04.311279Z","end":"2026-02-17T19:48:04.413996Z","steps":["trace[621840216] 'process raft request' (duration: 102.62976ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:04.506548Z","caller":"traceutil/trace.go:171","msg":"trace[1226995332] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"194.123793ms","start":"2026-02-17T19:48:04.312399Z","end":"2026-02-17T19:48:04.506522Z","steps":["trace[1226995332] 'process raft request' (duration: 193.966169ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:04.506651Z","caller":"traceutil/trace.go:171","msg":"trace[1584051422] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"193.09901ms","start":"2026-02-17T19:48:04.313538Z","end":"2026-02-17T19:48:04.506637Z","steps":["trace[1584051422] 'process raft request' (duration: 192.898116ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:04.876892Z","caller":"traceutil/trace.go:171","msg":"trace[1307530754] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"107.770309ms","start":"2026-02-17T19:48:04.769107Z","end":"2026-02-17T19:48:04.876878Z","steps":["trace[1307530754] 'read index received' (duration: 107.635387ms)","trace[1307530754] 'applied index is now lower than readState.Index' (duration: 134.432µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:04.877026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.901792ms","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-17T19:48:04.877043Z","caller":"traceutil/trace.go:171","msg":"trace[502333798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"107.955714ms","start":"2026-02-17T19:48:04.769082Z","end":"2026-02-17T19:48:04.877038Z","steps":["trace[502333798] 'agreement among raft nodes before linearized reading' (duration: 107.853662ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:04.877329Z","caller":"traceutil/trace.go:171","msg":"trace[1528307870] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"269.228644ms","start":"2026-02-17T19:48:04.608094Z","end":"2026-02-17T19:48:04.877323Z","steps":["trace[1528307870] 'process raft request' (duration: 268.692092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:05.354703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.322126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:05.354751Z","caller":"traceutil/trace.go:171","msg":"trace[296220643] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"284.419158ms","start":"2026-02-17T19:48:05.070319Z","end":"2026-02-17T19:48:05.354739Z","steps":["trace[296220643] 'range keys from in-memory index tree' (duration: 284.248663ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:06.335774Z","caller":"traceutil/trace.go:171","msg":"trace[1120724161] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"109.69783ms","start":"2026-02-17T19:48:06.226055Z","end":"2026-02-17T19:48:06.335753Z","steps":["trace[1120724161] 'process raft request' (duration: 109.552617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:06.692592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.93947ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-17T19:48:06.692667Z","caller":"traceutil/trace.go:171","msg":"trace[1943886516] linearizableReadLoop","detail":"{readStateIndex:982; appliedIndex:981; }","duration":"275.385384ms","start":"2026-02-17T19:48:06.41727Z","end":"2026-02-17T19:48:06.692655Z","steps":["trace[1943886516] 'read index received' (duration: 32.04µs)","trace[1943886516] 'applied index is now lower than readState.Index' (duration: 275.352334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:06.693092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.815193ms","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-17T19:48:06.693116Z","caller":"traceutil/trace.go:171","msg":"trace[713897443] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:941; }","duration":"275.878605ms","start":"2026-02-17T19:48:06.417231Z","end":"2026-02-17T19:48:06.69311Z","steps":["trace[713897443] 'agreement among raft nodes before linearized reading' (duration: 275.757152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:06.693254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.105295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-17T19:48:06.693269Z","caller":"traceutil/trace.go:171","msg":"trace[113366954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"182.153456ms","start":"2026-02-17T19:48:06.511112Z","end":"2026-02-17T19:48:06.693265Z","steps":["trace[113366954] 'agreement among raft nodes before linearized reading' (duration: 182.106795ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:06.693388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.103618ms","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-17T19:48:06.693402Z","caller":"traceutil/trace.go:171","msg":"trace[1564537633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"177.131359ms","start":"2026-02-17T19:48:06.516266Z","end":"2026-02-17T19:48:06.693398Z","steps":["trace[1564537633] 'agreement among raft nodes before linearized reading' (duration: 177.089398ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:07.006911Z","caller":"traceutil/trace.go:171","msg":"trace[1451675774] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"310.375112ms","start":"2026-02-17T19:48:06.696501Z","end":"2026-02-17T19:48:07.006877Z","steps":["trace[1451675774] 'process raft request' (duration: 310.187388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:07.007112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:06.696489Z","time spent":"310.536495ms","remote":"127.0.0.1:40978","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-17T19:48:07.018388Z","caller":"traceutil/trace.go:171","msg":"trace[983385127] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:983; }","duration":"132.248931ms","start":"2026-02-17T19:48:06.886131Z","end":"2026-02-17T19:48:07.01838Z","steps":["trace[983385127] 'read index received' (duration: 121.467512ms)","trace[983385127] 'applied index is now lower than readState.Index' (duration: 10.781059ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:48:07.018503Z","caller":"traceutil/trace.go:171","msg":"trace[239577633] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"321.218482ms","start":"2026-02-17T19:48:06.697279Z","end":"2026-02-17T19:48:07.018498Z","steps":["trace[239577633] 'process raft request' (duration: 321.045759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:07.018543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:06.697274Z","time spent":"321.241123ms","remote":"127.0.0.1:40978","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-17T19:48:07.018633Z","caller":"traceutil/trace.go:171","msg":"trace[2049181205] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"320.412315ms","start":"2026-02-17T19:48:06.698214Z","end":"2026-02-17T19:48:07.018627Z","steps":["trace[2049181205] 'process raft request' (duration: 320.148079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:07.018656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:06.698208Z","time spent":"320.435435ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:48:07.018752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.621169ms","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-17T19:48:07.018766Z","caller":"traceutil/trace.go:171","msg":"trace[847587075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"132.67065ms","start":"2026-02-17T19:48:06.886091Z","end":"2026-02-17T19:48:07.018762Z","steps":["trace[847587075] 'agreement among raft nodes before linearized reading' (duration: 132.610939ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:07.198771Z","caller":"traceutil/trace.go:171","msg":"trace[952861117] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"172.777117ms","start":"2026-02-17T19:48:07.025972Z","end":"2026-02-17T19:48:07.198749Z","steps":["trace[952861117] 'process raft request' (duration: 138.688769ms)","trace[952861117] 'compare' (duration: 33.941154ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:48:07.198932Z","caller":"traceutil/trace.go:171","msg":"trace[1526190250] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:985; }","duration":"128.685675ms","start":"2026-02-17T19:48:07.070239Z","end":"2026-02-17T19:48:07.198925Z","steps":["trace[1526190250] 'read index received' (duration: 94.430035ms)","trace[1526190250] 'applied index is now lower than readState.Index' (duration: 34.25491ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:07.199004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.748926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:07.19903Z","caller":"traceutil/trace.go:171","msg":"trace[1201445919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:946; }","duration":"128.804968ms","start":"2026-02-17T19:48:07.070217Z","end":"2026-02-17T19:48:07.199022Z","steps":["trace[1201445919] 'agreement among raft nodes before linearized reading' (duration: 128.730497ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:07.199232Z","caller":"traceutil/trace.go:171","msg":"trace[2056954470] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"172.357147ms","start":"2026-02-17T19:48:07.026854Z","end":"2026-02-17T19:48:07.199211Z","steps":["trace[2056954470] 'process raft request' (duration: 171.849817ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:16.72837Z","caller":"traceutil/trace.go:171","msg":"trace[207602676] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"105.736326ms","start":"2026-02-17T19:48:16.622606Z","end":"2026-02-17T19:48:16.728343Z","steps":["trace[207602676] 'process raft request' (duration: 105.371598ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:22.945263Z","caller":"traceutil/trace.go:171","msg":"trace[1186629381] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"117.190521ms","start":"2026-02-17T19:48:22.828048Z","end":"2026-02-17T19:48:22.945238Z","steps":["trace[1186629381] 'process raft request' (duration: 117.033147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.933472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.839642354s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:24.933541Z","caller":"traceutil/trace.go:171","msg":"trace[1475515506] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1052; }","duration":"1.839754746s","start":"2026-02-17T19:48:23.09377Z","end":"2026-02-17T19:48:24.933525Z","steps":["trace[1475515506] 'range keys from in-memory index tree' (duration: 1.839572272s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.933618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.093749Z","time spent":"1.839859779s","remote":"127.0.0.1:40660","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-17T19:48:24.937743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751706798s","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-17T19:48:24.937783Z","caller":"traceutil/trace.go:171","msg":"trace[1686347200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.75177388s","start":"2026-02-17T19:48:23.185996Z","end":"2026-02-17T19:48:24.93777Z","steps":["trace[1686347200] 'range keys from in-memory index tree' (duration: 1.751559135s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.937826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.185981Z","time spent":"1.751837291s","remote":"127.0.0.1:40978","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-17T19:48:24.938042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738127158s","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-17T19:48:24.938071Z","caller":"traceutil/trace.go:171","msg":"trace[344799044] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1052; }","duration":"1.738170509s","start":"2026-02-17T19:48:23.199893Z","end":"2026-02-17T19:48:24.938063Z","steps":["trace[344799044] 'range keys from in-memory index tree' (duration: 1.738062077s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.938096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.199886Z","time spent":"1.7382041s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-17T19:48:24.938246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.738228941s","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-17T19:48:24.93827Z","caller":"traceutil/trace.go:171","msg":"trace[491991044] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1052; }","duration":"1.738262961s","start":"2026-02-17T19:48:23.2Z","end":"2026-02-17T19:48:24.938263Z","steps":["trace[491991044] 'range keys from in-memory index tree' (duration: 1.738175669s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.938297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.199995Z","time spent":"1.738297092s","remote":"127.0.0.1:40978","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-17T19:48:24.939874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.62803567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-17T19:48:24.939942Z","caller":"traceutil/trace.go:171","msg":"trace[1315884374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.628136352s","start":"2026-02-17T19:48:23.311795Z","end":"2026-02-17T19:48:24.939931Z","steps":["trace[1315884374] 'range keys from in-memory index tree' (duration: 1.627698123s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.939973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.311781Z","time spent":"1.628184573s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T19:48:24.940217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627525699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-17T19:48:24.940252Z","caller":"traceutil/trace.go:171","msg":"trace[515283694] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1052; }","duration":"1.627569751s","start":"2026-02-17T19:48:23.312669Z","end":"2026-02-17T19:48:24.940239Z","steps":["trace[515283694] 'range keys from in-memory index tree' (duration: 1.627405997s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.94029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.312664Z","time spent":"1.627607502s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-17T19:48:24.941746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001915807s","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-17T19:48:24.941872Z","caller":"traceutil/trace.go:171","msg":"trace[696385168] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1052; }","duration":"1.002007999s","start":"2026-02-17T19:48:23.9398Z","end":"2026-02-17T19:48:24.941808Z","steps":["trace[696385168] 'range keys from in-memory index tree' (duration: 1.001686912s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:24.945845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:23.939746Z","time spent":"1.006086987s","remote":"127.0.0.1:40978","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-17T19:48:24.946439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.098881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"info","ts":"2026-02-17T19:48:24.946472Z","caller":"traceutil/trace.go:171","msg":"trace[915685513] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1052; }","duration":"121.130131ms","start":"2026-02-17T19:48:24.825331Z","end":"2026-02-17T19:48:24.946461Z","steps":["trace[915685513] 'range keys from in-memory index tree' (duration: 121.003629ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:48:25.263007Z","caller":"traceutil/trace.go:171","msg":"trace[1908887526] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"314.933463ms","start":"2026-02-17T19:48:24.948046Z","end":"2026-02-17T19:48:25.262979Z","steps":["trace[1908887526] 'process raft request' (duration: 314.713449ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:25.263293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.948034Z","time spent":"315.076557ms","remote":"127.0.0.1:40978","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-17T19:48:26.030929Z","caller":"traceutil/trace.go:171","msg":"trace[90590326] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"1.08025526s","start":"2026-02-17T19:48:24.950652Z","end":"2026-02-17T19:48:26.030907Z","steps":["trace[90590326] 'process raft request' (duration: 1.079486773s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.031059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.950645Z","time spent":"1.080346722s","remote":"127.0.0.1:40978","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-17T19:48:26.031073Z","caller":"traceutil/trace.go:171","msg":"trace[1548812992] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"1.08024159s","start":"2026-02-17T19:48:24.950807Z","end":"2026-02-17T19:48:26.031049Z","steps":["trace[1548812992] 'read index received' (duration: 313.082194ms)","trace[1548812992] 'applied index is now lower than readState.Index' (duration: 767.158526ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:48:26.031264Z","caller":"traceutil/trace.go:171","msg":"trace[168511409] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.078668887s","start":"2026-02-17T19:48:24.952582Z","end":"2026-02-17T19:48:26.031251Z","steps":["trace[168511409] 'process raft request' (duration: 1.078214637s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.031357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.952576Z","time spent":"1.078715778s","remote":"127.0.0.1:40978","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-17T19:48:26.032363Z","caller":"traceutil/trace.go:171","msg":"trace[602821371] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.075284784s","start":"2026-02-17T19:48:24.957061Z","end":"2026-02-17T19:48:26.032345Z","steps":["trace[602821371] 'process raft request' (duration: 1.073957175s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.032517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.95705Z","time spent":"1.075391206s","remote":"127.0.0.1:40978","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-17T19:48:26.03296Z","caller":"traceutil/trace.go:171","msg":"trace[1020567692] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.080318483s","start":"2026-02-17T19:48:24.952628Z","end":"2026-02-17T19:48:26.032946Z","steps":["trace[1020567692] 'process raft request' (duration: 1.078266558s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.033101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.952624Z","time spent":"1.080435815s","remote":"127.0.0.1:40978","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-17T19:48:26.033248Z","caller":"traceutil/trace.go:171","msg":"trace[584903028] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"1.080571698s","start":"2026-02-17T19:48:24.952666Z","end":"2026-02-17T19:48:26.033238Z","steps":["trace[584903028] 'process raft request' (duration: 1.078275698s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.033298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.952663Z","time spent":"1.080607509s","remote":"127.0.0.1:40978","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-17T19:48:26.033438Z","caller":"traceutil/trace.go:171","msg":"trace[903705063] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"1.080363844s","start":"2026-02-17T19:48:24.953065Z","end":"2026-02-17T19:48:26.033429Z","steps":["trace[903705063] 'process raft request' (duration: 1.077908981s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.033491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.953059Z","time spent":"1.080403945s","remote":"127.0.0.1:40978","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-17T19:48:26.034081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.036726786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-17T19:48:26.034111Z","caller":"traceutil/trace.go:171","msg":"trace[1685446496] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1059; }","duration":"1.036783757s","start":"2026-02-17T19:48:24.99732Z","end":"2026-02-17T19:48:26.034104Z","steps":["trace[1685446496] 'agreement among raft nodes before linearized reading' (duration: 1.036692596s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.034198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.997303Z","time spent":"1.036887559s","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-17T19:48:26.034348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08353873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:26.034366Z","caller":"traceutil/trace.go:171","msg":"trace[1512841536] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"1.083567921s","start":"2026-02-17T19:48:24.950792Z","end":"2026-02-17T19:48:26.03436Z","steps":["trace[1512841536] 'agreement among raft nodes before linearized reading' (duration: 1.08353805s)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.034382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:24.950788Z","time spent":"1.083590742s","remote":"127.0.0.1:40676","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-17T19:48:26.034696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.30842ms","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-17T19:48:26.035811Z","caller":"traceutil/trace.go:171","msg":"trace[1481319347] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1059; }","duration":"693.337552ms","start":"2026-02-17T19:48:25.342337Z","end":"2026-02-17T19:48:26.035675Z","steps":["trace[1481319347] 'agreement among raft nodes before linearized reading' (duration: 692.32722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.035987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:25.342317Z","time spent":"693.523195ms","remote":"127.0.0.1:41172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":3,"response size":31,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-02-17T19:48:26.535171Z","caller":"traceutil/trace.go:171","msg":"trace[568854839] linearizableReadLoop","detail":"{readStateIndex:1104; appliedIndex:1103; }","duration":"487.733345ms","start":"2026-02-17T19:48:26.047415Z","end":"2026-02-17T19:48:26.535148Z","steps":["trace[568854839] 'read index received' (duration: 485.923517ms)","trace[568854839] 'applied index is now lower than readState.Index' (duration: 1.808778ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:48:26.535275Z","caller":"traceutil/trace.go:171","msg":"trace[1018115686] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"488.077683ms","start":"2026-02-17T19:48:26.047183Z","end":"2026-02-17T19:48:26.53526Z","steps":["trace[1018115686] 'process raft request' (duration: 486.114661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:48:26.535365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:26.047162Z","time spent":"488.136954ms","remote":"127.0.0.1:40978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T19:48:26.553658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.210443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T19:48:26.553723Z","caller":"traceutil/trace.go:171","msg":"trace[534667189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1060; }","duration":"506.302384ms","start":"2026-02-17T19:48:26.047402Z","end":"2026-02-17T19:48:26.553705Z","steps":["trace[534667189] 'agreement among raft nodes before linearized reading' (duration: 487.831527ms)","trace[534667189] 'range keys from in-memory index tree' (duration: 18.369305ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T19:48:26.553753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T19:48:26.047395Z","time spent":"506.352895ms","remote":"127.0.0.1:40676","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-17T19:50:06.650018Z","caller":"traceutil/trace.go:171","msg":"trace[1005995741] transaction","detail":"{read_only:false; response_revision:1466; number_of_response:1; }","duration":"135.741394ms","start":"2026-02-17T19:50:06.514259Z","end":"2026-02-17T19:50:06.650001Z","steps":["trace[1005995741] 'process raft request' (duration: 135.504829ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T19:51:33.312536Z","caller":"traceutil/trace.go:171","msg":"trace[1032777621] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1929; }","duration":"153.016347ms","start":"2026-02-17T19:51:33.159504Z","end":"2026-02-17T19:51:33.312521Z","steps":["trace[1032777621] 'read index received' (duration: 148.295976ms)","trace[1032777621] 'applied index is now lower than readState.Index' (duration: 4.719681ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T19:51:33.313267Z","caller":"traceutil/trace.go:171","msg":"trace[67273546] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"153.972508ms","start":"2026-02-17T19:51:33.159286Z","end":"2026-02-17T19:51:33.313258Z","steps":["trace[67273546] 'process raft request' (duration: 148.570392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:51:33.313809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.292974ms","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-17T19:51:33.313912Z","caller":"traceutil/trace.go:171","msg":"trace[521596504] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1847; }","duration":"154.430567ms","start":"2026-02-17T19:51:33.159473Z","end":"2026-02-17T19:51:33.313903Z","steps":["trace[521596504] 'agreement among raft nodes before linearized reading' (duration: 154.242393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:51:42.310362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.48459ms","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:509"} {"level":"info","ts":"2026-02-17T19:51:42.310684Z","caller":"traceutil/trace.go:171","msg":"trace[1274598875] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1888; }","duration":"240.816397ms","start":"2026-02-17T19:51:42.069848Z","end":"2026-02-17T19:51:42.310664Z","steps":["trace[1274598875] 'range keys from in-memory index tree' (duration: 240.350418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T19:51:42.309922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.318542ms","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-17T19:51:42.322341Z","caller":"traceutil/trace.go:171","msg":"trace[67643926] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1888; }","duration":"197.760887ms","start":"2026-02-17T19:51:42.124569Z","end":"2026-02-17T19:51:42.32233Z","steps":["trace[67643926] 'range keys from in-memory index tree' (duration: 185.300901ms)"],"step_count":1}