{"level":"warn","ts":"2026-02-22T17:45:50.746401Z","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-22T17:45:50.746484Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.238:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.238:2380","--initial-cluster=instance=https://199.204.45.238:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.238:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.238: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-22T17:45:50.746542Z","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-22T17:45:50.746554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-22T17:45:50.746571Z","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-22T17:45:50.747247Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"]} {"level":"info","ts":"2026-02-22T17:45:50.747338Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.238: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-22T17:45:50.785991Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"37.486801ms"} {"level":"info","ts":"2026-02-22T17:45:50.804198Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d7dbc9f5893cc1f6","cluster-id":"823d1d08ab21430c"} {"level":"info","ts":"2026-02-22T17:45:50.804422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T17:45:50.804499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 0"} {"level":"info","ts":"2026-02-22T17:45:50.804538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d7dbc9f5893cc1f6 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T17:45:50.804592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became follower at term 1"} {"level":"info","ts":"2026-02-22T17:45:50.804673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"warn","ts":"2026-02-22T17:45:50.833004Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T17:45:50.834942Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T17:45:50.875164Z","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-22T17:45:50.910473Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d7dbc9f5893cc1f6","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T17:45:50.910785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d7dbc9f5893cc1f6","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T17:45:50.910913Z","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-22T17:45:50.912585Z","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-22T17:45:50.912687Z","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-22T17:45:50.91424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 switched to configuration voters=(15554247794460770806)"} {"level":"info","ts":"2026-02-22T17:45:50.914471Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","added-peer-id":"d7dbc9f5893cc1f6","added-peer-peer-urls":["https://199.204.45.238:2380"]} {"level":"info","ts":"2026-02-22T17:45:50.917788Z","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-22T17:45:50.918016Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d7dbc9f5893cc1f6","initial-advertise-peer-urls":["https://199.204.45.238:2380"],"listen-peer-urls":["https://199.204.45.238:2380"],"advertise-client-urls":["https://199.204.45.238:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.238:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T17:45:50.918077Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T17:45:50.918198Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-22T17:45:50.918238Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.238:2380"} {"level":"info","ts":"2026-02-22T17:45:51.10579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T17:45:51.105948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T17:45:51.106008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgPreVoteResp from d7dbc9f5893cc1f6 at term 1"} {"level":"info","ts":"2026-02-22T17:45:51.106055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became candidate at term 2"} {"level":"info","ts":"2026-02-22T17:45:51.106094Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 received MsgVoteResp from d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-22T17:45:51.106132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d7dbc9f5893cc1f6 became leader at term 2"} {"level":"info","ts":"2026-02-22T17:45:51.106166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d7dbc9f5893cc1f6 elected leader d7dbc9f5893cc1f6 at term 2"} {"level":"info","ts":"2026-02-22T17:45:51.114339Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d7dbc9f5893cc1f6","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.238:2379]}","request-path":"/0/members/d7dbc9f5893cc1f6/attributes","cluster-id":"823d1d08ab21430c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T17:45:51.114365Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:45:51.114416Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:45:51.114438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T17:45:51.116439Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T17:45:51.116522Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T17:45:51.118203Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T17:45:51.118858Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.238:2379"} {"level":"info","ts":"2026-02-22T17:45:51.150948Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"823d1d08ab21430c","local-member-id":"d7dbc9f5893cc1f6","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:45:51.151263Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:45:51.151363Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T17:45:52.685292Z","caller":"traceutil/trace.go:171","msg":"trace[1501735760] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"109.7112ms","start":"2026-02-22T17:45:52.575552Z","end":"2026-02-22T17:45:52.685263Z","steps":["trace[1501735760] 'process raft request' (duration: 41.551323ms)","trace[1501735760] 'compare' (duration: 67.960213ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:10.261898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.811747ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-22T17:46:10.262021Z","caller":"traceutil/trace.go:171","msg":"trace[25201608] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"880.282281ms","start":"2026-02-22T17:46:09.381721Z","end":"2026-02-22T17:46:10.262003Z","steps":["trace[25201608] 'read index received' (duration: 253.231438ms)","trace[25201608] 'applied index is now lower than readState.Index' (duration: 627.049363ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:10.262102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.376583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:10.262133Z","caller":"traceutil/trace.go:171","msg":"trace[835469796] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"880.417914ms","start":"2026-02-22T17:46:09.381707Z","end":"2026-02-22T17:46:10.262125Z","steps":["trace[835469796] 'agreement among raft nodes before linearized reading' (duration: 880.351323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:10.262171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:09.381646Z","time spent":"880.518516ms","remote":"127.0.0.1:42734","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-22T17:46:10.532027Z","caller":"traceutil/trace.go:171","msg":"trace[686162804] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"152.171484ms","start":"2026-02-22T17:46:10.37983Z","end":"2026-02-22T17:46:10.532001Z","steps":["trace[686162804] 'process raft request' (duration: 152.008471ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:11.996365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336411976s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-22T17:46:11.996405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073195832s","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-22T17:46:11.996488Z","caller":"traceutil/trace.go:171","msg":"trace[845529894] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"1.073340735s","start":"2026-02-22T17:46:10.923127Z","end":"2026-02-22T17:46:11.996468Z","steps":["trace[845529894] 'range keys from in-memory index tree' (duration: 1.073072818s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:11.996493Z","caller":"traceutil/trace.go:171","msg":"trace[990176443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:412; }","duration":"1.336604481s","start":"2026-02-22T17:46:10.659855Z","end":"2026-02-22T17:46:11.99646Z","steps":["trace[990176443] 'range keys from in-memory index tree' (duration: 1.336260073s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:11.99653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:10.9231Z","time spent":"1.073421507s","remote":"127.0.0.1:43016","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-22T17:46:11.996551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:10.659834Z","time spent":"1.336707033s","remote":"127.0.0.1:43016","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-22T17:46:11.996744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.069732973s","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-22T17:46:11.996783Z","caller":"traceutil/trace.go:171","msg":"trace[617247950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"1.069793665s","start":"2026-02-22T17:46:10.92698Z","end":"2026-02-22T17:46:11.996774Z","steps":["trace[617247950] 'range keys from in-memory index tree' (duration: 1.069677172s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:11.99681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:10.92697Z","time spent":"1.069834315s","remote":"127.0.0.1:43016","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-22T17:46:11.996911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.799039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:11.99694Z","caller":"traceutil/trace.go:171","msg":"trace[1256174507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"615.86414ms","start":"2026-02-22T17:46:11.381069Z","end":"2026-02-22T17:46:11.996933Z","steps":["trace[1256174507] 'range keys from in-memory index tree' (duration: 615.744698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:11.996964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:11.381051Z","time spent":"615.908451ms","remote":"127.0.0.1:42734","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-22T17:46:12.1884Z","caller":"traceutil/trace.go:171","msg":"trace[2076321096] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"186.613106ms","start":"2026-02-22T17:46:12.001763Z","end":"2026-02-22T17:46:12.188376Z","steps":["trace[2076321096] 'process raft request' (duration: 186.447392ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:12.226884Z","caller":"traceutil/trace.go:171","msg":"trace[571600392] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"221.700693ms","start":"2026-02-22T17:46:12.005157Z","end":"2026-02-22T17:46:12.226858Z","steps":["trace[571600392] 'process raft request' (duration: 221.629541ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:12.226951Z","caller":"traceutil/trace.go:171","msg":"trace[57371081] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"225.043549ms","start":"2026-02-22T17:46:12.001862Z","end":"2026-02-22T17:46:12.226906Z","steps":["trace[57371081] 'process raft request' (duration: 207.548481ms)","trace[57371081] 'compare' (duration: 17.259452ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:12.654618Z","caller":"traceutil/trace.go:171","msg":"trace[693079574] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"116.196818ms","start":"2026-02-22T17:46:12.538403Z","end":"2026-02-22T17:46:12.654599Z","steps":["trace[693079574] 'process raft request' (duration: 116.080825ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:16.452934Z","caller":"traceutil/trace.go:171","msg":"trace[1045054832] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"252.251877ms","start":"2026-02-22T17:46:16.200657Z","end":"2026-02-22T17:46:16.452909Z","steps":["trace[1045054832] 'read index received' (duration: 252.011821ms)","trace[1045054832] 'applied index is now lower than readState.Index' (duration: 239.186µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:16.453043Z","caller":"traceutil/trace.go:171","msg":"trace[1688817718] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"278.997023ms","start":"2026-02-22T17:46:16.174024Z","end":"2026-02-22T17:46:16.453021Z","steps":["trace[1688817718] 'process raft request' (duration: 278.738557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.453127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.441961ms","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-22T17:46:16.453168Z","caller":"traceutil/trace.go:171","msg":"trace[198660001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"252.532682ms","start":"2026-02-22T17:46:16.200625Z","end":"2026-02-22T17:46:16.453158Z","steps":["trace[198660001] 'agreement among raft nodes before linearized reading' (duration: 252.40746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.453409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.88933ms","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-22T17:46:16.45353Z","caller":"traceutil/trace.go:171","msg":"trace[1227737930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:423; }","duration":"212.049894ms","start":"2026-02-22T17:46:16.241449Z","end":"2026-02-22T17:46:16.453499Z","steps":["trace[1227737930] 'agreement among raft nodes before linearized reading' (duration: 211.85487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.454041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.002101ms","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-22T17:46:16.454163Z","caller":"traceutil/trace.go:171","msg":"trace[587241834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"215.155085ms","start":"2026-02-22T17:46:16.238978Z","end":"2026-02-22T17:46:16.454133Z","steps":["trace[587241834] 'agreement among raft nodes before linearized reading' (duration: 214.296365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.75479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.996567ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:16.754908Z","caller":"traceutil/trace.go:171","msg":"trace[921297431] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"297.679307ms","start":"2026-02-22T17:46:16.457211Z","end":"2026-02-22T17:46:16.75489Z","steps":["trace[921297431] 'process raft request' (duration: 145.985444ms)","trace[921297431] 'compare' (duration: 150.888155ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:16.784346Z","caller":"traceutil/trace.go:171","msg":"trace[2143702272] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:439; }","duration":"112.910493ms","start":"2026-02-22T17:46:16.671423Z","end":"2026-02-22T17:46:16.784334Z","steps":["trace[2143702272] 'read index received' (duration: 112.563795ms)","trace[2143702272] 'applied index is now lower than readState.Index' (duration: 343.468µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:16.784409Z","caller":"traceutil/trace.go:171","msg":"trace[2052695759] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"326.634924ms","start":"2026-02-22T17:46:16.457752Z","end":"2026-02-22T17:46:16.784387Z","steps":["trace[2052695759] 'process raft request' (duration: 326.387219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.784463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.039876ms","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-22T17:46:16.784498Z","caller":"traceutil/trace.go:171","msg":"trace[406331564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:426; }","duration":"113.141518ms","start":"2026-02-22T17:46:16.671346Z","end":"2026-02-22T17:46:16.784487Z","steps":["trace[406331564] 'agreement among raft nodes before linearized reading' (duration: 113.044046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.784523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:16.457748Z","time spent":"326.706417ms","remote":"127.0.0.1:43016","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-22T17:46:16.784256Z","caller":"traceutil/trace.go:171","msg":"trace[1490212605] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"326.752737ms","start":"2026-02-22T17:46:16.457452Z","end":"2026-02-22T17:46:16.784205Z","steps":["trace[1490212605] 'process raft request' (duration: 326.594613ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:16.785016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:16.457437Z","time spent":"327.222398ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:46:17.34395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.730287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:17.344021Z","caller":"traceutil/trace.go:171","msg":"trace[1232702273] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"555.892627ms","start":"2026-02-22T17:46:16.788116Z","end":"2026-02-22T17:46:17.344008Z","steps":["trace[1232702273] 'process raft request' (duration: 325.041527ms)","trace[1232702273] 'compare' (duration: 230.660866ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:17.344054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:16.788098Z","time spent":"555.941979ms","remote":"127.0.0.1:43016","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-22T17:46:21.479833Z","caller":"traceutil/trace.go:171","msg":"trace[1119518978] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"120.412494ms","start":"2026-02-22T17:46:21.359376Z","end":"2026-02-22T17:46:21.479789Z","steps":["trace[1119518978] 'process raft request' (duration: 120.062166ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:29.882382Z","caller":"traceutil/trace.go:171","msg":"trace[136881504] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"770.902557ms","start":"2026-02-22T17:46:29.111453Z","end":"2026-02-22T17:46:29.882355Z","steps":["trace[136881504] 'process raft request' (duration: 770.733133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:29.882545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:29.111436Z","time spent":"771.02736ms","remote":"127.0.0.1:42942","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:46:30.893618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.704029ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-22T17:46:30.893717Z","caller":"traceutil/trace.go:171","msg":"trace[865185598] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:508; }","duration":"1.512320206s","start":"2026-02-22T17:46:29.381378Z","end":"2026-02-22T17:46:30.893698Z","steps":["trace[865185598] 'read index received' (duration: 500.864724ms)","trace[865185598] 'applied index is now lower than readState.Index' (duration: 1.011454112s)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:30.893905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.369740078s","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-22T17:46:30.893942Z","caller":"traceutil/trace.go:171","msg":"trace[193273552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:492; }","duration":"1.36982574s","start":"2026-02-22T17:46:29.524105Z","end":"2026-02-22T17:46:30.89393Z","steps":["trace[193273552] 'agreement among raft nodes before linearized reading' (duration: 1.369720888s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:30.893978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:29.52408Z","time spent":"1.369890582s","remote":"127.0.0.1:43016","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-22T17:46:30.893986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512559111s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:30.894095Z","caller":"traceutil/trace.go:171","msg":"trace[1719666997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:492; }","duration":"1.512729416s","start":"2026-02-22T17:46:29.381342Z","end":"2026-02-22T17:46:30.894072Z","steps":["trace[1719666997] 'agreement among raft nodes before linearized reading' (duration: 1.5124884s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:30.894144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:29.381327Z","time spent":"1.512810757s","remote":"127.0.0.1:42734","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-22T17:46:31.404498Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896009304,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:46:31.465456Z","caller":"traceutil/trace.go:171","msg":"trace[572186646] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"561.387369ms","start":"2026-02-22T17:46:30.904029Z","end":"2026-02-22T17:46:31.465416Z","steps":["trace[572186646] 'read index received' (duration: 561.080902ms)","trace[572186646] 'applied index is now lower than readState.Index' (duration: 305.337µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:31.465501Z","caller":"traceutil/trace.go:171","msg":"trace[1437084499] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"567.581683ms","start":"2026-02-22T17:46:30.897874Z","end":"2026-02-22T17:46:31.465456Z","steps":["trace[1437084499] 'process raft request' (duration: 567.314357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:31.46562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.561853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:31.465658Z","caller":"traceutil/trace.go:171","msg":"trace[1583232910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"561.648566ms","start":"2026-02-22T17:46:30.903997Z","end":"2026-02-22T17:46:31.465646Z","steps":["trace[1583232910] 'agreement among raft nodes before linearized reading' (duration: 561.562044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:31.465682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:30.897837Z","time spent":"567.744207ms","remote":"127.0.0.1:43016","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-22T17:46:31.465698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:30.903983Z","time spent":"561.706177ms","remote":"127.0.0.1:42734","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-22T17:46:31.465821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.643501ms","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-22T17:46:31.465909Z","caller":"traceutil/trace.go:171","msg":"trace[1372501753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:493; }","duration":"532.773624ms","start":"2026-02-22T17:46:30.93311Z","end":"2026-02-22T17:46:31.465884Z","steps":["trace[1372501753] 'agreement among raft nodes before linearized reading' (duration: 532.60193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:31.465975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:30.933093Z","time spent":"532.869876ms","remote":"127.0.0.1:43016","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-22T17:46:31.466085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.905547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-22T17:46:31.466129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.880834ms","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-22T17:46:31.466138Z","caller":"traceutil/trace.go:171","msg":"trace[993763954] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"486.976179ms","start":"2026-02-22T17:46:30.979149Z","end":"2026-02-22T17:46:31.466125Z","steps":["trace[993763954] 'agreement among raft nodes before linearized reading' (duration: 486.875226ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:31.466167Z","caller":"traceutil/trace.go:171","msg":"trace[255892773] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"522.943925ms","start":"2026-02-22T17:46:30.943212Z","end":"2026-02-22T17:46:31.466156Z","steps":["trace[255892773] 'agreement among raft nodes before linearized reading' (duration: 522.874744ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:31.466171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:30.979139Z","time spent":"487.02568ms","remote":"127.0.0.1:43016","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-22T17:46:31.466207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:30.943201Z","time spent":"522.999476ms","remote":"127.0.0.1:43016","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-22T17:46:31.790974Z","caller":"traceutil/trace.go:171","msg":"trace[1353356570] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"320.884686ms","start":"2026-02-22T17:46:31.470062Z","end":"2026-02-22T17:46:31.790946Z","steps":["trace[1353356570] 'process raft request' (duration: 237.301331ms)","trace[1353356570] 'compare' (duration: 83.216876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:31.791138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:31.470041Z","time spent":"321.020818ms","remote":"127.0.0.1:43016","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-22T17:46:31.836185Z","caller":"traceutil/trace.go:171","msg":"trace[150403474] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"365.163496ms","start":"2026-02-22T17:46:31.470994Z","end":"2026-02-22T17:46:31.836158Z","steps":["trace[150403474] 'process raft request' (duration: 365.126715ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:31.836261Z","caller":"traceutil/trace.go:171","msg":"trace[980029853] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"365.585246ms","start":"2026-02-22T17:46:31.470647Z","end":"2026-02-22T17:46:31.836232Z","steps":["trace[980029853] 'process raft request' (duration: 365.364701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:31.836358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:31.470984Z","time spent":"365.267288ms","remote":"127.0.0.1:43016","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-22T17:46:31.836462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:31.470623Z","time spent":"365.719709ms","remote":"127.0.0.1:43016","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-22T17:46:33.325732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:32.180821Z","time spent":"1.144903498s","remote":"127.0.0.1:48270","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-22T17:46:33.326206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.609177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:33.326284Z","caller":"traceutil/trace.go:171","msg":"trace[2125670211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"944.73086ms","start":"2026-02-22T17:46:32.381535Z","end":"2026-02-22T17:46:33.326266Z","steps":["trace[2125670211] 'range keys from in-memory index tree' (duration: 944.492825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:33.326323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:32.38151Z","time spent":"944.804912ms","remote":"127.0.0.1:42734","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-22T17:46:34.248686Z","caller":"traceutil/trace.go:171","msg":"trace[1006469976] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"774.94851ms","start":"2026-02-22T17:46:33.473714Z","end":"2026-02-22T17:46:34.248663Z","steps":["trace[1006469976] 'process raft request' (duration: 774.802816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:34.24994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:33.473692Z","time spent":"776.070575ms","remote":"127.0.0.1:43016","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-22T17:46:34.518627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.238245ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:34.518755Z","caller":"traceutil/trace.go:171","msg":"trace[1200043798] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:515; }","duration":"724.353882ms","start":"2026-02-22T17:46:33.794378Z","end":"2026-02-22T17:46:34.518732Z","steps":["trace[1200043798] 'read index received' (duration: 454.507494ms)","trace[1200043798] 'applied index is now lower than readState.Index' (duration: 269.845128ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:34.518918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.537667ms","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-22T17:46:34.518976Z","caller":"traceutil/trace.go:171","msg":"trace[440934888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:498; }","duration":"724.63619ms","start":"2026-02-22T17:46:33.794324Z","end":"2026-02-22T17:46:34.518961Z","steps":["trace[440934888] 'agreement among raft nodes before linearized reading' (duration: 724.528977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:34.518997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.658263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:34.519034Z","caller":"traceutil/trace.go:171","msg":"trace[1821705674] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"137.728124ms","start":"2026-02-22T17:46:34.381294Z","end":"2026-02-22T17:46:34.519022Z","steps":["trace[1821705674] 'agreement among raft nodes before linearized reading' (duration: 137.673323ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:34.519084Z","caller":"traceutil/trace.go:171","msg":"trace[858588355] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.040795835s","start":"2026-02-22T17:46:33.478273Z","end":"2026-02-22T17:46:34.519068Z","steps":["trace[858588355] 'process raft request' (duration: 878.002137ms)","trace[858588355] 'compare' (duration: 162.088632ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:34.519197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:33.478256Z","time spent":"1.040893677s","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:46:34.519298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.683852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:34.519355Z","caller":"traceutil/trace.go:171","msg":"trace[1420377340] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"351.777744ms","start":"2026-02-22T17:46:34.167564Z","end":"2026-02-22T17:46:34.519342Z","steps":["trace[1420377340] 'agreement among raft nodes before linearized reading' (duration: 351.700143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:34.519406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:34.167542Z","time spent":"351.853166ms","remote":"127.0.0.1:42734","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-22T17:46:34.519035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:33.794304Z","time spent":"724.717491ms","remote":"127.0.0.1:43016","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-22T17:46:34.519757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.775768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-22T17:46:34.518932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.09705ms","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-22T17:46:34.51983Z","caller":"traceutil/trace.go:171","msg":"trace[53351285] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:498; }","duration":"395.836919ms","start":"2026-02-22T17:46:34.123966Z","end":"2026-02-22T17:46:34.519803Z","steps":["trace[53351285] 'agreement among raft nodes before linearized reading' (duration: 395.739097ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:34.519852Z","caller":"traceutil/trace.go:171","msg":"trace[695998716] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:498; }","duration":"680.044342ms","start":"2026-02-22T17:46:33.839793Z","end":"2026-02-22T17:46:34.519837Z","steps":["trace[695998716] 'agreement among raft nodes before linearized reading' (duration: 679.09578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:34.519882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:34.123921Z","time spent":"395.949262ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-02-22T17:46:34.519912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:33.839775Z","time spent":"680.122033ms","remote":"127.0.0.1:43016","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-22T17:46:34.520174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.297477ms","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-22T17:46:34.520232Z","caller":"traceutil/trace.go:171","msg":"trace[1860492080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:498; }","duration":"680.37613ms","start":"2026-02-22T17:46:33.839843Z","end":"2026-02-22T17:46:34.520219Z","steps":["trace[1860492080] 'agreement among raft nodes before linearized reading' (duration: 680.286138ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:34.520274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:33.839838Z","time spent":"680.425371ms","remote":"127.0.0.1:43016","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-22T17:46:36.233252Z","caller":"traceutil/trace.go:171","msg":"trace[846117982] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"100.189912ms","start":"2026-02-22T17:46:36.133041Z","end":"2026-02-22T17:46:36.233231Z","steps":["trace[846117982] 'process raft request' (duration: 100.053329ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:37.551507Z","caller":"traceutil/trace.go:171","msg":"trace[2083377495] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"424.816284ms","start":"2026-02-22T17:46:37.126673Z","end":"2026-02-22T17:46:37.551489Z","steps":["trace[2083377495] 'read index received' (duration: 424.688721ms)","trace[2083377495] 'applied index is now lower than readState.Index' (duration: 126.643µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:37.551548Z","caller":"traceutil/trace.go:171","msg":"trace[2055527334] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"581.281755ms","start":"2026-02-22T17:46:36.97024Z","end":"2026-02-22T17:46:37.551522Z","steps":["trace[2055527334] 'process raft request' (duration: 581.130161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:37.551666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:36.970218Z","time spent":"581.388997ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":834,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:46:37.551676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.994759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-62ghd\" ","response":"range_response_count:1 size:17078"} {"level":"info","ts":"2026-02-22T17:46:37.5517Z","caller":"traceutil/trace.go:171","msg":"trace[968432106] range","detail":"{range_begin:/registry/pods/kube-system/cilium-62ghd; range_end:; response_count:1; response_revision:513; }","duration":"425.06902ms","start":"2026-02-22T17:46:37.126626Z","end":"2026-02-22T17:46:37.551695Z","steps":["trace[968432106] 'agreement among raft nodes before linearized reading' (duration: 424.932387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:37.551722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:37.126612Z","time spent":"425.104841ms","remote":"127.0.0.1:42942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17102,"request content":"key:\"/registry/pods/kube-system/cilium-62ghd\" "} {"level":"info","ts":"2026-02-22T17:46:38.272664Z","caller":"traceutil/trace.go:171","msg":"trace[1204618859] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"203.463153ms","start":"2026-02-22T17:46:38.069178Z","end":"2026-02-22T17:46:38.272641Z","steps":["trace[1204618859] 'process raft request' (duration: 203.383721ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.272928Z","caller":"traceutil/trace.go:171","msg":"trace[1999332121] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"208.192623ms","start":"2026-02-22T17:46:38.064724Z","end":"2026-02-22T17:46:38.272917Z","steps":["trace[1999332121] 'process raft request' (duration: 207.715352ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.27298Z","caller":"traceutil/trace.go:171","msg":"trace[196607800] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"203.666028ms","start":"2026-02-22T17:46:38.069289Z","end":"2026-02-22T17:46:38.272955Z","steps":["trace[196607800] 'process raft request' (duration: 203.587716ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.273162Z","caller":"traceutil/trace.go:171","msg":"trace[1905938926] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"203.892853ms","start":"2026-02-22T17:46:38.06926Z","end":"2026-02-22T17:46:38.273152Z","steps":["trace[1905938926] 'process raft request' (duration: 203.34761ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.433787Z","caller":"traceutil/trace.go:171","msg":"trace[1279124337] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"346.815758ms","start":"2026-02-22T17:46:38.086942Z","end":"2026-02-22T17:46:38.433758Z","steps":["trace[1279124337] 'process raft request' (duration: 300.565882ms)","trace[1279124337] 'compare' (duration: 45.849117ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:38.433843Z","caller":"traceutil/trace.go:171","msg":"trace[856939489] linearizableReadLoop","detail":"{readStateIndex:546; appliedIndex:541; }","duration":"301.909083ms","start":"2026-02-22T17:46:38.131915Z","end":"2026-02-22T17:46:38.433824Z","steps":["trace[856939489] 'read index received' (duration: 140.654841ms)","trace[856939489] 'applied index is now lower than readState.Index' (duration: 161.253402ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:38.433933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:38.08693Z","time spent":"346.939521ms","remote":"127.0.0.1:43294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:46:38.434108Z","caller":"traceutil/trace.go:171","msg":"trace[263378513] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"345.872346ms","start":"2026-02-22T17:46:38.088224Z","end":"2026-02-22T17:46:38.434096Z","steps":["trace[263378513] 'process raft request' (duration: 345.404025ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.434208Z","caller":"traceutil/trace.go:171","msg":"trace[256130292] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"175.011931ms","start":"2026-02-22T17:46:38.259185Z","end":"2026-02-22T17:46:38.434197Z","steps":["trace[256130292] 'process raft request' (duration: 174.603692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:38.434232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:38.088217Z","time spent":"345.920997ms","remote":"127.0.0.1:42798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:46:38.434376Z","caller":"traceutil/trace.go:171","msg":"trace[1205093342] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"298.720659ms","start":"2026-02-22T17:46:38.135646Z","end":"2026-02-22T17:46:38.434367Z","steps":["trace[1205093342] 'process raft request' (duration: 298.055404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:38.435961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.910039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:46:38.436031Z","caller":"traceutil/trace.go:171","msg":"trace[1962031602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"174.9953ms","start":"2026-02-22T17:46:38.261011Z","end":"2026-02-22T17:46:38.436006Z","steps":["trace[1962031602] 'agreement among raft nodes before linearized reading' (duration: 174.887138ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.436151Z","caller":"traceutil/trace.go:171","msg":"trace[1423489892] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"159.657155ms","start":"2026-02-22T17:46:38.276456Z","end":"2026-02-22T17:46:38.436113Z","steps":["trace[1423489892] 'process raft request' (duration: 159.386739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:38.43638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.456233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-xqs5c\" ","response":"range_response_count:1 size:4639"} {"level":"info","ts":"2026-02-22T17:46:38.43642Z","caller":"traceutil/trace.go:171","msg":"trace[1511032628] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-xqs5c; range_end:; response_count:1; response_revision:529; }","duration":"304.525924ms","start":"2026-02-22T17:46:38.131884Z","end":"2026-02-22T17:46:38.43641Z","steps":["trace[1511032628] 'agreement among raft nodes before linearized reading' (duration: 302.442775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:38.436455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:38.131873Z","time spent":"304.574295ms","remote":"127.0.0.1:42942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4663,"request content":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-xqs5c\" "} {"level":"info","ts":"2026-02-22T17:46:38.853512Z","caller":"traceutil/trace.go:171","msg":"trace[155080674] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"159.377388ms","start":"2026-02-22T17:46:38.694108Z","end":"2026-02-22T17:46:38.853485Z","steps":["trace[155080674] 'process raft request' (duration: 159.229335ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.853799Z","caller":"traceutil/trace.go:171","msg":"trace[1506875050] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"159.581442ms","start":"2026-02-22T17:46:38.694183Z","end":"2026-02-22T17:46:38.853764Z","steps":["trace[1506875050] 'process raft request' (duration: 159.268445ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.855167Z","caller":"traceutil/trace.go:171","msg":"trace[365942074] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"158.438885ms","start":"2026-02-22T17:46:38.696703Z","end":"2026-02-22T17:46:38.855142Z","steps":["trace[365942074] 'process raft request' (duration: 158.259931ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:38.85535Z","caller":"traceutil/trace.go:171","msg":"trace[1357490995] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"158.495727ms","start":"2026-02-22T17:46:38.696839Z","end":"2026-02-22T17:46:38.855335Z","steps":["trace[1357490995] 'process raft request' (duration: 158.252381ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:40.272244Z","caller":"traceutil/trace.go:171","msg":"trace[630272617] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"103.228331ms","start":"2026-02-22T17:46:40.168993Z","end":"2026-02-22T17:46:40.272222Z","steps":["trace[630272617] 'process raft request' (duration: 102.975326ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:40.737841Z","caller":"traceutil/trace.go:171","msg":"trace[99777860] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"150.723236ms","start":"2026-02-22T17:46:40.587094Z","end":"2026-02-22T17:46:40.737817Z","steps":["trace[99777860] 'process raft request' (duration: 150.551472ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:40.805715Z","caller":"traceutil/trace.go:171","msg":"trace[204808471] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"143.853387ms","start":"2026-02-22T17:46:40.66184Z","end":"2026-02-22T17:46:40.805694Z","steps":["trace[204808471] 'process raft request' (duration: 143.241172ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.357634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896009433,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:46:41.400288Z","caller":"traceutil/trace.go:171","msg":"trace[1996849072] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"590.249401ms","start":"2026-02-22T17:46:40.810016Z","end":"2026-02-22T17:46:41.400265Z","steps":["trace[1996849072] 'process raft request' (duration: 589.77336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.40043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.809992Z","time spent":"590.361623ms","remote":"127.0.0.1:43016","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-22T17:46:41.421501Z","caller":"traceutil/trace.go:171","msg":"trace[73696687] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"603.783235ms","start":"2026-02-22T17:46:40.81767Z","end":"2026-02-22T17:46:41.421453Z","steps":["trace[73696687] 'process raft request' (duration: 603.600991ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.421613Z","caller":"traceutil/trace.go:171","msg":"trace[1296944629] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"601.063702ms","start":"2026-02-22T17:46:40.820526Z","end":"2026-02-22T17:46:41.42159Z","steps":["trace[1296944629] 'process raft request' (duration: 600.827197ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.421721Z","caller":"traceutil/trace.go:171","msg":"trace[1118902467] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"159.385398ms","start":"2026-02-22T17:46:41.262283Z","end":"2026-02-22T17:46:41.421669Z","steps":["trace[1118902467] 'process raft request' (duration: 159.228985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.421783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.81766Z","time spent":"603.93239ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:46:41.421816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.820517Z","time spent":"601.162065ms","remote":"127.0.0.1:42798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24220,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:46:41.421972Z","caller":"traceutil/trace.go:171","msg":"trace[179555546] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:586; }","duration":"564.995334ms","start":"2026-02-22T17:46:40.856963Z","end":"2026-02-22T17:46:41.421959Z","steps":["trace[179555546] 'read index received' (duration: 542.946261ms)","trace[179555546] 'applied index is now lower than readState.Index' (duration: 22.047943ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:41.422137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.170658ms","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-22T17:46:41.422168Z","caller":"traceutil/trace.go:171","msg":"trace[1853190768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:570; }","duration":"565.24033ms","start":"2026-02-22T17:46:40.856918Z","end":"2026-02-22T17:46:41.422158Z","steps":["trace[1853190768] 'agreement among raft nodes before linearized reading' (duration: 565.111547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.422198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.856896Z","time spent":"565.29514ms","remote":"127.0.0.1:43016","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-22T17:46:41.422318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.076295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"warn","ts":"2026-02-22T17:46:41.42235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.823591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"warn","ts":"2026-02-22T17:46:41.422358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.745233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-62ghd\" ","response":"range_response_count:1 size:17918"} {"level":"info","ts":"2026-02-22T17:46:41.422403Z","caller":"traceutil/trace.go:171","msg":"trace[357107552] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"365.909873ms","start":"2026-02-22T17:46:41.056478Z","end":"2026-02-22T17:46:41.422388Z","steps":["trace[357107552] 'agreement among raft nodes before linearized reading' (duration: 365.78252ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.422407Z","caller":"traceutil/trace.go:171","msg":"trace[1198284840] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:570; }","duration":"565.204649ms","start":"2026-02-22T17:46:40.857178Z","end":"2026-02-22T17:46:41.422383Z","steps":["trace[1198284840] 'agreement among raft nodes before linearized reading' (duration: 564.993294ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.422434Z","caller":"traceutil/trace.go:171","msg":"trace[214363873] range","detail":"{range_begin:/registry/pods/kube-system/cilium-62ghd; range_end:; response_count:1; response_revision:570; }","duration":"256.872035ms","start":"2026-02-22T17:46:41.165536Z","end":"2026-02-22T17:46:41.422408Z","steps":["trace[214363873] 'agreement among raft nodes before linearized reading' (duration: 256.64478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.422445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:41.056455Z","time spent":"365.974954ms","remote":"127.0.0.1:42798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4107,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-22T17:46:41.422477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.857171Z","time spent":"565.27798ms","remote":"127.0.0.1:42798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-02-22T17:46:41.422483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.540023ms","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-22T17:46:41.422519Z","caller":"traceutil/trace.go:171","msg":"trace[810545172] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"564.622775ms","start":"2026-02-22T17:46:40.857886Z","end":"2026-02-22T17:46:41.422509Z","steps":["trace[810545172] 'agreement among raft nodes before linearized reading' (duration: 564.530193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:41.422548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:40.857875Z","time spent":"564.665856ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:46:41.763238Z","caller":"traceutil/trace.go:171","msg":"trace[396410234] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"249.571826ms","start":"2026-02-22T17:46:41.513645Z","end":"2026-02-22T17:46:41.763216Z","steps":["trace[396410234] 'process raft request' (duration: 168.200903ms)","trace[396410234] 'compare' (duration: 80.919563ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:41.76532Z","caller":"traceutil/trace.go:171","msg":"trace[681589146] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"230.446161ms","start":"2026-02-22T17:46:41.534864Z","end":"2026-02-22T17:46:41.76531Z","steps":["trace[681589146] 'process raft request' (duration: 230.367479ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.800119Z","caller":"traceutil/trace.go:171","msg":"trace[1821630432] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"116.150163ms","start":"2026-02-22T17:46:41.683952Z","end":"2026-02-22T17:46:41.800103Z","steps":["trace[1821630432] 'process raft request' (duration: 115.991619ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:41.952592Z","caller":"traceutil/trace.go:171","msg":"trace[1094765421] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"110.238664ms","start":"2026-02-22T17:46:41.842301Z","end":"2026-02-22T17:46:41.95254Z","steps":["trace[1094765421] 'process raft request' (duration: 20.600028ms)","trace[1094765421] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; req_size:188467; } (duration: 88.878057ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:42.232468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.409574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:42.232768Z","caller":"traceutil/trace.go:171","msg":"trace[438731600] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"214.563092ms","start":"2026-02-22T17:46:42.018175Z","end":"2026-02-22T17:46:42.232738Z","steps":["trace[438731600] 'process raft request' (duration: 89.794529ms)","trace[438731600] 'compare' (duration: 124.281451ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:42.234569Z","caller":"traceutil/trace.go:171","msg":"trace[576355326] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:599; }","duration":"177.241074ms","start":"2026-02-22T17:46:42.057289Z","end":"2026-02-22T17:46:42.23453Z","steps":["trace[576355326] 'read index received' (duration: 50.68906ms)","trace[576355326] 'applied index is now lower than readState.Index' (duration: 126.550674ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:42.234667Z","caller":"traceutil/trace.go:171","msg":"trace[200231158] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"203.755911ms","start":"2026-02-22T17:46:42.030879Z","end":"2026-02-22T17:46:42.234634Z","steps":["trace[200231158] 'process raft request' (duration: 203.30439ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:42.234908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.585462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-02-22T17:46:42.234973Z","caller":"traceutil/trace.go:171","msg":"trace[787333215] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:581; }","duration":"177.710995ms","start":"2026-02-22T17:46:42.057246Z","end":"2026-02-22T17:46:42.234957Z","steps":["trace[787333215] 'agreement among raft nodes before linearized reading' (duration: 177.450479ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:42.727421Z","caller":"traceutil/trace.go:171","msg":"trace[816095226] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"128.817037ms","start":"2026-02-22T17:46:42.598572Z","end":"2026-02-22T17:46:42.727389Z","steps":["trace[816095226] 'process raft request' (duration: 95.992423ms)","trace[816095226] 'compare' (duration: 32.575998ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:43.435046Z","caller":"traceutil/trace.go:171","msg":"trace[2376364] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"164.656761ms","start":"2026-02-22T17:46:43.270357Z","end":"2026-02-22T17:46:43.435014Z","steps":["trace[2376364] 'process raft request' (duration: 100.382635ms)","trace[2376364] 'compare' (duration: 64.080231ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:43.592329Z","caller":"traceutil/trace.go:171","msg":"trace[117749480] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"150.454521ms","start":"2026-02-22T17:46:43.441818Z","end":"2026-02-22T17:46:43.592273Z","steps":["trace[117749480] 'process raft request' (duration: 150.319117ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:43.842724Z","caller":"traceutil/trace.go:171","msg":"trace[1114387726] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"365.31933ms","start":"2026-02-22T17:46:43.477379Z","end":"2026-02-22T17:46:43.842698Z","steps":["trace[1114387726] 'read index received' (duration: 114.73262ms)","trace[1114387726] 'applied index is now lower than readState.Index' (duration: 250.5854ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:43.84286Z","caller":"traceutil/trace.go:171","msg":"trace[799729520] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"397.132729ms","start":"2026-02-22T17:46:43.445717Z","end":"2026-02-22T17:46:43.842849Z","steps":["trace[799729520] 'process raft request' (duration: 396.826502ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:43.842963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:43.445691Z","time spent":"397.194701ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4161,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:46:43.843131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.316689ms","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-22T17:46:43.843204Z","caller":"traceutil/trace.go:171","msg":"trace[834025607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"313.495544ms","start":"2026-02-22T17:46:43.529688Z","end":"2026-02-22T17:46:43.843183Z","steps":["trace[834025607] 'agreement among raft nodes before linearized reading' (duration: 313.305279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:43.843243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:43.529665Z","time spent":"313.568945ms","remote":"127.0.0.1:43016","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-22T17:46:43.843367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.983985ms","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-22T17:46:43.843404Z","caller":"traceutil/trace.go:171","msg":"trace[1295987548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:594; }","duration":"366.062687ms","start":"2026-02-22T17:46:43.47733Z","end":"2026-02-22T17:46:43.843392Z","steps":["trace[1295987548] 'agreement among raft nodes before linearized reading' (duration: 365.933694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:43.843433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:43.477317Z","time spent":"366.109828ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:46:52.335659Z","caller":"traceutil/trace.go:171","msg":"trace[1990116797] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"416.450339ms","start":"2026-02-22T17:46:51.919184Z","end":"2026-02-22T17:46:52.335635Z","steps":["trace[1990116797] 'process raft request' (duration: 416.321956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:52.3358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:51.919167Z","time spent":"416.558052ms","remote":"127.0.0.1:43016","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-22T17:46:53.526713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.64375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:53.52682Z","caller":"traceutil/trace.go:171","msg":"trace[1472921648] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"1.142495979s","start":"2026-02-22T17:46:52.384306Z","end":"2026-02-22T17:46:53.526802Z","steps":["trace[1472921648] 'read index received' (duration: 496.589102ms)","trace[1472921648] 'applied index is now lower than readState.Index' (duration: 645.905527ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:53.526909Z","caller":"traceutil/trace.go:171","msg":"trace[1817643280] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.607648901s","start":"2026-02-22T17:46:51.919246Z","end":"2026-02-22T17:46:53.526895Z","steps":["trace[1817643280] 'process raft request' (duration: 961.682713ms)","trace[1817643280] 'compare' (duration: 645.519868ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:53.526961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:51.919233Z","time spent":"1.607699522s","remote":"127.0.0.1:43016","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-22T17:46:53.527159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142854507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:53.527216Z","caller":"traceutil/trace.go:171","msg":"trace[812519903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:674; }","duration":"1.142926739s","start":"2026-02-22T17:46:52.384267Z","end":"2026-02-22T17:46:53.527194Z","steps":["trace[812519903] 'agreement among raft nodes before linearized reading' (duration: 1.142862898s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:53.527246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:52.38425Z","time spent":"1.14298839s","remote":"127.0.0.1:42734","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-22T17:46:53.527418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038043719s","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":"warn","ts":"2026-02-22T17:46:53.527478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.607852ms","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-22T17:46:53.527519Z","caller":"traceutil/trace.go:171","msg":"trace[1350117920] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:674; }","duration":"1.038163422s","start":"2026-02-22T17:46:52.489329Z","end":"2026-02-22T17:46:53.527492Z","steps":["trace[1350117920] 'agreement among raft nodes before linearized reading' (duration: 1.037862005s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:53.527561Z","caller":"traceutil/trace.go:171","msg":"trace[317719005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:674; }","duration":"805.716565ms","start":"2026-02-22T17:46:52.721821Z","end":"2026-02-22T17:46:53.527538Z","steps":["trace[317719005] 'agreement among raft nodes before linearized reading' (duration: 805.465519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:53.52758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:52.489292Z","time spent":"1.038279405s","remote":"127.0.0.1:43016","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-22T17:46:53.527618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:52.721805Z","time spent":"805.796357ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:46:53.644935Z","caller":"traceutil/trace.go:171","msg":"trace[1713684849] linearizableReadLoop","detail":"{readStateIndex:700; appliedIndex:698; }","duration":"110.065091ms","start":"2026-02-22T17:46:53.534847Z","end":"2026-02-22T17:46:53.644912Z","steps":["trace[1713684849] 'read index received' (duration: 105.370621ms)","trace[1713684849] 'applied index is now lower than readState.Index' (duration: 4.69319ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:53.644973Z","caller":"traceutil/trace.go:171","msg":"trace[1817137225] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"113.134973ms","start":"2026-02-22T17:46:53.531815Z","end":"2026-02-22T17:46:53.64495Z","steps":["trace[1817137225] 'process raft request' (duration: 108.339341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:53.645082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.221474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:53.64579Z","caller":"traceutil/trace.go:171","msg":"trace[793867025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"110.906931ms","start":"2026-02-22T17:46:53.534816Z","end":"2026-02-22T17:46:53.645723Z","steps":["trace[793867025] 'agreement among raft nodes before linearized reading' (duration: 110.210774ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:53.645059Z","caller":"traceutil/trace.go:171","msg":"trace[1724826651] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"112.905617ms","start":"2026-02-22T17:46:53.532102Z","end":"2026-02-22T17:46:53.645008Z","steps":["trace[1724826651] 'process raft request' (duration: 112.767664ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:56.558247Z","caller":"traceutil/trace.go:171","msg":"trace[54243321] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"206.531615ms","start":"2026-02-22T17:46:56.351692Z","end":"2026-02-22T17:46:56.558224Z","steps":["trace[54243321] 'process raft request' (duration: 206.381322ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:56.558247Z","caller":"traceutil/trace.go:171","msg":"trace[281930154] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:710; }","duration":"160.948414ms","start":"2026-02-22T17:46:56.397271Z","end":"2026-02-22T17:46:56.558219Z","steps":["trace[281930154] 'read index received' (duration: 160.935984ms)","trace[281930154] 'applied index is now lower than readState.Index' (duration: 7.25µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:56.558369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.075638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:56.558414Z","caller":"traceutil/trace.go:171","msg":"trace[1568113869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"161.161399ms","start":"2026-02-22T17:46:56.397238Z","end":"2026-02-22T17:46:56.558399Z","steps":["trace[1568113869] 'agreement among raft nodes before linearized reading' (duration: 161.061867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:56.762929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.606302ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-02-22T17:46:56.763032Z","caller":"traceutil/trace.go:171","msg":"trace[1242279976] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:710; }","duration":"203.409943ms","start":"2026-02-22T17:46:56.559604Z","end":"2026-02-22T17:46:56.763014Z","steps":["trace[1242279976] 'read index received' (duration: 74.719529ms)","trace[1242279976] 'applied index is now lower than readState.Index' (duration: 128.688534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:56.763105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.487504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:56.763157Z","caller":"traceutil/trace.go:171","msg":"trace[1262014175] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"203.575066ms","start":"2026-02-22T17:46:56.55957Z","end":"2026-02-22T17:46:56.763145Z","steps":["trace[1262014175] 'agreement among raft nodes before linearized reading' (duration: 203.491214ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:56.855555Z","caller":"traceutil/trace.go:171","msg":"trace[1612479242] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"108.786301ms","start":"2026-02-22T17:46:56.746744Z","end":"2026-02-22T17:46:56.85553Z","steps":["trace[1612479242] 'process raft request' (duration: 108.626737ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:57.285854Z","caller":"traceutil/trace.go:171","msg":"trace[830657983] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"521.82706ms","start":"2026-02-22T17:46:56.764004Z","end":"2026-02-22T17:46:57.285832Z","steps":["trace[830657983] 'process raft request' (duration: 521.636605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:57.285989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:56.763988Z","time spent":"521.936363ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":815,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:46:57.417415Z","caller":"traceutil/trace.go:171","msg":"trace[1685095185] linearizableReadLoop","detail":"{readStateIndex:714; appliedIndex:712; }","duration":"132.79437ms","start":"2026-02-22T17:46:57.284601Z","end":"2026-02-22T17:46:57.417395Z","steps":["trace[1685095185] 'read index received' (duration: 1.057125ms)","trace[1685095185] 'applied index is now lower than readState.Index' (duration: 131.736685ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:57.417553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.923822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:46:57.417579Z","caller":"traceutil/trace.go:171","msg":"trace[1853727436] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:688; }","duration":"133.034085ms","start":"2026-02-22T17:46:57.284541Z","end":"2026-02-22T17:46:57.417575Z","steps":["trace[1853727436] 'agreement among raft nodes before linearized reading' (duration: 132.930763ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:57.417808Z","caller":"traceutil/trace.go:171","msg":"trace[1547296359] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"165.165333ms","start":"2026-02-22T17:46:57.252626Z","end":"2026-02-22T17:46:57.417791Z","steps":["trace[1547296359] 'process raft request' (duration: 104.45583ms)","trace[1547296359] 'compare' (duration: 60.227372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:57.659365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.293152ms","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-22T17:46:57.659455Z","caller":"traceutil/trace.go:171","msg":"trace[1411500849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:688; }","duration":"115.439066ms","start":"2026-02-22T17:46:57.543996Z","end":"2026-02-22T17:46:57.659435Z","steps":["trace[1411500849] 'range keys from in-memory index tree' (duration: 115.094868ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:58.056214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.145684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:46:58.056401Z","caller":"traceutil/trace.go:171","msg":"trace[258279731] linearizableReadLoop","detail":"{readStateIndex:717; appliedIndex:714; }","duration":"375.074096ms","start":"2026-02-22T17:46:57.68131Z","end":"2026-02-22T17:46:58.056384Z","steps":["trace[258279731] 'read index received' (duration: 71.093164ms)","trace[258279731] 'applied index is now lower than readState.Index' (duration: 303.979522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:58.056582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.273ms","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-22T17:46:58.056617Z","caller":"traceutil/trace.go:171","msg":"trace[1045968278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:691; }","duration":"375.332992ms","start":"2026-02-22T17:46:57.681274Z","end":"2026-02-22T17:46:58.056607Z","steps":["trace[1045968278] 'agreement among raft nodes before linearized reading' (duration: 375.166878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:58.056651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:57.681248Z","time spent":"375.395584ms","remote":"127.0.0.1:43016","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-22T17:46:58.056841Z","caller":"traceutil/trace.go:171","msg":"trace[1978686441] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"393.514525ms","start":"2026-02-22T17:46:57.663315Z","end":"2026-02-22T17:46:58.05683Z","steps":["trace[1978686441] 'process raft request' (duration: 153.650595ms)","trace[1978686441] 'compare' (duration: 239.055541ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:58.056908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:57.663294Z","time spent":"393.582206ms","remote":"127.0.0.1:43016","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-22T17:46:58.056991Z","caller":"traceutil/trace.go:171","msg":"trace[2097203445] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"393.393253ms","start":"2026-02-22T17:46:57.663588Z","end":"2026-02-22T17:46:58.056982Z","steps":["trace[2097203445] 'process raft request' (duration: 392.741387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:58.057042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:57.663579Z","time spent":"393.438613ms","remote":"127.0.0.1:43016","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-22T17:46:58.479784Z","caller":"traceutil/trace.go:171","msg":"trace[2116807990] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:717; }","duration":"232.360956ms","start":"2026-02-22T17:46:58.247376Z","end":"2026-02-22T17:46:58.479737Z","steps":["trace[2116807990] 'read index received' (duration: 232.199902ms)","trace[2116807990] 'applied index is now lower than readState.Index' (duration: 159.964µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:46:58.479914Z","caller":"traceutil/trace.go:171","msg":"trace[654309346] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"418.207909ms","start":"2026-02-22T17:46:58.061696Z","end":"2026-02-22T17:46:58.479904Z","steps":["trace[654309346] 'process raft request' (duration: 417.894552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:58.479977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.046891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:46:58.480036Z","caller":"traceutil/trace.go:171","msg":"trace[1793411722] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:692; }","duration":"101.135143ms","start":"2026-02-22T17:46:58.378887Z","end":"2026-02-22T17:46:58.480022Z","steps":["trace[1793411722] 'agreement among raft nodes before linearized reading' (duration: 101.047151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:46:58.48004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:58.061676Z","time spent":"418.272501ms","remote":"127.0.0.1:43016","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-22T17:46:58.480228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.847777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:46:58.480257Z","caller":"traceutil/trace.go:171","msg":"trace[1279848011] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:692; }","duration":"232.915139ms","start":"2026-02-22T17:46:58.247336Z","end":"2026-02-22T17:46:58.480251Z","steps":["trace[1279848011] 'agreement among raft nodes before linearized reading' (duration: 232.862988ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:46:58.886034Z","caller":"traceutil/trace.go:171","msg":"trace[255753290] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"320.884025ms","start":"2026-02-22T17:46:58.565118Z","end":"2026-02-22T17:46:58.886002Z","steps":["trace[255753290] 'process raft request' (duration: 232.342925ms)","trace[255753290] 'compare' (duration: 88.411177ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:46:58.886211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:46:58.565091Z","time spent":"321.028249ms","remote":"127.0.0.1:43016","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-22T17:47:23.99834Z","caller":"traceutil/trace.go:171","msg":"trace[1595164592] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"109.980509ms","start":"2026-02-22T17:47:23.888334Z","end":"2026-02-22T17:47:23.998315Z","steps":["trace[1595164592] 'process raft request' (duration: 109.802855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.744168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.212943ms","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-22T17:47:24.744238Z","caller":"traceutil/trace.go:171","msg":"trace[467621968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"128.343686ms","start":"2026-02-22T17:47:24.615878Z","end":"2026-02-22T17:47:24.744221Z","steps":["trace[467621968] 'range keys from in-memory index tree' (duration: 128.003859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.744506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.414405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:24.744534Z","caller":"traceutil/trace.go:171","msg":"trace[1814604062] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"363.476236ms","start":"2026-02-22T17:47:24.381048Z","end":"2026-02-22T17:47:24.744525Z","steps":["trace[1814604062] 'range keys from in-memory index tree' (duration: 363.348083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.744563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.38103Z","time spent":"363.523358ms","remote":"127.0.0.1:42736","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-22T17:47:24.744703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.718742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-22T17:47:24.744779Z","caller":"traceutil/trace.go:171","msg":"trace[521147851] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:765; }","duration":"617.923796ms","start":"2026-02-22T17:47:24.126833Z","end":"2026-02-22T17:47:24.744757Z","steps":["trace[521147851] 'range keys from in-memory index tree' (duration: 617.524567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.744822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.126812Z","time spent":"618.000328ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.238\" "} {"level":"warn","ts":"2026-02-22T17:47:24.74493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.029523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-22T17:47:24.744962Z","caller":"traceutil/trace.go:171","msg":"trace[1230157293] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:765; }","duration":"558.074804ms","start":"2026-02-22T17:47:24.186878Z","end":"2026-02-22T17:47:24.744953Z","steps":["trace[1230157293] 'range keys from in-memory index tree' (duration: 557.956051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.744988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.186871Z","time spent":"558.110725ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:47:24.745019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.423018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:47:24.745022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.891506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:47:24.745097Z","caller":"traceutil/trace.go:171","msg":"trace[1795036549] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"578.56002ms","start":"2026-02-22T17:47:24.166516Z","end":"2026-02-22T17:47:24.745076Z","steps":["trace[1795036549] 'range keys from in-memory index tree' (duration: 578.330695ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:24.745107Z","caller":"traceutil/trace.go:171","msg":"trace[850525923] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:765; }","duration":"563.014389ms","start":"2026-02-22T17:47:24.182069Z","end":"2026-02-22T17:47:24.745084Z","steps":["trace[850525923] 'range keys from in-memory index tree' (duration: 562.733973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:24.74514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.166498Z","time spent":"578.634372ms","remote":"127.0.0.1:42734","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-22T17:47:24.745148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.182053Z","time spent":"563.08514ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:47:25.075076Z","caller":"traceutil/trace.go:171","msg":"trace[251462494] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"325.849281ms","start":"2026-02-22T17:47:24.749183Z","end":"2026-02-22T17:47:25.075032Z","steps":["trace[251462494] 'process raft request' (duration: 325.670377ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:25.075248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.749158Z","time spent":"325.997094ms","remote":"127.0.0.1:43016","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-22T17:47:25.075457Z","caller":"traceutil/trace.go:171","msg":"trace[376430885] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"326.068805ms","start":"2026-02-22T17:47:24.749365Z","end":"2026-02-22T17:47:25.075433Z","steps":["trace[376430885] 'process raft request' (duration: 325.951362ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:25.075506Z","caller":"traceutil/trace.go:171","msg":"trace[141383988] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"326.26485ms","start":"2026-02-22T17:47:24.749212Z","end":"2026-02-22T17:47:25.075477Z","steps":["trace[141383988] 'process raft request' (duration: 325.777189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:25.075619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.749337Z","time spent":"326.194108ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:47:25.075667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:24.749192Z","time spent":"326.383342ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:47:25.084435Z","caller":"traceutil/trace.go:171","msg":"trace[1997390804] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"177.737475ms","start":"2026-02-22T17:47:24.906672Z","end":"2026-02-22T17:47:25.084409Z","steps":["trace[1997390804] 'process raft request' (duration: 177.609982ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:25.200244Z","caller":"traceutil/trace.go:171","msg":"trace[96848254] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"112.010576ms","start":"2026-02-22T17:47:25.088212Z","end":"2026-02-22T17:47:25.200222Z","steps":["trace[96848254] 'read index received' (duration: 64.543222ms)","trace[96848254] 'applied index is now lower than readState.Index' (duration: 47.466294ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:25.200321Z","caller":"traceutil/trace.go:171","msg":"trace[1189783118] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"112.378895ms","start":"2026-02-22T17:47:25.087916Z","end":"2026-02-22T17:47:25.200295Z","steps":["trace[1189783118] 'process raft request' (duration: 64.89114ms)","trace[1189783118] 'compare' (duration: 47.309001ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:47:25.200403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.16662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-22T17:47:25.200445Z","caller":"traceutil/trace.go:171","msg":"trace[193156856] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:770; }","duration":"112.250041ms","start":"2026-02-22T17:47:25.088184Z","end":"2026-02-22T17:47:25.200434Z","steps":["trace[193156856] 'agreement among raft nodes before linearized reading' (duration: 112.125458ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:26.311452Z","caller":"traceutil/trace.go:171","msg":"trace[842381853] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"455.755374ms","start":"2026-02-22T17:47:25.855674Z","end":"2026-02-22T17:47:26.311429Z","steps":["trace[842381853] 'process raft request' (duration: 455.629201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:26.311602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:25.855652Z","time spent":"455.868786ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:47:26.311754Z","caller":"traceutil/trace.go:171","msg":"trace[1613173055] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:804; }","duration":"318.083681ms","start":"2026-02-22T17:47:25.993641Z","end":"2026-02-22T17:47:26.311725Z","steps":["trace[1613173055] 'read index received' (duration: 318.074111ms)","trace[1613173055] 'applied index is now lower than readState.Index' (duration: 7.55µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:47:26.311979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.294946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:47:26.312074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.879419ms","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-22T17:47:26.312089Z","caller":"traceutil/trace.go:171","msg":"trace[325733611] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:771; }","duration":"318.457569ms","start":"2026-02-22T17:47:25.993607Z","end":"2026-02-22T17:47:26.312064Z","steps":["trace[325733611] 'agreement among raft nodes before linearized reading' (duration: 318.229983ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:26.312147Z","caller":"traceutil/trace.go:171","msg":"trace[1749092521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:771; }","duration":"309.991492ms","start":"2026-02-22T17:47:26.002139Z","end":"2026-02-22T17:47:26.31213Z","steps":["trace[1749092521] 'agreement among raft nodes before linearized reading' (duration: 309.769247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:26.312164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:25.993593Z","time spent":"318.554312ms","remote":"127.0.0.1:43152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:47:26.312199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:26.002127Z","time spent":"310.063274ms","remote":"127.0.0.1:43016","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-22T17:47:26.882143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896009930,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:47:27.269597Z","caller":"traceutil/trace.go:171","msg":"trace[775004188] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:804; }","duration":"887.641462ms","start":"2026-02-22T17:47:26.381935Z","end":"2026-02-22T17:47:27.269576Z","steps":["trace[775004188] 'read index received' (duration: 873.007631ms)","trace[775004188] 'applied index is now lower than readState.Index' (duration: 14.632741ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:27.269859Z","caller":"traceutil/trace.go:171","msg":"trace[1011985918] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"952.955171ms","start":"2026-02-22T17:47:26.316882Z","end":"2026-02-22T17:47:27.269837Z","steps":["trace[1011985918] 'process raft request' (duration: 938.118086ms)","trace[1011985918] 'compare' (duration: 14.095988ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:47:27.269961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:26.316861Z","time spent":"953.032533ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:47:27.270069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.156027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:47:27.270073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"888.121693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:47:27.270093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.185104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:47:27.27013Z","caller":"traceutil/trace.go:171","msg":"trace[782706927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"191.261769ms","start":"2026-02-22T17:47:27.078853Z","end":"2026-02-22T17:47:27.270115Z","steps":["trace[782706927] 'agreement among raft nodes before linearized reading' (duration: 191.100066ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:27.270134Z","caller":"traceutil/trace.go:171","msg":"trace[1552072530] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"888.235836ms","start":"2026-02-22T17:47:26.381884Z","end":"2026-02-22T17:47:27.27012Z","steps":["trace[1552072530] 'agreement among raft nodes before linearized reading' (duration: 888.062851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:27.270163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:26.381865Z","time spent":"888.292327ms","remote":"127.0.0.1:42734","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-22T17:47:27.270156Z","caller":"traceutil/trace.go:171","msg":"trace[924064940] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"190.302937ms","start":"2026-02-22T17:47:27.079837Z","end":"2026-02-22T17:47:27.27014Z","steps":["trace[924064940] 'agreement among raft nodes before linearized reading' (duration: 190.135463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:27.270546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.758218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-22T17:47:27.270623Z","caller":"traceutil/trace.go:171","msg":"trace[485473618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:772; }","duration":"191.162368ms","start":"2026-02-22T17:47:27.079442Z","end":"2026-02-22T17:47:27.270605Z","steps":["trace[485473618] 'agreement among raft nodes before linearized reading' (duration: 190.540913ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:27.480422Z","caller":"traceutil/trace.go:171","msg":"trace[1219291148] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"205.850469ms","start":"2026-02-22T17:47:27.274235Z","end":"2026-02-22T17:47:27.480086Z","steps":["trace[1219291148] 'process raft request' (duration: 174.424428ms)","trace[1219291148] 'compare' (duration: 31.316739ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:27.518361Z","caller":"traceutil/trace.go:171","msg":"trace[1690682881] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"243.604227ms","start":"2026-02-22T17:47:27.274734Z","end":"2026-02-22T17:47:27.518338Z","steps":["trace[1690682881] 'process raft request' (duration: 243.339491ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:27.518767Z","caller":"traceutil/trace.go:171","msg":"trace[1997325086] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"241.826127ms","start":"2026-02-22T17:47:27.276915Z","end":"2026-02-22T17:47:27.518741Z","steps":["trace[1997325086] 'process raft request' (duration: 241.370876ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:27.518997Z","caller":"traceutil/trace.go:171","msg":"trace[2110240543] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:805; }","duration":"135.730038ms","start":"2026-02-22T17:47:27.383222Z","end":"2026-02-22T17:47:27.518952Z","steps":["trace[2110240543] 'read index received' (duration: 65.404611ms)","trace[2110240543] 'applied index is now lower than readState.Index' (duration: 70.324327ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:27.519099Z","caller":"traceutil/trace.go:171","msg":"trace[691665360] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"242.062702ms","start":"2026-02-22T17:47:27.277023Z","end":"2026-02-22T17:47:27.519086Z","steps":["trace[691665360] 'process raft request' (duration: 241.606401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:27.519312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.083777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:27.519381Z","caller":"traceutil/trace.go:171","msg":"trace[958981585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:776; }","duration":"136.193289ms","start":"2026-02-22T17:47:27.383169Z","end":"2026-02-22T17:47:27.519362Z","steps":["trace[958981585] 'agreement among raft nodes before linearized reading' (duration: 136.094357ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:28.953645Z","caller":"traceutil/trace.go:171","msg":"trace[403004194] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"341.995006ms","start":"2026-02-22T17:47:28.611627Z","end":"2026-02-22T17:47:28.953622Z","steps":["trace[403004194] 'read index received' (duration: 341.772531ms)","trace[403004194] 'applied index is now lower than readState.Index' (duration: 221.545µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:47:28.953894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.236882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:28.953982Z","caller":"traceutil/trace.go:171","msg":"trace[392780566] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:777; }","duration":"342.376965ms","start":"2026-02-22T17:47:28.611592Z","end":"2026-02-22T17:47:28.953969Z","steps":["trace[392780566] 'agreement among raft nodes before linearized reading' (duration: 342.12374ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:28.954022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:28.611576Z","time spent":"342.436277ms","remote":"127.0.0.1:42910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:47:28.954245Z","caller":"traceutil/trace.go:171","msg":"trace[807253452] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"533.40691ms","start":"2026-02-22T17:47:28.420778Z","end":"2026-02-22T17:47:28.954185Z","steps":["trace[807253452] 'process raft request' (duration: 532.712243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:28.954452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:28.420755Z","time spent":"533.586904ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:47:29.869304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.992674ms","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-22T17:47:29.869514Z","caller":"traceutil/trace.go:171","msg":"trace[1590180997] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:777; }","duration":"380.211359ms","start":"2026-02-22T17:47:29.489282Z","end":"2026-02-22T17:47:29.869493Z","steps":["trace[1590180997] 'range keys from in-memory index tree' (duration: 379.979314ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.870045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.638341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:29.870124Z","caller":"traceutil/trace.go:171","msg":"trace[98785351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"488.751494ms","start":"2026-02-22T17:47:29.381355Z","end":"2026-02-22T17:47:29.870107Z","steps":["trace[98785351] 'range keys from in-memory index tree' (duration: 488.365675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.870155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.381339Z","time spent":"488.809775ms","remote":"127.0.0.1:42734","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-22T17:47:29.870498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.443979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-22T17:47:29.87058Z","caller":"traceutil/trace.go:171","msg":"trace[1197533254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"596.574143ms","start":"2026-02-22T17:47:29.273985Z","end":"2026-02-22T17:47:29.870559Z","steps":["trace[1197533254] 'range keys from in-memory index tree' (duration: 596.223095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.870623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.273968Z","time spent":"596.646184ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T17:47:29.871015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.811044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:47:29.871283Z","caller":"traceutil/trace.go:171","msg":"trace[1176858119] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:777; }","duration":"513.864978ms","start":"2026-02-22T17:47:29.357401Z","end":"2026-02-22T17:47:29.871266Z","steps":["trace[1176858119] 'count revisions from in-memory index tree' (duration: 512.723562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.871383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.357382Z","time spent":"513.982591ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":51,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:47:29.938766Z","caller":"traceutil/trace.go:171","msg":"trace[270581841] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"453.482896ms","start":"2026-02-22T17:47:29.485256Z","end":"2026-02-22T17:47:29.938738Z","steps":["trace[270581841] 'read index received' (duration: 375.209745ms)","trace[270581841] 'applied index is now lower than readState.Index' (duration: 78.270561ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:47:29.939092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.831524ms","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-22T17:47:29.939654Z","caller":"traceutil/trace.go:171","msg":"trace[1948906599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"454.415208ms","start":"2026-02-22T17:47:29.485218Z","end":"2026-02-22T17:47:29.939633Z","steps":["trace[1948906599] 'agreement among raft nodes before linearized reading' (duration: 453.792964ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.939775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.4852Z","time spent":"454.555271ms","remote":"127.0.0.1:43016","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-22T17:47:29.940048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.088324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:47:29.940084Z","caller":"traceutil/trace.go:171","msg":"trace[227197325] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:778; }","duration":"157.156536ms","start":"2026-02-22T17:47:29.782919Z","end":"2026-02-22T17:47:29.940076Z","steps":["trace[227197325] 'agreement among raft nodes before linearized reading' (duration: 157.099205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.940068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.533273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-22T17:47:29.940444Z","caller":"traceutil/trace.go:171","msg":"trace[1522854610] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:778; }","duration":"418.913801ms","start":"2026-02-22T17:47:29.521514Z","end":"2026-02-22T17:47:29.940428Z","steps":["trace[1522854610] 'agreement among raft nodes before linearized reading' (duration: 418.473841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.941037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.521509Z","time spent":"419.510555ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:47:29.940654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.867787ms","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-22T17:47:29.941218Z","caller":"traceutil/trace.go:171","msg":"trace[339185414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"418.45121ms","start":"2026-02-22T17:47:29.522755Z","end":"2026-02-22T17:47:29.941207Z","steps":["trace[339185414] 'agreement among raft nodes before linearized reading' (duration: 417.839746ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.941268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.522748Z","time spent":"418.509271ms","remote":"127.0.0.1:43016","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-22T17:47:29.940696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.341256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:29.941347Z","caller":"traceutil/trace.go:171","msg":"trace[218228534] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:778; }","duration":"240.026652ms","start":"2026-02-22T17:47:29.701309Z","end":"2026-02-22T17:47:29.941336Z","steps":["trace[218228534] 'agreement among raft nodes before linearized reading' (duration: 239.365097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.94077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.189014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:47:29.94149Z","caller":"traceutil/trace.go:171","msg":"trace[93094760] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:778; }","duration":"418.945251ms","start":"2026-02-22T17:47:29.522536Z","end":"2026-02-22T17:47:29.941481Z","steps":["trace[93094760] 'agreement among raft nodes before linearized reading' (duration: 418.181624ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:29.941519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:29.522517Z","time spent":"418.993483ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:47:44.420551Z","caller":"traceutil/trace.go:171","msg":"trace[1738207268] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:903; }","duration":"356.934492ms","start":"2026-02-22T17:47:44.063589Z","end":"2026-02-22T17:47:44.420523Z","steps":["trace[1738207268] 'read index received' (duration: 356.84875ms)","trace[1738207268] 'applied index is now lower than readState.Index' (duration: 84.482µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:44.420684Z","caller":"traceutil/trace.go:171","msg":"trace[1637639409] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"416.092083ms","start":"2026-02-22T17:47:44.004581Z","end":"2026-02-22T17:47:44.420673Z","steps":["trace[1637639409] 'process raft request' (duration: 415.806566ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.420782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.004566Z","time spent":"416.145165ms","remote":"127.0.0.1:43016","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-22T17:47:44.421042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.04134ms","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-22T17:47:44.421135Z","caller":"traceutil/trace.go:171","msg":"trace[103659931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:867; }","duration":"352.178883ms","start":"2026-02-22T17:47:44.068932Z","end":"2026-02-22T17:47:44.421111Z","steps":["trace[103659931] 'agreement among raft nodes before linearized reading' (duration: 351.970268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.421183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.233866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-22T17:47:44.421191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.068915Z","time spent":"352.262854ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:47:44.421229Z","caller":"traceutil/trace.go:171","msg":"trace[258664108] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:867; }","duration":"293.300378ms","start":"2026-02-22T17:47:44.127918Z","end":"2026-02-22T17:47:44.421218Z","steps":["trace[258664108] 'agreement among raft nodes before linearized reading' (duration: 293.174365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.421512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.265647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-22T17:47:44.421526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.917835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:47:44.421702Z","caller":"traceutil/trace.go:171","msg":"trace[1521094438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:867; }","duration":"358.13984ms","start":"2026-02-22T17:47:44.063539Z","end":"2026-02-22T17:47:44.421679Z","steps":["trace[1521094438] 'agreement among raft nodes before linearized reading' (duration: 357.793362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.421555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.907394ms","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-22T17:47:44.421793Z","caller":"traceutil/trace.go:171","msg":"trace[1939409250] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"348.1863ms","start":"2026-02-22T17:47:44.073595Z","end":"2026-02-22T17:47:44.421781Z","steps":["trace[1939409250] 'agreement among raft nodes before linearized reading' (duration: 347.899104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.421838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.073575Z","time spent":"348.251632ms","remote":"127.0.0.1:43016","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-22T17:47:44.421557Z","caller":"traceutil/trace.go:171","msg":"trace[1906975005] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:867; }","duration":"346.331798ms","start":"2026-02-22T17:47:44.075214Z","end":"2026-02-22T17:47:44.421546Z","steps":["trace[1906975005] 'agreement among raft nodes before linearized reading' (duration: 346.217915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:44.42192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.06352Z","time spent":"358.384395ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T17:47:44.422003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.075204Z","time spent":"346.750337ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-22T17:47:44.950871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.483066ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-02-22T17:47:44.950964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.42302Z","time spent":"527.941606ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-22T17:47:45.027568Z","caller":"traceutil/trace.go:171","msg":"trace[1899658161] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"600.751431ms","start":"2026-02-22T17:47:44.426789Z","end":"2026-02-22T17:47:45.02754Z","steps":["trace[1899658161] 'process raft request' (duration: 600.365282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:45.027726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.426774Z","time spent":"600.858803ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T17:47:45.027881Z","caller":"traceutil/trace.go:171","msg":"trace[1673756346] linearizableReadLoop","detail":"{readStateIndex:909; appliedIndex:904; }","duration":"536.106493ms","start":"2026-02-22T17:47:44.491743Z","end":"2026-02-22T17:47:45.02785Z","steps":["trace[1673756346] 'read index received' (duration: 74.608916ms)","trace[1673756346] 'applied index is now lower than readState.Index' (duration: 461.493197ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:47:45.028017Z","caller":"traceutil/trace.go:171","msg":"trace[546914346] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"601.103259ms","start":"2026-02-22T17:47:44.426897Z","end":"2026-02-22T17:47:45.028Z","steps":["trace[546914346] 'process raft request' (duration: 600.485795ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:47:45.028052Z","caller":"traceutil/trace.go:171","msg":"trace[203259212] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"601.098269ms","start":"2026-02-22T17:47:44.426939Z","end":"2026-02-22T17:47:45.028038Z","steps":["trace[203259212] 'process raft request' (duration: 600.823382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:45.028092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.426892Z","time spent":"601.16533ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:47:45.028204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.426927Z","time spent":"601.17005ms","remote":"127.0.0.1:43016","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-22T17:47:45.028202Z","caller":"traceutil/trace.go:171","msg":"trace[517560473] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"601.198911ms","start":"2026-02-22T17:47:44.426971Z","end":"2026-02-22T17:47:45.02817Z","steps":["trace[517560473] 'process raft request' (duration: 600.826212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:45.028407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.426966Z","time spent":"601.335654ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:47:45.028438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.686087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:45.028479Z","caller":"traceutil/trace.go:171","msg":"trace[1018164441] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:871; }","duration":"536.755779ms","start":"2026-02-22T17:47:44.491711Z","end":"2026-02-22T17:47:45.028467Z","steps":["trace[1018164441] 'agreement among raft nodes before linearized reading' (duration: 536.259077ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:45.02852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:44.491699Z","time spent":"536.81259ms","remote":"127.0.0.1:42984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:47:56.747433Z","caller":"traceutil/trace.go:171","msg":"trace[1456453066] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"279.166352ms","start":"2026-02-22T17:47:56.468242Z","end":"2026-02-22T17:47:56.747408Z","steps":["trace[1456453066] 'process raft request' (duration: 279.041409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:57.696798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.995317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:47:57.696851Z","caller":"traceutil/trace.go:171","msg":"trace[609131238] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"315.053829ms","start":"2026-02-22T17:47:57.381783Z","end":"2026-02-22T17:47:57.696837Z","steps":["trace[609131238] 'range keys from in-memory index tree' (duration: 314.916375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:47:57.696872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:47:57.381721Z","time spent":"315.14694ms","remote":"127.0.0.1:42734","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-22T17:47:57.698423Z","caller":"traceutil/trace.go:171","msg":"trace[889576425] transaction","detail":"{read_only:false; response_revision:906; number_of_response:1; }","duration":"251.737902ms","start":"2026-02-22T17:47:57.446676Z","end":"2026-02-22T17:47:57.698414Z","steps":["trace[889576425] 'process raft request' (duration: 251.507767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:00.413313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.330988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:00.413422Z","caller":"traceutil/trace.go:171","msg":"trace[104469158] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"239.48713ms","start":"2026-02-22T17:48:00.173915Z","end":"2026-02-22T17:48:00.413402Z","steps":["trace[104469158] 'process raft request' (duration: 138.972988ms)","trace[104469158] 'compare' (duration: 100.205765ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:00.863333Z","caller":"traceutil/trace.go:171","msg":"trace[396123966] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"101.71803ms","start":"2026-02-22T17:48:00.761596Z","end":"2026-02-22T17:48:00.863314Z","steps":["trace[396123966] 'process raft request' (duration: 101.618088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:02.393954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.118837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:02.394124Z","caller":"traceutil/trace.go:171","msg":"trace[614108658] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:937; }","duration":"141.342012ms","start":"2026-02-22T17:48:02.252758Z","end":"2026-02-22T17:48:02.3941Z","steps":["trace[614108658] 'count revisions from in-memory index tree' (duration: 141.007414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:02.394507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013761023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:02.395874Z","caller":"traceutil/trace.go:171","msg":"trace[973195203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"1.015150355s","start":"2026-02-22T17:48:01.38071Z","end":"2026-02-22T17:48:02.39586Z","steps":["trace[973195203] 'range keys from in-memory index tree' (duration: 1.013691362s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:02.395951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:01.380694Z","time spent":"1.015239407s","remote":"127.0.0.1:42734","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-22T17:48:02.395286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.615614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:48:02.396104Z","caller":"traceutil/trace.go:171","msg":"trace[1926785890] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:937; }","duration":"340.464893ms","start":"2026-02-22T17:48:02.055613Z","end":"2026-02-22T17:48:02.396078Z","steps":["trace[1926785890] 'count revisions from in-memory index tree' (duration: 339.562412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:02.396165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:02.055599Z","time spent":"340.546875ms","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:48:03.370294Z","caller":"traceutil/trace.go:171","msg":"trace[1928836280] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:980; }","duration":"193.549143ms","start":"2026-02-22T17:48:03.176724Z","end":"2026-02-22T17:48:03.370273Z","steps":["trace[1928836280] 'read index received' (duration: 193.257646ms)","trace[1928836280] 'applied index is now lower than readState.Index' (duration: 290.187µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:03.370415Z","caller":"traceutil/trace.go:171","msg":"trace[237313143] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"233.610644ms","start":"2026-02-22T17:48:03.136793Z","end":"2026-02-22T17:48:03.370404Z","steps":["trace[237313143] 'process raft request' (duration: 233.268206ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:03.370654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.908821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-22T17:48:03.370707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.456313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:48:03.370708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.547077ms","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-22T17:48:03.370749Z","caller":"traceutil/trace.go:171","msg":"trace[1667758060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:939; }","duration":"194.058465ms","start":"2026-02-22T17:48:03.17667Z","end":"2026-02-22T17:48:03.370728Z","steps":["trace[1667758060] 'agreement among raft nodes before linearized reading' (duration: 193.86324ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:03.370773Z","caller":"traceutil/trace.go:171","msg":"trace[382953057] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:939; }","duration":"154.578326ms","start":"2026-02-22T17:48:03.216177Z","end":"2026-02-22T17:48:03.370755Z","steps":["trace[382953057] 'agreement among raft nodes before linearized reading' (duration: 154.350471ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:03.37078Z","caller":"traceutil/trace.go:171","msg":"trace[173016338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:939; }","duration":"188.66018ms","start":"2026-02-22T17:48:03.182105Z","end":"2026-02-22T17:48:03.370765Z","steps":["trace[173016338] 'agreement among raft nodes before linearized reading' (duration: 188.404605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:03.6454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.472472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:03.645575Z","caller":"traceutil/trace.go:171","msg":"trace[775737042] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"268.719903ms","start":"2026-02-22T17:48:03.376841Z","end":"2026-02-22T17:48:03.645561Z","steps":["trace[775737042] 'process raft request' (duration: 268.643761ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:03.645697Z","caller":"traceutil/trace.go:171","msg":"trace[436818689] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"268.737633ms","start":"2026-02-22T17:48:03.376832Z","end":"2026-02-22T17:48:03.645569Z","steps":["trace[436818689] 'process raft request' (duration: 108.005345ms)","trace[436818689] 'compare' (duration: 160.265237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:04.32388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.977761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:48:04.32392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.443343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.238\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-22T17:48:04.323963Z","caller":"traceutil/trace.go:171","msg":"trace[1206459178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"157.114094ms","start":"2026-02-22T17:48:04.16683Z","end":"2026-02-22T17:48:04.323944Z","steps":["trace[1206459178] 'range keys from in-memory index tree' (duration: 156.898638ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:04.323985Z","caller":"traceutil/trace.go:171","msg":"trace[1406847275] range","detail":"{range_begin:/registry/masterleases/199.204.45.238; range_end:; response_count:1; response_revision:942; }","duration":"194.554095ms","start":"2026-02-22T17:48:04.129414Z","end":"2026-02-22T17:48:04.323969Z","steps":["trace[1406847275] 'range keys from in-memory index tree' (duration: 194.278469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:04.881894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896010326,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T17:48:04.959684Z","caller":"traceutil/trace.go:171","msg":"trace[1232355973] linearizableReadLoop","detail":"{readStateIndex:985; appliedIndex:984; }","duration":"578.789076ms","start":"2026-02-22T17:48:04.380879Z","end":"2026-02-22T17:48:04.959668Z","steps":["trace[1232355973] 'read index received' (duration: 578.713144ms)","trace[1232355973] 'applied index is now lower than readState.Index' (duration: 75.022µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:04.959713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:04.324768Z","time spent":"634.938668ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-22T17:48:04.959773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.878898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:04.959799Z","caller":"traceutil/trace.go:171","msg":"trace[1835829556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:942; }","duration":"578.94741ms","start":"2026-02-22T17:48:04.380846Z","end":"2026-02-22T17:48:04.959793Z","steps":["trace[1835829556] 'agreement among raft nodes before linearized reading' (duration: 578.889659ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:04.959817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:04.380831Z","time spent":"578.98209ms","remote":"127.0.0.1:42736","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-22T17:48:05.92538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.223466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:05.925559Z","caller":"traceutil/trace.go:171","msg":"trace[140198127] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"798.03408ms","start":"2026-02-22T17:48:05.127509Z","end":"2026-02-22T17:48:05.925543Z","steps":["trace[140198127] 'process raft request' (duration: 797.966118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.925625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.127487Z","time spent":"798.109852ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":871,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:48:05.925826Z","caller":"traceutil/trace.go:171","msg":"trace[85897793] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"964.88923ms","start":"2026-02-22T17:48:04.960899Z","end":"2026-02-22T17:48:05.925788Z","steps":["trace[85897793] 'process raft request' (duration: 262.163932ms)","trace[85897793] 'compare' (duration: 702.075113ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:05.925871Z","caller":"traceutil/trace.go:171","msg":"trace[862865538] linearizableReadLoop","detail":"{readStateIndex:986; appliedIndex:985; }","duration":"962.018283ms","start":"2026-02-22T17:48:04.963827Z","end":"2026-02-22T17:48:05.925845Z","steps":["trace[862865538] 'read index received' (duration: 259.247874ms)","trace[862865538] 'applied index is now lower than readState.Index' (duration: 702.768379ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:05.926068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.106426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-02-22T17:48:05.926074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.236258ms","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-22T17:48:05.926115Z","caller":"traceutil/trace.go:171","msg":"trace[1935667938] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:944; }","duration":"277.188687ms","start":"2026-02-22T17:48:05.648915Z","end":"2026-02-22T17:48:05.926104Z","steps":["trace[1935667938] 'agreement among raft nodes before linearized reading' (duration: 277.075774ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.92611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.657972ms","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-22T17:48:05.926123Z","caller":"traceutil/trace.go:171","msg":"trace[1869241344] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:944; }","duration":"962.31983ms","start":"2026-02-22T17:48:04.96379Z","end":"2026-02-22T17:48:05.92611Z","steps":["trace[1869241344] 'agreement among raft nodes before linearized reading' (duration: 962.138756ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:05.926158Z","caller":"traceutil/trace.go:171","msg":"trace[2049640923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"511.707083ms","start":"2026-02-22T17:48:05.414439Z","end":"2026-02-22T17:48:05.926146Z","steps":["trace[2049640923] 'agreement among raft nodes before linearized reading' (duration: 511.57905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.92616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:04.963773Z","time spent":"962.379721ms","remote":"127.0.0.1:43016","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-22T17:48:05.926196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.414405Z","time spent":"511.783545ms","remote":"127.0.0.1:43016","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-22T17:48:05.926193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.238925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-02-22T17:48:05.926268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.047479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:05.926268Z","caller":"traceutil/trace.go:171","msg":"trace[444901221] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"276.351348ms","start":"2026-02-22T17:48:05.649896Z","end":"2026-02-22T17:48:05.926247Z","steps":["trace[444901221] 'agreement among raft nodes before linearized reading' (duration: 276.118173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.926193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.797162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-22T17:48:05.926584Z","caller":"traceutil/trace.go:171","msg":"trace[747696007] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"551.202392ms","start":"2026-02-22T17:48:05.375366Z","end":"2026-02-22T17:48:05.926568Z","steps":["trace[747696007] 'agreement among raft nodes before linearized reading' (duration: 550.627428ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.926648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.375311Z","time spent":"551.318614ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T17:48:05.926506Z","caller":"traceutil/trace.go:171","msg":"trace[1281980200] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"545.123852ms","start":"2026-02-22T17:48:05.381189Z","end":"2026-02-22T17:48:05.926312Z","steps":["trace[1281980200] 'agreement among raft nodes before linearized reading' (duration: 545.04229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:05.92745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.381178Z","time spent":"546.253257ms","remote":"127.0.0.1:42734","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-22T17:48:05.926394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:04.960887Z","time spent":"965.112084ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:06.866785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.669268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:06.866976Z","caller":"traceutil/trace.go:171","msg":"trace[500767063] linearizableReadLoop","detail":"{readStateIndex:988; appliedIndex:987; }","duration":"933.1932ms","start":"2026-02-22T17:48:05.933766Z","end":"2026-02-22T17:48:06.866959Z","steps":["trace[500767063] 'read index received' (duration: 439.45109ms)","trace[500767063] 'applied index is now lower than readState.Index' (duration: 493.74038ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:06.867028Z","caller":"traceutil/trace.go:171","msg":"trace[975897804] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"933.323113ms","start":"2026-02-22T17:48:05.933672Z","end":"2026-02-22T17:48:06.866995Z","steps":["trace[975897804] 'process raft request' (duration: 439.355678ms)","trace[975897804] 'compare' (duration: 493.070404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:06.867189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.933657Z","time spent":"933.445685ms","remote":"127.0.0.1:43016","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-22T17:48:06.867218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.446286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-22T17:48:06.86733Z","caller":"traceutil/trace.go:171","msg":"trace[1646453999] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:945; }","duration":"933.62787ms","start":"2026-02-22T17:48:05.933687Z","end":"2026-02-22T17:48:06.867315Z","steps":["trace[1646453999] 'agreement among raft nodes before linearized reading' (duration: 933.465196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:06.867434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.933663Z","time spent":"933.758473ms","remote":"127.0.0.1:42916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":446,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-22T17:48:07.367138Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896010340,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:48:07.47881Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.105669648s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-22T17:48:07.6518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.736045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:07.652058Z","caller":"traceutil/trace.go:171","msg":"trace[78912666] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"1.718153929s","start":"2026-02-22T17:48:05.933878Z","end":"2026-02-22T17:48:07.652032Z","steps":["trace[78912666] 'process raft request' (duration: 1.545101868s)","trace[78912666] 'compare' (duration: 172.612071ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:07.652191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.933869Z","time spent":"1.718252672s","remote":"127.0.0.1:43016","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-22T17:48:07.65234Z","caller":"traceutil/trace.go:171","msg":"trace[884689457] linearizableReadLoop","detail":"{readStateIndex:992; appliedIndex:988; }","duration":"785.253417ms","start":"2026-02-22T17:48:06.867068Z","end":"2026-02-22T17:48:07.652322Z","steps":["trace[884689457] 'read index received' (duration: 611.891498ms)","trace[884689457] 'applied index is now lower than readState.Index' (duration: 173.360449ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:07.652527Z","caller":"traceutil/trace.go:171","msg":"trace[224269221] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.718572989s","start":"2026-02-22T17:48:05.933937Z","end":"2026-02-22T17:48:07.65251Z","steps":["trace[224269221] 'process raft request' (duration: 1.718270122s)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:07.652599Z","caller":"traceutil/trace.go:171","msg":"trace[8553686] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"1.718650321s","start":"2026-02-22T17:48:05.933913Z","end":"2026-02-22T17:48:07.652563Z","steps":["trace[8553686] 'process raft request' (duration: 1.718089288s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:07.652618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.93393Z","time spent":"1.71864343s","remote":"127.0.0.1:43016","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-22T17:48:07.652628Z","caller":"traceutil/trace.go:171","msg":"trace[2095783022] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.718704792s","start":"2026-02-22T17:48:05.933911Z","end":"2026-02-22T17:48:07.652616Z","steps":["trace[2095783022] 'process raft request' (duration: 1.7181801s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:07.652688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.933899Z","time spent":"1.718745073s","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:07.65269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:05.933897Z","time spent":"1.718759453s","remote":"127.0.0.1:43016","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-22T17:48:07.652701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430836859s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T17:48:07.652761Z","caller":"traceutil/trace.go:171","msg":"trace[1107408928] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:949; }","duration":"1.430945361s","start":"2026-02-22T17:48:06.2218Z","end":"2026-02-22T17:48:07.652746Z","steps":["trace[1107408928] 'agreement among raft nodes before linearized reading' (duration: 1.430809038s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:07.652809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:06.221774Z","time spent":"1.431026024s","remote":"127.0.0.1:43110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-22T17:48:07.652859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.267518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-22T17:48:07.652902Z","caller":"traceutil/trace.go:171","msg":"trace[288165788] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:949; }","duration":"782.34893ms","start":"2026-02-22T17:48:06.870541Z","end":"2026-02-22T17:48:07.65289Z","steps":["trace[288165788] 'agreement among raft nodes before linearized reading' (duration: 782.232407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:07.652932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:06.870522Z","time spent":"782.403721ms","remote":"127.0.0.1:42772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-22T17:48:07.65316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271114455s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:07.653202Z","caller":"traceutil/trace.go:171","msg":"trace[1329552540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:949; }","duration":"1.271189446s","start":"2026-02-22T17:48:06.382001Z","end":"2026-02-22T17:48:07.65319Z","steps":["trace[1329552540] 'agreement among raft nodes before linearized reading' (duration: 1.270561161s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:07.653228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:06.381981Z","time spent":"1.271240897s","remote":"127.0.0.1:42734","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-22T17:48:08.309501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.980337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:08.30959Z","caller":"traceutil/trace.go:171","msg":"trace[1004255162] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:950; }","duration":"149.128961ms","start":"2026-02-22T17:48:08.16044Z","end":"2026-02-22T17:48:08.309569Z","steps":["trace[1004255162] 'count revisions from in-memory index tree' (duration: 148.893956ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:08.414496Z","caller":"traceutil/trace.go:171","msg":"trace[242596831] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"250.614776ms","start":"2026-02-22T17:48:08.163829Z","end":"2026-02-22T17:48:08.414444Z","steps":["trace[242596831] 'process raft request' (duration: 250.423401ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:08.415473Z","caller":"traceutil/trace.go:171","msg":"trace[1601570475] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:994; }","duration":"122.638912ms","start":"2026-02-22T17:48:08.292802Z","end":"2026-02-22T17:48:08.415441Z","steps":["trace[1601570475] 'read index received' (duration: 122.627791ms)","trace[1601570475] 'applied index is now lower than readState.Index' (duration: 9.271µs)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:08.415659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.835776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:08.41599Z","caller":"traceutil/trace.go:171","msg":"trace[311328057] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:951; }","duration":"122.99853ms","start":"2026-02-22T17:48:08.292752Z","end":"2026-02-22T17:48:08.415751Z","steps":["trace[311328057] 'agreement among raft nodes before linearized reading' (duration: 122.803995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:08.92703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13976530594896010349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T17:48:09.543474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.612835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:09.543573Z","caller":"traceutil/trace.go:171","msg":"trace[1765382101] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:994; }","duration":"1.116973748s","start":"2026-02-22T17:48:08.426582Z","end":"2026-02-22T17:48:09.543556Z","steps":["trace[1765382101] 'read index received' (duration: 504.1962ms)","trace[1765382101] 'applied index is now lower than readState.Index' (duration: 612.776258ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:09.543654Z","caller":"traceutil/trace.go:171","msg":"trace[562025864] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.118568675s","start":"2026-02-22T17:48:08.425074Z","end":"2026-02-22T17:48:09.543643Z","steps":["trace[562025864] 'process raft request' (duration: 505.720515ms)","trace[562025864] 'compare' (duration: 612.500272ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:09.543706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:08.425053Z","time spent":"1.118630996s","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:48:09.543925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117339926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:09.543962Z","caller":"traceutil/trace.go:171","msg":"trace[669462965] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:952; }","duration":"1.117411938s","start":"2026-02-22T17:48:08.426541Z","end":"2026-02-22T17:48:09.543952Z","steps":["trace[669462965] 'agreement among raft nodes before linearized reading' (duration: 1.117330056s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:09.543992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:08.426524Z","time spent":"1.117460579s","remote":"127.0.0.1:42820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-22T17:48:10.246551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.505933ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-22T17:48:10.246643Z","caller":"traceutil/trace.go:171","msg":"trace[2111261130] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"703.024154ms","start":"2026-02-22T17:48:09.543599Z","end":"2026-02-22T17:48:10.246623Z","steps":["trace[2111261130] 'read index received' (duration: 289.452299ms)","trace[2111261130] 'applied index is now lower than readState.Index' (duration: 413.570375ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:10.246744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.094747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:10.246786Z","caller":"traceutil/trace.go:171","msg":"trace[1189169125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:952; }","duration":"863.175079ms","start":"2026-02-22T17:48:09.3836Z","end":"2026-02-22T17:48:10.246775Z","steps":["trace[1189169125] 'agreement among raft nodes before linearized reading' (duration: 863.093897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.246802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375774872s","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-22T17:48:10.246827Z","caller":"traceutil/trace.go:171","msg":"trace[1145281735] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"1.375849644s","start":"2026-02-22T17:48:08.87097Z","end":"2026-02-22T17:48:10.24682Z","steps":["trace[1145281735] 'agreement among raft nodes before linearized reading' (duration: 1.375726091s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.246817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.383574Z","time spent":"863.235881ms","remote":"127.0.0.1:42734","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-22T17:48:10.246852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:08.870947Z","time spent":"1.375898486s","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:48:10.317594Z","caller":"traceutil/trace.go:171","msg":"trace[1758843867] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"771.585792ms","start":"2026-02-22T17:48:09.545988Z","end":"2026-02-22T17:48:10.317574Z","steps":["trace[1758843867] 'process raft request' (duration: 771.366827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.317703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.545968Z","time spent":"771.686484ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:48:10.317789Z","caller":"traceutil/trace.go:171","msg":"trace[725098237] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"769.187407ms","start":"2026-02-22T17:48:09.548571Z","end":"2026-02-22T17:48:10.317759Z","steps":["trace[725098237] 'process raft request' (duration: 768.936721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.317963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.548548Z","time spent":"769.34903ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T17:48:10.318187Z","caller":"traceutil/trace.go:171","msg":"trace[495174770] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"563.905204ms","start":"2026-02-22T17:48:09.75427Z","end":"2026-02-22T17:48:10.318176Z","steps":["trace[495174770] 'process raft request' (duration: 563.276059ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.318247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.754249Z","time spent":"563.964965ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:10.318398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.038961ms","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":"warn","ts":"2026-02-22T17:48:10.318399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.139606ms","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-22T17:48:10.318436Z","caller":"traceutil/trace.go:171","msg":"trace[1140798447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:955; }","duration":"662.088652ms","start":"2026-02-22T17:48:09.656336Z","end":"2026-02-22T17:48:10.318425Z","steps":["trace[1140798447] 'agreement among raft nodes before linearized reading' (duration: 661.935669ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.318444Z","caller":"traceutil/trace.go:171","msg":"trace[159793110] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"643.227548ms","start":"2026-02-22T17:48:09.675204Z","end":"2026-02-22T17:48:10.318431Z","steps":["trace[159793110] 'agreement among raft nodes before linearized reading' (duration: 643.090925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.318466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.656332Z","time spent":"662.127893ms","remote":"127.0.0.1:43016","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-22T17:48:10.318432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.560491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:48:10.318493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.754738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-22T17:48:10.318494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.675184Z","time spent":"643.302311ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:48:10.31852Z","caller":"traceutil/trace.go:171","msg":"trace[649809670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:955; }","duration":"662.810939ms","start":"2026-02-22T17:48:09.655701Z","end":"2026-02-22T17:48:10.318512Z","steps":["trace[649809670] 'agreement among raft nodes before linearized reading' (duration: 662.710387ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.31852Z","caller":"traceutil/trace.go:171","msg":"trace[1726991391] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"661.670023ms","start":"2026-02-22T17:48:09.656827Z","end":"2026-02-22T17:48:10.318497Z","steps":["trace[1726991391] 'agreement among raft nodes before linearized reading' (duration: 661.335905ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.318544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.655685Z","time spent":"662.85319ms","remote":"127.0.0.1:43016","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-22T17:48:10.318579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:09.656824Z","time spent":"661.742185ms","remote":"127.0.0.1:43016","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-22T17:48:10.475339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.246136ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:10.475406Z","caller":"traceutil/trace.go:171","msg":"trace[825422678] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"157.273679ms","start":"2026-02-22T17:48:10.318121Z","end":"2026-02-22T17:48:10.475395Z","steps":["trace[825422678] 'read index received' (duration: 43.916271ms)","trace[825422678] 'applied index is now lower than readState.Index' (duration: 113.356388ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:10.47545Z","caller":"traceutil/trace.go:171","msg":"trace[86989771] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"225.357085ms","start":"2026-02-22T17:48:10.250086Z","end":"2026-02-22T17:48:10.475443Z","steps":["trace[86989771] 'process raft request' (duration: 111.935605ms)","trace[86989771] 'compare' (duration: 113.108523ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:10.475555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.384251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:10.475571Z","caller":"traceutil/trace.go:171","msg":"trace[1820275773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"227.438973ms","start":"2026-02-22T17:48:10.248126Z","end":"2026-02-22T17:48:10.475565Z","steps":["trace[1820275773] 'agreement among raft nodes before linearized reading' (duration: 227.403272ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.684808Z","caller":"traceutil/trace.go:171","msg":"trace[1840284629] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"359.390548ms","start":"2026-02-22T17:48:10.325402Z","end":"2026-02-22T17:48:10.684793Z","steps":["trace[1840284629] 'process raft request' (duration: 359.333297ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.68489Z","caller":"traceutil/trace.go:171","msg":"trace[2136229025] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"359.699446ms","start":"2026-02-22T17:48:10.325167Z","end":"2026-02-22T17:48:10.684867Z","steps":["trace[2136229025] 'process raft request' (duration: 262.546561ms)","trace[2136229025] 'compare' (duration: 96.790607ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:10.684937Z","caller":"traceutil/trace.go:171","msg":"trace[1848083569] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"359.679296ms","start":"2026-02-22T17:48:10.325252Z","end":"2026-02-22T17:48:10.684931Z","steps":["trace[1848083569] 'process raft request' (duration: 359.42032ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.684922Z","caller":"traceutil/trace.go:171","msg":"trace[443843696] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1000; }","duration":"209.48173ms","start":"2026-02-22T17:48:10.47542Z","end":"2026-02-22T17:48:10.684901Z","steps":["trace[443843696] 'read index received' (duration: 112.225552ms)","trace[443843696] 'applied index is now lower than readState.Index' (duration: 97.255228ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:10.684982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.325244Z","time spent":"359.713896ms","remote":"127.0.0.1:43016","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-22T17:48:10.685011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.325129Z","time spent":"359.813228ms","remote":"127.0.0.1:43016","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-22T17:48:10.68491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.325393Z","time spent":"359.46698ms","remote":"127.0.0.1:42810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T17:48:10.685187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.844312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-02-22T17:48:10.685193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.66476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:10.685209Z","caller":"traceutil/trace.go:171","msg":"trace[979581216] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:961; }","duration":"360.888773ms","start":"2026-02-22T17:48:10.324314Z","end":"2026-02-22T17:48:10.685203Z","steps":["trace[979581216] 'agreement among raft nodes before linearized reading' (duration: 360.78113ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.685217Z","caller":"traceutil/trace.go:171","msg":"trace[1879772523] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"359.599133ms","start":"2026-02-22T17:48:10.325611Z","end":"2026-02-22T17:48:10.68521Z","steps":["trace[1879772523] 'process raft request' (duration: 359.159163ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.685229Z","caller":"traceutil/trace.go:171","msg":"trace[433148083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"201.745001ms","start":"2026-02-22T17:48:10.483473Z","end":"2026-02-22T17:48:10.685218Z","steps":["trace[433148083] 'agreement among raft nodes before linearized reading' (duration: 201.67877ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:10.68524Z","caller":"traceutil/trace.go:171","msg":"trace[1163741227] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"358.031947ms","start":"2026-02-22T17:48:10.327203Z","end":"2026-02-22T17:48:10.685235Z","steps":["trace[1163741227] 'process raft request' (duration: 357.656419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:10.685224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.324299Z","time spent":"360.922134ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-22T17:48:10.68709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.327193Z","time spent":"358.059778ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:10.685257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.32558Z","time spent":"359.656125ms","remote":"127.0.0.1:43016","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-22T17:48:11.066902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.449231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T17:48:11.067013Z","caller":"traceutil/trace.go:171","msg":"trace[718559278] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"372.812297ms","start":"2026-02-22T17:48:10.694177Z","end":"2026-02-22T17:48:11.06699Z","steps":["trace[718559278] 'process raft request' (duration: 232.177102ms)","trace[718559278] 'compare' (duration: 140.348409ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:11.067871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:10.69416Z","time spent":"373.655526ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:13.121355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.853725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-22T17:48:13.121394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.347489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T17:48:13.121459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.285203ms","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-22T17:48:13.12153Z","caller":"traceutil/trace.go:171","msg":"trace[117113747] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:963; }","duration":"283.536933ms","start":"2026-02-22T17:48:12.837966Z","end":"2026-02-22T17:48:13.121503Z","steps":["trace[117113747] 'count revisions from in-memory index tree' (duration: 283.251407ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:13.121541Z","caller":"traceutil/trace.go:171","msg":"trace[670461326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"428.414736ms","start":"2026-02-22T17:48:12.693107Z","end":"2026-02-22T17:48:13.121521Z","steps":["trace[670461326] 'range keys from in-memory index tree' (duration: 428.13061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:13.121588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:12.693086Z","time spent":"428.495219ms","remote":"127.0.0.1:43016","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-22T17:48:13.121648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.523716ms","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-22T17:48:13.121775Z","caller":"traceutil/trace.go:171","msg":"trace[422713575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"427.647859ms","start":"2026-02-22T17:48:12.694063Z","end":"2026-02-22T17:48:13.121711Z","steps":["trace[422713575] 'range keys from in-memory index tree' (duration: 427.325792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:13.121727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.348175ms","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":"warn","ts":"2026-02-22T17:48:13.121823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:12.694051Z","time spent":"427.762441ms","remote":"127.0.0.1:43016","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-22T17:48:13.121863Z","caller":"traceutil/trace.go:171","msg":"trace[1453839666] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"424.988217ms","start":"2026-02-22T17:48:12.69644Z","end":"2026-02-22T17:48:13.121428Z","steps":["trace[1453839666] 'range keys from in-memory index tree' (duration: 424.691231ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:13.122001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:12.69643Z","time spent":"425.554071ms","remote":"127.0.0.1:43016","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-22T17:48:13.121902Z","caller":"traceutil/trace.go:171","msg":"trace[358011205] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:963; }","duration":"428.607601ms","start":"2026-02-22T17:48:12.693263Z","end":"2026-02-22T17:48:13.12187Z","steps":["trace[358011205] 'range keys from in-memory index tree' (duration: 428.087469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:13.122091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:12.693243Z","time spent":"428.834646ms","remote":"127.0.0.1:43016","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":"info","ts":"2026-02-22T17:48:15.330256Z","caller":"traceutil/trace.go:171","msg":"trace[142688939] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"188.832825ms","start":"2026-02-22T17:48:15.141402Z","end":"2026-02-22T17:48:15.330234Z","steps":["trace[142688939] 'process raft request' (duration: 188.705502ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:15.331842Z","caller":"traceutil/trace.go:171","msg":"trace[693147786] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"186.044551ms","start":"2026-02-22T17:48:15.145783Z","end":"2026-02-22T17:48:15.331828Z","steps":["trace[693147786] 'process raft request' (duration: 186.01319ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:15.331907Z","caller":"traceutil/trace.go:171","msg":"trace[2074262211] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"189.818657ms","start":"2026-02-22T17:48:15.142059Z","end":"2026-02-22T17:48:15.331878Z","steps":["trace[2074262211] 'process raft request' (duration: 189.642843ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:15.332087Z","caller":"traceutil/trace.go:171","msg":"trace[2077186870] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"187.369691ms","start":"2026-02-22T17:48:15.144706Z","end":"2026-02-22T17:48:15.332076Z","steps":["trace[2077186870] 'process raft request' (duration: 187.059974ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:16.204912Z","caller":"traceutil/trace.go:171","msg":"trace[1272780337] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"132.499159ms","start":"2026-02-22T17:48:16.072386Z","end":"2026-02-22T17:48:16.204886Z","steps":["trace[1272780337] 'read index received' (duration: 131.079486ms)","trace[1272780337] 'applied index is now lower than readState.Index' (duration: 1.418443ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:16.204986Z","caller":"traceutil/trace.go:171","msg":"trace[1900416054] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"157.493413ms","start":"2026-02-22T17:48:16.047462Z","end":"2026-02-22T17:48:16.204955Z","steps":["trace[1900416054] 'process raft request' (duration: 156.05866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:16.205275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.861687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-22T17:48:16.205322Z","caller":"traceutil/trace.go:171","msg":"trace[967223573] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:982; }","duration":"132.947669ms","start":"2026-02-22T17:48:16.072361Z","end":"2026-02-22T17:48:16.205308Z","steps":["trace[967223573] 'agreement among raft nodes before linearized reading' (duration: 132.711473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:18.68861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.853674ms","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-22T17:48:18.688658Z","caller":"traceutil/trace.go:171","msg":"trace[311229719] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"307.816981ms","start":"2026-02-22T17:48:18.380817Z","end":"2026-02-22T17:48:18.688634Z","steps":["trace[311229719] 'read index received' (duration: 220.151714ms)","trace[311229719] 'applied index is now lower than readState.Index' (duration: 87.664237ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:48:18.688696Z","caller":"traceutil/trace.go:171","msg":"trace[1896639036] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:995; }","duration":"475.015297ms","start":"2026-02-22T17:48:18.213664Z","end":"2026-02-22T17:48:18.688679Z","steps":["trace[1896639036] 'count revisions from in-memory index tree' (duration: 474.716001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:18.688742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:18.213642Z","time spent":"475.088949ms","remote":"127.0.0.1:42980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-02-22T17:48:18.688758Z","caller":"traceutil/trace.go:171","msg":"trace[1979910162] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"331.037635ms","start":"2026-02-22T17:48:18.357709Z","end":"2026-02-22T17:48:18.688747Z","steps":["trace[1979910162] 'process raft request' (duration: 243.306556ms)","trace[1979910162] 'compare' (duration: 87.513344ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T17:48:18.688848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:18.357683Z","time spent":"331.099707ms","remote":"127.0.0.1:43016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T17:48:18.689019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.116258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:18.689254Z","caller":"traceutil/trace.go:171","msg":"trace[1772577935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"308.416705ms","start":"2026-02-22T17:48:18.380778Z","end":"2026-02-22T17:48:18.689195Z","steps":["trace[1772577935] 'agreement among raft nodes before linearized reading' (duration: 308.053707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:18.689312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T17:48:18.380761Z","time spent":"308.537927ms","remote":"127.0.0.1:42734","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-22T17:48:18.694375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.883775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T17:48:18.694444Z","caller":"traceutil/trace.go:171","msg":"trace[466976388] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:997; }","duration":"151.975716ms","start":"2026-02-22T17:48:18.542452Z","end":"2026-02-22T17:48:18.694428Z","steps":["trace[466976388] 'agreement among raft nodes before linearized reading' (duration: 151.854564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T17:48:18.694502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.156331ms","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-22T17:48:18.694672Z","caller":"traceutil/trace.go:171","msg":"trace[1686596390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:997; }","duration":"162.244972ms","start":"2026-02-22T17:48:18.532306Z","end":"2026-02-22T17:48:18.694551Z","steps":["trace[1686596390] 'agreement among raft nodes before linearized reading' (duration: 162.044138ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:48:43.590058Z","caller":"traceutil/trace.go:171","msg":"trace[1231678420] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"104.39132ms","start":"2026-02-22T17:48:43.485646Z","end":"2026-02-22T17:48:43.590037Z","steps":["trace[1231678420] 'process raft request' (duration: 104.275447ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:15.633048Z","caller":"traceutil/trace.go:171","msg":"trace[317346468] transaction","detail":"{read_only:false; response_revision:1762; number_of_response:1; }","duration":"103.058657ms","start":"2026-02-22T17:51:15.529959Z","end":"2026-02-22T17:51:15.633017Z","steps":["trace[317346468] 'process raft request' (duration: 102.858062ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:20.537202Z","caller":"traceutil/trace.go:171","msg":"trace[1890094467] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"101.690994ms","start":"2026-02-22T17:51:20.435484Z","end":"2026-02-22T17:51:20.537175Z","steps":["trace[1890094467] 'process raft request' (duration: 101.608252ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T17:51:20.537208Z","caller":"traceutil/trace.go:171","msg":"trace[1138293464] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"102.780751ms","start":"2026-02-22T17:51:20.434396Z","end":"2026-02-22T17:51:20.537177Z","steps":["trace[1138293464] 'process raft request' (duration: 84.611894ms)","trace[1138293464] 'compare' (duration: 17.946041ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T17:51:20.537434Z","caller":"traceutil/trace.go:171","msg":"trace[46115640] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"100.882425ms","start":"2026-02-22T17:51:20.436513Z","end":"2026-02-22T17:51:20.537395Z","steps":["trace[46115640] 'process raft request' (duration: 100.625709ms)"],"step_count":1}