{"level":"warn","ts":"2026-09-25T03:22:08.840017Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T03:22:08.840107Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.220:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.220:2380","--initial-cluster=instance=https://162.253.55.220:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.220:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.220: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-09-25T03:22:08.840177Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-25T03:22:08.840192Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-25T03:22:08.840213Z","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-09-25T03:22:08.842168Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"]} {"level":"info","ts":"2026-09-25T03:22:08.842407Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.220: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-09-25T03:22:08.846174Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.357826ms"} {"level":"info","ts":"2026-09-25T03:22:08.855562Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4c62f064ba268721","cluster-id":"65b58f2bbdfe5647"} {"level":"info","ts":"2026-09-25T03:22:08.855636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=()"} {"level":"info","ts":"2026-09-25T03:22:08.855657Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 0"} {"level":"info","ts":"2026-09-25T03:22:08.855676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4c62f064ba268721 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-25T03:22:08.855686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became follower at term 1"} {"level":"info","ts":"2026-09-25T03:22:08.855742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"warn","ts":"2026-09-25T03:22:08.861234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-25T03:22:08.864868Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-25T03:22:08.867366Z","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-09-25T03:22:08.869268Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4c62f064ba268721","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-25T03:22:08.869425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4c62f064ba268721","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-25T03:22:08.869553Z","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-09-25T03:22:08.869662Z","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-09-25T03:22:08.869675Z","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-09-25T03:22:08.870354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 switched to configuration voters=(5504226010010650401)"} {"level":"info","ts":"2026-09-25T03:22:08.870459Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","added-peer-id":"4c62f064ba268721","added-peer-peer-urls":["https://162.253.55.220:2380"]} {"level":"info","ts":"2026-09-25T03:22:08.871326Z","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-09-25T03:22:08.871406Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-25T03:22:08.871452Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.220:2380"} {"level":"info","ts":"2026-09-25T03:22:08.871524Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4c62f064ba268721","initial-advertise-peer-urls":["https://162.253.55.220:2380"],"listen-peer-urls":["https://162.253.55.220:2380"],"advertise-client-urls":["https://162.253.55.220:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.220:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-25T03:22:08.871581Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-25T03:22:09.256305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 is starting a new election at term 1"} {"level":"info","ts":"2026-09-25T03:22:09.256375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-25T03:22:09.256413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgPreVoteResp from 4c62f064ba268721 at term 1"} {"level":"info","ts":"2026-09-25T03:22:09.256431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became candidate at term 2"} {"level":"info","ts":"2026-09-25T03:22:09.25644Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 received MsgVoteResp from 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-25T03:22:09.256455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4c62f064ba268721 became leader at term 2"} {"level":"info","ts":"2026-09-25T03:22:09.256469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4c62f064ba268721 elected leader 4c62f064ba268721 at term 2"} {"level":"info","ts":"2026-09-25T03:22:09.257607Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:09.258317Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4c62f064ba268721","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.220:2379]}","request-path":"/0/members/4c62f064ba268721/attributes","cluster-id":"65b58f2bbdfe5647","publish-timeout":"7s"} {"level":"info","ts":"2026-09-25T03:22:09.258497Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T03:22:09.258548Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-25T03:22:09.258865Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"65b58f2bbdfe5647","local-member-id":"4c62f064ba268721","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:09.258901Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-25T03:22:09.258931Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-25T03:22:09.258961Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:09.259006Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-25T03:22:09.260793Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-25T03:22:09.261965Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.220:2379"} {"level":"warn","ts":"2026-09-25T03:22:55.016165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.985113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-09-25T03:22:55.016218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.185379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:22:55.016324Z","caller":"traceutil/trace.go:171","msg":"trace[593089056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:594; }","duration":"105.192007ms","start":"2026-09-25T03:22:54.911105Z","end":"2026-09-25T03:22:55.016297Z","steps":["trace[593089056] 'range keys from in-memory index tree' (duration: 104.785119ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:22:55.016342Z","caller":"traceutil/trace.go:171","msg":"trace[90974388] range","detail":"{range_begin:/registry/clusterrolebindings/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:594; }","duration":"177.384444ms","start":"2026-09-25T03:22:54.83893Z","end":"2026-09-25T03:22:55.016314Z","steps":["trace[90974388] 'range keys from in-memory index tree' (duration: 177.105127ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:22:55.605612Z","caller":"traceutil/trace.go:171","msg":"trace[1193972915] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"188.767529ms","start":"2026-09-25T03:22:55.416823Z","end":"2026-09-25T03:22:55.60559Z","steps":["trace[1193972915] 'process raft request' (duration: 186.905358ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:23:13.211745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.657277ms","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-09-25T03:23:13.211852Z","caller":"traceutil/trace.go:171","msg":"trace[1086827779] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"200.82576ms","start":"2026-09-25T03:23:13.011008Z","end":"2026-09-25T03:23:13.211833Z","steps":["trace[1086827779] 'range keys from in-memory index tree' (duration: 200.36151ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:09.053032Z","caller":"traceutil/trace.go:171","msg":"trace[405422387] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"128.306128ms","start":"2026-09-25T03:24:08.924697Z","end":"2026-09-25T03:24:09.053004Z","steps":["trace[405422387] 'process raft request' (duration: 119.328476ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:24:36.131305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.020564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aa9dcdb-b220-43e4-a63c-adfcbb67bd5b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-25T03:24:36.131395Z","caller":"traceutil/trace.go:171","msg":"trace[486441645] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4aa9dcdb-b220-43e4-a63c-adfcbb67bd5b; range_end:; response_count:1; response_revision:1865; }","duration":"161.154917ms","start":"2026-09-25T03:24:35.970221Z","end":"2026-09-25T03:24:36.131376Z","steps":["trace[486441645] 'range keys from in-memory index tree' (duration: 160.799139ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:24:36.131829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.775004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4aa9dcdb-b220-43e4-a63c-adfcbb67bd5b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-25T03:24:36.13203Z","caller":"traceutil/trace.go:171","msg":"trace[1128061789] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4aa9dcdb-b220-43e4-a63c-adfcbb67bd5b; range_end:; response_count:1; response_revision:1865; }","duration":"114.980118ms","start":"2026-09-25T03:24:36.016963Z","end":"2026-09-25T03:24:36.131943Z","steps":["trace[1128061789] 'range keys from in-memory index tree' (duration: 114.201391ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.446972Z","caller":"traceutil/trace.go:171","msg":"trace[512468185] linearizableReadLoop","detail":"{readStateIndex:1941; appliedIndex:1940; }","duration":"194.406107ms","start":"2026-09-25T03:24:36.252533Z","end":"2026-09-25T03:24:36.446939Z","steps":["trace[512468185] 'read index received' (duration: 194.176391ms)","trace[512468185] 'applied index is now lower than readState.Index' (duration: 228.176µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:24:36.447158Z","caller":"traceutil/trace.go:171","msg":"trace[1774481666] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"303.67844ms","start":"2026-09-25T03:24:36.143444Z","end":"2026-09-25T03:24:36.447122Z","steps":["trace[1774481666] 'process raft request' (duration: 303.27262ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:24:36.447432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.869977ms","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-09-25T03:24:36.447576Z","caller":"traceutil/trace.go:171","msg":"trace[1128714293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1866; }","duration":"195.094273ms","start":"2026-09-25T03:24:36.252449Z","end":"2026-09-25T03:24:36.447544Z","steps":["trace[1128714293] 'agreement among raft nodes before linearized reading' (duration: 194.686154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:24:36.448001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:24:36.143422Z","time spent":"303.888544ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:24:36.64428Z","caller":"traceutil/trace.go:171","msg":"trace[423252347] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"175.20984ms","start":"2026-09-25T03:24:36.469033Z","end":"2026-09-25T03:24:36.644242Z","steps":["trace[423252347] 'process raft request' (duration: 174.983165ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.892498Z","caller":"traceutil/trace.go:171","msg":"trace[1626400676] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"243.95357ms","start":"2026-09-25T03:24:36.648497Z","end":"2026-09-25T03:24:36.89245Z","steps":["trace[1626400676] 'process raft request' (duration: 205.692208ms)","trace[1626400676] 'compare' (duration: 38.053797ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:24:36.899798Z","caller":"traceutil/trace.go:171","msg":"trace[1190281163] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"250.29426ms","start":"2026-09-25T03:24:36.649486Z","end":"2026-09-25T03:24:36.89978Z","steps":["trace[1190281163] 'process raft request' (duration: 250.098186ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.9004Z","caller":"traceutil/trace.go:171","msg":"trace[1417660437] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"249.83577ms","start":"2026-09-25T03:24:36.650494Z","end":"2026-09-25T03:24:36.900329Z","steps":["trace[1417660437] 'process raft request' (duration: 249.214346ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.900421Z","caller":"traceutil/trace.go:171","msg":"trace[1172970233] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"249.709717ms","start":"2026-09-25T03:24:36.650686Z","end":"2026-09-25T03:24:36.900396Z","steps":["trace[1172970233] 'process raft request' (duration: 249.297218ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.900541Z","caller":"traceutil/trace.go:171","msg":"trace[1318493032] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"248.548991ms","start":"2026-09-25T03:24:36.651966Z","end":"2026-09-25T03:24:36.900515Z","steps":["trace[1318493032] 'process raft request' (duration: 248.121422ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:36.900738Z","caller":"traceutil/trace.go:171","msg":"trace[1050551431] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"248.743456ms","start":"2026-09-25T03:24:36.651964Z","end":"2026-09-25T03:24:36.900708Z","steps":["trace[1050551431] 'process raft request' (duration: 248.180043ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:24:48.434939Z","caller":"traceutil/trace.go:171","msg":"trace[1864024891] transaction","detail":"{read_only:false; response_revision:1971; number_of_response:1; }","duration":"131.816144ms","start":"2026-09-25T03:24:48.303104Z","end":"2026-09-25T03:24:48.43492Z","steps":["trace[1864024891] 'process raft request' (duration: 131.612179ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:25:26.876702Z","caller":"traceutil/trace.go:171","msg":"trace[1544239034] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"101.042028ms","start":"2026-09-25T03:25:26.77561Z","end":"2026-09-25T03:25:26.876652Z","steps":["trace[1544239034] 'process raft request' (duration: 100.696581ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:26:23.395941Z","caller":"traceutil/trace.go:171","msg":"trace[342893689] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"181.233739ms","start":"2026-09-25T03:26:23.214677Z","end":"2026-09-25T03:26:23.395911Z","steps":["trace[342893689] 'process raft request' (duration: 181.074576ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:31:35.649964Z","caller":"traceutil/trace.go:171","msg":"trace[375158724] transaction","detail":"{read_only:false; response_revision:4973; number_of_response:1; }","duration":"101.57916ms","start":"2026-09-25T03:31:35.548356Z","end":"2026-09-25T03:31:35.649935Z","steps":["trace[375158724] 'process raft request' (duration: 101.399616ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:31:35.677328Z","caller":"traceutil/trace.go:171","msg":"trace[966950929] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"112.130028ms","start":"2026-09-25T03:31:35.565139Z","end":"2026-09-25T03:31:35.67727Z","steps":["trace[966950929] 'process raft request' (duration: 111.911293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:31:49.021041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.150051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-25T03:31:49.021188Z","caller":"traceutil/trace.go:171","msg":"trace[740761601] transaction","detail":"{read_only:false; response_revision:5317; number_of_response:1; }","duration":"286.756746ms","start":"2026-09-25T03:31:48.734408Z","end":"2026-09-25T03:31:49.021165Z","steps":["trace[740761601] 'process raft request' (duration: 126.221896ms)","trace[740761601] 'compare' (duration: 159.951376ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:31:49.063531Z","caller":"traceutil/trace.go:171","msg":"trace[1512628024] transaction","detail":"{read_only:false; response_revision:5318; number_of_response:1; }","duration":"326.90867ms","start":"2026-09-25T03:31:48.73653Z","end":"2026-09-25T03:31:49.063439Z","steps":["trace[1512628024] 'process raft request' (duration: 326.549932ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:31:49.063732Z","caller":"traceutil/trace.go:171","msg":"trace[641494418] transaction","detail":"{read_only:false; response_revision:5320; number_of_response:1; }","duration":"319.309149ms","start":"2026-09-25T03:31:48.744411Z","end":"2026-09-25T03:31:49.06372Z","steps":["trace[641494418] 'process raft request' (duration: 318.943921ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:31:49.063545Z","caller":"traceutil/trace.go:171","msg":"trace[621695730] transaction","detail":"{read_only:false; response_revision:5319; number_of_response:1; }","duration":"319.445131ms","start":"2026-09-25T03:31:48.744062Z","end":"2026-09-25T03:31:49.063507Z","steps":["trace[621695730] 'process raft request' (duration: 319.235036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:31:49.063789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:31:48.736471Z","time spent":"327.191676ms","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":928,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T03:31:49.063905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:31:48.744399Z","time spent":"319.38382ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:31:49.063886Z","caller":"traceutil/trace.go:171","msg":"trace[808993537] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"317.640921ms","start":"2026-09-25T03:31:48.746215Z","end":"2026-09-25T03:31:49.063856Z","steps":["trace[808993537] 'process raft request' (duration: 317.511958ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:31:49.063961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:31:48.744037Z","time spent":"319.787369ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T03:31:49.064052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:31:48.746169Z","time spent":"317.796554ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T03:31:49.064275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.764256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e657b30c-4673-4025-8a40-dc2e1da78312\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-09-25T03:31:49.064348Z","caller":"traceutil/trace.go:171","msg":"trace[76787831] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e657b30c-4673-4025-8a40-dc2e1da78312; range_end:; response_count:1; response_revision:5321; }","duration":"263.843728ms","start":"2026-09-25T03:31:48.800486Z","end":"2026-09-25T03:31:49.06433Z","steps":["trace[76787831] 'agreement among raft nodes before linearized reading' (duration: 263.585992ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:31:49.064432Z","caller":"traceutil/trace.go:171","msg":"trace[982761244] linearizableReadLoop","detail":"{readStateIndex:5529; appliedIndex:5524; }","duration":"263.302126ms","start":"2026-09-25T03:31:48.800516Z","end":"2026-09-25T03:31:49.063818Z","steps":["trace[982761244] 'read index received' (duration: 60.043403ms)","trace[982761244] 'applied index is now lower than readState.Index' (duration: 203.257733ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:31:49.076651Z","caller":"traceutil/trace.go:171","msg":"trace[1623963362] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"151.73578ms","start":"2026-09-25T03:31:48.924891Z","end":"2026-09-25T03:31:49.076627Z","steps":["trace[1623963362] 'process raft request' (duration: 151.382222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:31:49.076947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.995011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-25T03:31:49.077147Z","caller":"traceutil/trace.go:171","msg":"trace[1485273529] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5323; }","duration":"201.216946ms","start":"2026-09-25T03:31:48.875909Z","end":"2026-09-25T03:31:49.077126Z","steps":["trace[1485273529] 'agreement among raft nodes before linearized reading' (duration: 200.921409ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:31:49.077339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.882087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:413950"} {"level":"info","ts":"2026-09-25T03:31:49.077465Z","caller":"traceutil/trace.go:171","msg":"trace[1884093995] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:5323; }","duration":"157.072711ms","start":"2026-09-25T03:31:48.92037Z","end":"2026-09-25T03:31:49.077443Z","steps":["trace[1884093995] 'agreement among raft nodes before linearized reading' (duration: 156.699023ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:32:04.678439Z","caller":"traceutil/trace.go:171","msg":"trace[1324995124] transaction","detail":"{read_only:false; response_revision:5604; number_of_response:1; }","duration":"124.20362ms","start":"2026-09-25T03:32:04.554199Z","end":"2026-09-25T03:32:04.678402Z","steps":["trace[1324995124] 'process raft request' (duration: 123.992226ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:32:05.018757Z","caller":"traceutil/trace.go:171","msg":"trace[1105066727] transaction","detail":"{read_only:false; response_revision:5605; number_of_response:1; }","duration":"334.752116ms","start":"2026-09-25T03:32:04.683966Z","end":"2026-09-25T03:32:05.018718Z","steps":["trace[1105066727] 'process raft request' (duration: 334.525081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:32:05.019027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:32:04.683919Z","time spent":"334.94926ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:32:05.019885Z","caller":"traceutil/trace.go:171","msg":"trace[1239302583] transaction","detail":"{read_only:false; response_revision:5606; number_of_response:1; }","duration":"334.818008ms","start":"2026-09-25T03:32:04.685032Z","end":"2026-09-25T03:32:05.01985Z","steps":["trace[1239302583] 'process raft request' (duration: 334.531462ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:32:05.020032Z","caller":"traceutil/trace.go:171","msg":"trace[492147646] linearizableReadLoop","detail":"{readStateIndex:5823; appliedIndex:5820; }","duration":"246.537737ms","start":"2026-09-25T03:32:04.773473Z","end":"2026-09-25T03:32:05.020011Z","steps":["trace[492147646] 'read index received' (duration: 245.275569ms)","trace[492147646] 'applied index is now lower than readState.Index' (duration: 1.260368ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:32:05.020105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:32:04.684997Z","time spent":"334.992622ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T03:32:05.020181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.696032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:32:05.020249Z","caller":"traceutil/trace.go:171","msg":"trace[728517216] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:5608; }","duration":"246.823954ms","start":"2026-09-25T03:32:04.773406Z","end":"2026-09-25T03:32:05.02023Z","steps":["trace[728517216] 'agreement among raft nodes before linearized reading' (duration: 246.679781ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:32:05.020437Z","caller":"traceutil/trace.go:171","msg":"trace[2022549838] transaction","detail":"{read_only:false; response_revision:5607; number_of_response:1; }","duration":"335.364431ms","start":"2026-09-25T03:32:04.685056Z","end":"2026-09-25T03:32:05.02042Z","steps":["trace[2022549838] 'process raft request' (duration: 334.669995ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:32:05.020508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:32:04.685026Z","time spent":"335.450042ms","remote":"127.0.0.1:59832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":681,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:32:05.020686Z","caller":"traceutil/trace.go:171","msg":"trace[1466941336] transaction","detail":"{read_only:false; response_revision:5608; number_of_response:1; }","duration":"335.583515ms","start":"2026-09-25T03:32:04.685084Z","end":"2026-09-25T03:32:05.020668Z","steps":["trace[1466941336] 'process raft request' (duration: 334.694205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:32:05.02079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T03:32:04.685052Z","time spent":"335.678557ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1038,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T03:32:09.387147Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2941} {"level":"info","ts":"2026-09-25T03:32:09.478592Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2941,"took":"88.830161ms","hash":2168227140,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":31494144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-25T03:32:09.47866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2168227140,"revision":2941,"compact-revision":-1} {"level":"info","ts":"2026-09-25T03:37:09.394269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5684} {"level":"info","ts":"2026-09-25T03:37:09.47757Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5684,"took":"81.374788ms","hash":1206125113,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":25972736,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-25T03:37:09.47764Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1206125113,"revision":5684,"compact-revision":2941} {"level":"info","ts":"2026-09-25T03:39:10.677162Z","caller":"traceutil/trace.go:171","msg":"trace[1737152804] transaction","detail":"{read_only:false; response_revision:8867; number_of_response:1; }","duration":"128.641749ms","start":"2026-09-25T03:39:10.548497Z","end":"2026-09-25T03:39:10.677139Z","steps":["trace[1737152804] 'process raft request' (duration: 128.354293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:39:10.677483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.674207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-09-25T03:39:10.67754Z","caller":"traceutil/trace.go:171","msg":"trace[2130841267] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8867; }","duration":"123.775689ms","start":"2026-09-25T03:39:10.553754Z","end":"2026-09-25T03:39:10.67753Z","steps":["trace[2130841267] 'agreement among raft nodes before linearized reading' (duration: 123.603625ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:10.677307Z","caller":"traceutil/trace.go:171","msg":"trace[1285643129] linearizableReadLoop","detail":"{readStateIndex:9188; appliedIndex:9187; }","duration":"123.215246ms","start":"2026-09-25T03:39:10.553793Z","end":"2026-09-25T03:39:10.677009Z","steps":["trace[1285643129] 'read index received' (duration: 122.964611ms)","trace[1285643129] 'applied index is now lower than readState.Index' (duration: 249.615µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:12.416005Z","caller":"traceutil/trace.go:171","msg":"trace[886562106] linearizableReadLoop","detail":"{readStateIndex:9197; appliedIndex:9196; }","duration":"236.227341ms","start":"2026-09-25T03:39:12.179754Z","end":"2026-09-25T03:39:12.415981Z","steps":["trace[886562106] 'read index received' (duration: 236.056958ms)","trace[886562106] 'applied index is now lower than readState.Index' (duration: 169.023µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:12.416138Z","caller":"traceutil/trace.go:171","msg":"trace[402554130] transaction","detail":"{read_only:false; response_revision:8876; number_of_response:1; }","duration":"237.650474ms","start":"2026-09-25T03:39:12.178443Z","end":"2026-09-25T03:39:12.416093Z","steps":["trace[402554130] 'process raft request' (duration: 237.379928ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:39:12.416288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.317168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-25T03:39:12.416294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.516778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-09-25T03:39:12.416363Z","caller":"traceutil/trace.go:171","msg":"trace[1422843639] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:8876; }","duration":"100.456952ms","start":"2026-09-25T03:39:12.31588Z","end":"2026-09-25T03:39:12.416337Z","steps":["trace[1422843639] 'agreement among raft nodes before linearized reading' (duration: 100.306908ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:12.416372Z","caller":"traceutil/trace.go:171","msg":"trace[630745567] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8876; }","duration":"236.645292ms","start":"2026-09-25T03:39:12.179708Z","end":"2026-09-25T03:39:12.416353Z","steps":["trace[630745567] 'agreement among raft nodes before linearized reading' (duration: 236.390915ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:44.825582Z","caller":"traceutil/trace.go:171","msg":"trace[1627443499] linearizableReadLoop","detail":"{readStateIndex:9433; appliedIndex:9432; }","duration":"128.536927ms","start":"2026-09-25T03:39:44.69702Z","end":"2026-09-25T03:39:44.825556Z","steps":["trace[1627443499] 'read index received' (duration: 128.313541ms)","trace[1627443499] 'applied index is now lower than readState.Index' (duration: 222.466µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:44.825915Z","caller":"traceutil/trace.go:171","msg":"trace[353720740] transaction","detail":"{read_only:false; response_revision:9103; number_of_response:1; }","duration":"131.473203ms","start":"2026-09-25T03:39:44.694423Z","end":"2026-09-25T03:39:44.825896Z","steps":["trace[353720740] 'process raft request' (duration: 130.974372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:39:44.826202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.157931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-25T03:39:44.826231Z","caller":"traceutil/trace.go:171","msg":"trace[634489358] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9103; }","duration":"129.212042ms","start":"2026-09-25T03:39:44.697012Z","end":"2026-09-25T03:39:44.826224Z","steps":["trace[634489358] 'agreement among raft nodes before linearized reading' (duration: 129.067079ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T03:39:44.826339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.56485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-25T03:39:44.826366Z","caller":"traceutil/trace.go:171","msg":"trace[1891927030] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:9103; }","duration":"125.604351ms","start":"2026-09-25T03:39:44.700751Z","end":"2026-09-25T03:39:44.826356Z","steps":["trace[1891927030] 'agreement among raft nodes before linearized reading' (duration: 125.55651ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:44.982272Z","caller":"traceutil/trace.go:171","msg":"trace[21828870] transaction","detail":"{read_only:false; response_revision:9104; number_of_response:1; }","duration":"151.115577ms","start":"2026-09-25T03:39:44.831124Z","end":"2026-09-25T03:39:44.98224Z","steps":["trace[21828870] 'process raft request' (duration: 118.697694ms)","trace[21828870] 'compare' (duration: 32.256459ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:49.405382Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024f8.wal"} {"level":"warn","ts":"2026-09-25T03:39:52.943257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.751228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-25T03:39:52.943353Z","caller":"traceutil/trace.go:171","msg":"trace[1052424678] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:9146; }","duration":"165.942442ms","start":"2026-09-25T03:39:52.777391Z","end":"2026-09-25T03:39:52.943333Z","steps":["trace[1052424678] 'range keys from in-memory index tree' (duration: 165.547844ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:39:53.192947Z","caller":"traceutil/trace.go:171","msg":"trace[757736006] transaction","detail":"{read_only:false; response_revision:9153; number_of_response:1; }","duration":"126.383347ms","start":"2026-09-25T03:39:53.066511Z","end":"2026-09-25T03:39:53.192894Z","steps":["trace[757736006] 'process raft request' (duration: 102.100299ms)","trace[757736006] 'compare' (duration: 23.854549ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:39:56.429133Z","caller":"traceutil/trace.go:171","msg":"trace[1198720239] transaction","detail":"{read_only:false; response_revision:9180; number_of_response:1; }","duration":"133.331256ms","start":"2026-09-25T03:39:56.295771Z","end":"2026-09-25T03:39:56.429103Z","steps":["trace[1198720239] 'process raft request' (duration: 132.64332ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:40:48.565731Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T03:40:48.574263Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-09-25T03:40:48.574432Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-09-25T03:42:09.402372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7945} {"level":"info","ts":"2026-09-25T03:42:09.476295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7945,"took":"71.215625ms","hash":1947599765,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":27676672,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-25T03:42:09.476364Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1947599765,"revision":7945,"compact-revision":5684} {"level":"info","ts":"2026-09-25T03:47:07.619949Z","caller":"traceutil/trace.go:171","msg":"trace[2118015398] transaction","detail":"{read_only:false; response_revision:12476; number_of_response:1; }","duration":"115.775062ms","start":"2026-09-25T03:47:07.504149Z","end":"2026-09-25T03:47:07.619924Z","steps":["trace[2118015398] 'process raft request' (duration: 115.732961ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:47:07.620014Z","caller":"traceutil/trace.go:171","msg":"trace[1138087832] transaction","detail":"{read_only:false; response_revision:12475; number_of_response:1; }","duration":"117.115493ms","start":"2026-09-25T03:47:07.502871Z","end":"2026-09-25T03:47:07.619986Z","steps":["trace[1138087832] 'process raft request' (duration: 116.907788ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:47:09.477773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10345} {"level":"info","ts":"2026-09-25T03:47:09.731395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10345,"took":"251.651028ms","hash":1990416893,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":25473024,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-25T03:47:09.731458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1990416893,"revision":10345,"compact-revision":7945} {"level":"info","ts":"2026-09-25T03:52:09.484843Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12484} {"level":"info","ts":"2026-09-25T03:52:09.560082Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12484,"took":"72.727096ms","hash":2072094170,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":27693056,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-25T03:52:09.560155Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2072094170,"revision":12484,"compact-revision":10345} {"level":"info","ts":"2026-09-25T03:53:27.904107Z","caller":"traceutil/trace.go:171","msg":"trace[1869620001] transaction","detail":"{read_only:false; response_revision:15026; number_of_response:1; }","duration":"111.425937ms","start":"2026-09-25T03:53:27.792658Z","end":"2026-09-25T03:53:27.904084Z","steps":["trace[1869620001] 'process raft request' (duration: 107.797945ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:27.904224Z","caller":"traceutil/trace.go:171","msg":"trace[608120670] transaction","detail":"{read_only:false; response_revision:15027; number_of_response:1; }","duration":"110.329161ms","start":"2026-09-25T03:53:27.79387Z","end":"2026-09-25T03:53:27.904199Z","steps":["trace[608120670] 'process raft request' (duration: 110.175598ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:45.649463Z","caller":"traceutil/trace.go:171","msg":"trace[2138533105] transaction","detail":"{read_only:false; response_revision:15132; number_of_response:1; }","duration":"154.980434ms","start":"2026-09-25T03:53:45.494453Z","end":"2026-09-25T03:53:45.649433Z","steps":["trace[2138533105] 'process raft request' (duration: 154.792869ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:56.065157Z","caller":"traceutil/trace.go:171","msg":"trace[123149089] transaction","detail":"{read_only:false; response_revision:15305; number_of_response:1; }","duration":"133.696905ms","start":"2026-09-25T03:53:55.931427Z","end":"2026-09-25T03:53:56.065124Z","steps":["trace[123149089] 'process raft request' (duration: 133.504861ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:56.183583Z","caller":"traceutil/trace.go:171","msg":"trace[2019457580] transaction","detail":"{read_only:false; response_revision:15306; number_of_response:1; }","duration":"114.282779ms","start":"2026-09-25T03:53:56.069276Z","end":"2026-09-25T03:53:56.183559Z","steps":["trace[2019457580] 'process raft request' (duration: 110.502704ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:56.186193Z","caller":"traceutil/trace.go:171","msg":"trace[1846624189] transaction","detail":"{read_only:false; response_revision:15307; number_of_response:1; }","duration":"115.802672ms","start":"2026-09-25T03:53:56.070372Z","end":"2026-09-25T03:53:56.186174Z","steps":["trace[1846624189] 'process raft request' (duration: 115.628639ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:56.186199Z","caller":"traceutil/trace.go:171","msg":"trace[1066013097] transaction","detail":"{read_only:false; response_revision:15308; number_of_response:1; }","duration":"115.700651ms","start":"2026-09-25T03:53:56.070481Z","end":"2026-09-25T03:53:56.186182Z","steps":["trace[1066013097] 'process raft request' (duration: 115.606689ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:53:56.186667Z","caller":"traceutil/trace.go:171","msg":"trace[1172256588] transaction","detail":"{read_only:false; response_revision:15309; number_of_response:1; }","duration":"102.982575ms","start":"2026-09-25T03:53:56.083673Z","end":"2026-09-25T03:53:56.186656Z","steps":["trace[1172256588] 'process raft request' (duration: 102.457174ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:55:36.245626Z","caller":"traceutil/trace.go:171","msg":"trace[1344813453] transaction","detail":"{read_only:false; response_revision:16387; number_of_response:1; }","duration":"137.832967ms","start":"2026-09-25T03:55:36.107773Z","end":"2026-09-25T03:55:36.245605Z","steps":["trace[1344813453] 'process raft request' (duration: 137.51179ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:55:36.534119Z","caller":"traceutil/trace.go:171","msg":"trace[357958886] transaction","detail":"{read_only:false; response_revision:16388; number_of_response:1; }","duration":"284.654698ms","start":"2026-09-25T03:55:36.249386Z","end":"2026-09-25T03:55:36.534041Z","steps":["trace[357958886] 'process raft request' (duration: 271.549943ms)","trace[357958886] 'compare' (duration: 12.741787ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T03:55:36.534184Z","caller":"traceutil/trace.go:171","msg":"trace[1371712543] linearizableReadLoop","detail":"{readStateIndex:16958; appliedIndex:16957; }","duration":"124.453077ms","start":"2026-09-25T03:55:36.409714Z","end":"2026-09-25T03:55:36.533947Z","steps":["trace[1371712543] 'read index received' (duration: 111.227029ms)","trace[1371712543] 'applied index is now lower than readState.Index' (duration: 13.005573ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T03:55:36.534325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.59559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-09-25T03:55:36.534389Z","caller":"traceutil/trace.go:171","msg":"trace[1122265260] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:16388; }","duration":"124.701322ms","start":"2026-09-25T03:55:36.40967Z","end":"2026-09-25T03:55:36.534371Z","steps":["trace[1122265260] 'agreement among raft nodes before linearized reading' (duration: 124.492388ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T03:57:09.491575Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14534} {"level":"info","ts":"2026-09-25T03:57:09.573319Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14534,"took":"79.169539ms","hash":1304282376,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":31633408,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-25T03:57:09.573391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1304282376,"revision":14534,"compact-revision":12484} {"level":"info","ts":"2026-09-25T04:01:13.15605Z","caller":"traceutil/trace.go:171","msg":"trace[560749970] linearizableReadLoop","detail":"{readStateIndex:19587; appliedIndex:19586; }","duration":"127.692764ms","start":"2026-09-25T04:01:13.028331Z","end":"2026-09-25T04:01:13.156024Z","steps":["trace[560749970] 'read index received' (duration: 127.474309ms)","trace[560749970] 'applied index is now lower than readState.Index' (duration: 217.625µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:01:13.156167Z","caller":"traceutil/trace.go:171","msg":"trace[1332280233] transaction","detail":"{read_only:false; response_revision:18934; number_of_response:1; }","duration":"176.207689ms","start":"2026-09-25T04:01:12.979949Z","end":"2026-09-25T04:01:13.156157Z","steps":["trace[1332280233] 'process raft request' (duration: 175.921373ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:13.156336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.98164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:01:13.156367Z","caller":"traceutil/trace.go:171","msg":"trace[485644374] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:18934; }","duration":"128.054032ms","start":"2026-09-25T04:01:13.028303Z","end":"2026-09-25T04:01:13.156357Z","steps":["trace[485644374] 'agreement among raft nodes before linearized reading' (duration: 127.980621ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:20.284863Z","caller":"traceutil/trace.go:171","msg":"trace[540117000] transaction","detail":"{read_only:false; response_revision:19041; number_of_response:1; }","duration":"381.558301ms","start":"2026-09-25T04:01:19.903277Z","end":"2026-09-25T04:01:20.284836Z","steps":["trace[540117000] 'process raft request' (duration: 381.365597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.285141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:19.903256Z","time spent":"381.692173ms","remote":"127.0.0.1:59854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:01:20.472754Z","caller":"traceutil/trace.go:171","msg":"trace[647042248] linearizableReadLoop","detail":"{readStateIndex:19700; appliedIndex:19695; }","duration":"300.62903ms","start":"2026-09-25T04:01:20.172102Z","end":"2026-09-25T04:01:20.472731Z","steps":["trace[647042248] 'read index received' (duration: 113.036326ms)","trace[647042248] 'applied index is now lower than readState.Index' (duration: 187.591704ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:01:20.47289Z","caller":"traceutil/trace.go:171","msg":"trace[1554592456] transaction","detail":"{read_only:false; response_revision:19043; number_of_response:1; }","duration":"440.046418ms","start":"2026-09-25T04:01:20.032833Z","end":"2026-09-25T04:01:20.472879Z","steps":["trace[1554592456] 'process raft request' (duration: 439.71459ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:20.472938Z","caller":"traceutil/trace.go:171","msg":"trace[895634959] transaction","detail":"{read_only:false; response_revision:19042; number_of_response:1; }","duration":"493.84227ms","start":"2026-09-25T04:01:19.97907Z","end":"2026-09-25T04:01:20.472912Z","steps":["trace[895634959] 'process raft request' (duration: 493.329779ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.47301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:20.032817Z","time spent":"440.09945ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:01:20.473066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:19.979048Z","time spent":"493.955982ms","remote":"127.0.0.1:59730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":917,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-25T04:01:20.473192Z","caller":"traceutil/trace.go:171","msg":"trace[1492403026] transaction","detail":"{read_only:false; response_revision:19044; number_of_response:1; }","duration":"379.011983ms","start":"2026-09-25T04:01:20.094165Z","end":"2026-09-25T04:01:20.473177Z","steps":["trace[1492403026] 'process raft request' (duration: 378.440691ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:20.473232Z","caller":"traceutil/trace.go:171","msg":"trace[1460078292] transaction","detail":"{read_only:false; response_revision:19045; number_of_response:1; }","duration":"376.18681ms","start":"2026-09-25T04:01:20.097021Z","end":"2026-09-25T04:01:20.473208Z","steps":["trace[1460078292] 'process raft request' (duration: 375.619347ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.473274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:20.09414Z","time spent":"379.089564ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:01:20.473364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:20.096997Z","time spent":"376.302472ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:01:20.473374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.275085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-25T04:01:20.473369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.544871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-29838480-dn9pz\" ","response":"range_response_count:1 size:7040"} {"level":"info","ts":"2026-09-25T04:01:20.473428Z","caller":"traceutil/trace.go:171","msg":"trace[1477620209] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19046; }","duration":"301.373727ms","start":"2026-09-25T04:01:20.172042Z","end":"2026-09-25T04:01:20.473415Z","steps":["trace[1477620209] 'agreement among raft nodes before linearized reading' (duration: 301.195914ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:20.473452Z","caller":"traceutil/trace.go:171","msg":"trace[561384757] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-29838480-dn9pz; range_end:; response_count:1; response_revision:19046; }","duration":"288.678634ms","start":"2026-09-25T04:01:20.184758Z","end":"2026-09-25T04:01:20.473437Z","steps":["trace[561384757] 'agreement among raft nodes before linearized reading' (duration: 288.415788ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.473544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.175088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-25T04:01:20.47359Z","caller":"traceutil/trace.go:171","msg":"trace[856808200] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:19046; }","duration":"159.261281ms","start":"2026-09-25T04:01:20.314317Z","end":"2026-09-25T04:01:20.473579Z","steps":["trace[856808200] 'agreement among raft nodes before linearized reading' (duration: 159.043836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.473458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:20.17202Z","time spent":"301.431598ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-25T04:01:20.473674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.449768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat\" ","response":"range_response_count:1 size:3299"} {"level":"info","ts":"2026-09-25T04:01:20.473797Z","caller":"traceutil/trace.go:171","msg":"trace[748576961] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-heat; range_end:; response_count:1; response_revision:19046; }","duration":"135.607061ms","start":"2026-09-25T04:01:20.33818Z","end":"2026-09-25T04:01:20.473787Z","steps":["trace[748576961] 'agreement among raft nodes before linearized reading' (duration: 135.071649ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:01:20.473122Z","caller":"traceutil/trace.go:171","msg":"trace[1309183679] transaction","detail":"{read_only:false; response_revision:19046; number_of_response:1; }","duration":"374.83733ms","start":"2026-09-25T04:01:20.098249Z","end":"2026-09-25T04:01:20.473086Z","steps":["trace[1309183679] 'process raft request' (duration: 374.420271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:01:20.474429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:01:20.098232Z","time spent":"376.130889ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:01:52.824191Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:01:52.831588Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-09-25T04:01:52.831766Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-09-25T04:01:56.74092Z","caller":"traceutil/trace.go:171","msg":"trace[1045886191] transaction","detail":"{read_only:false; response_revision:19504; number_of_response:1; }","duration":"108.072144ms","start":"2026-09-25T04:01:56.632823Z","end":"2026-09-25T04:01:56.740895Z","steps":["trace[1045886191] 'process raft request' (duration: 107.87935ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:02:09.498326Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17003} {"level":"info","ts":"2026-09-25T04:02:09.602909Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17003,"took":"100.723829ms","hash":539402729,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":34320384,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-09-25T04:02:09.602983Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":539402729,"revision":17003,"compact-revision":14534} {"level":"info","ts":"2026-09-25T04:07:09.505507Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19614} {"level":"info","ts":"2026-09-25T04:07:09.62692Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19614,"took":"118.111613ms","hash":4180443763,"current-db-size-bytes":56176640,"current-db-size":"56 MB","current-db-size-in-use-bytes":30220288,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:07:09.626994Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4180443763,"revision":19614,"compact-revision":17003} {"level":"info","ts":"2026-09-25T04:07:52.633339Z","caller":"traceutil/trace.go:171","msg":"trace[1534862641] transaction","detail":"{read_only:false; response_revision:21948; number_of_response:1; }","duration":"132.690721ms","start":"2026-09-25T04:07:52.500627Z","end":"2026-09-25T04:07:52.633317Z","steps":["trace[1534862641] 'process raft request' (duration: 132.546588ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:07:52.784325Z","caller":"traceutil/trace.go:171","msg":"trace[217260050] linearizableReadLoop","detail":"{readStateIndex:22701; appliedIndex:22699; }","duration":"156.400134ms","start":"2026-09-25T04:07:52.627897Z","end":"2026-09-25T04:07:52.784297Z","steps":["trace[217260050] 'read index received' (duration: 5.271719ms)","trace[217260050] 'applied index is now lower than readState.Index' (duration: 151.127525ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:07:52.784463Z","caller":"traceutil/trace.go:171","msg":"trace[1346411851] transaction","detail":"{read_only:false; response_revision:21949; number_of_response:1; }","duration":"236.287739ms","start":"2026-09-25T04:07:52.548153Z","end":"2026-09-25T04:07:52.784441Z","steps":["trace[1346411851] 'process raft request' (duration: 215.598284ms)","trace[1346411851] 'compare' (duration: 20.43791ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:07:52.784568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.657069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:07:52.784625Z","caller":"traceutil/trace.go:171","msg":"trace[802584116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21949; }","duration":"156.764022ms","start":"2026-09-25T04:07:52.627843Z","end":"2026-09-25T04:07:52.784607Z","steps":["trace[802584116] 'agreement among raft nodes before linearized reading' (duration: 156.592678ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:07:52.78485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.349439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:07:52.784936Z","caller":"traceutil/trace.go:171","msg":"trace[1911669530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21949; }","duration":"122.464692ms","start":"2026-09-25T04:07:52.662452Z","end":"2026-09-25T04:07:52.784916Z","steps":["trace[1911669530] 'agreement among raft nodes before linearized reading' (duration: 122.208156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:07:52.784842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.648872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:07:52.785028Z","caller":"traceutil/trace.go:171","msg":"trace[887884603] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21949; }","duration":"137.918638ms","start":"2026-09-25T04:07:52.647089Z","end":"2026-09-25T04:07:52.785008Z","steps":["trace[887884603] 'agreement among raft nodes before linearized reading' (duration: 137.506309ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:07:53.092925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.406405ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:07:53.093112Z","caller":"traceutil/trace.go:171","msg":"trace[304346380] linearizableReadLoop","detail":"{readStateIndex:22705; appliedIndex:22702; }","duration":"210.789115ms","start":"2026-09-25T04:07:52.882308Z","end":"2026-09-25T04:07:53.093097Z","steps":["trace[304346380] 'read index received' (duration: 106.164795ms)","trace[304346380] 'applied index is now lower than readState.Index' (duration: 104.62326ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:07:53.093281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.977019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-25T04:07:53.093267Z","caller":"traceutil/trace.go:171","msg":"trace[642352434] transaction","detail":"{read_only:false; response_revision:21951; number_of_response:1; }","duration":"303.842056ms","start":"2026-09-25T04:07:52.789394Z","end":"2026-09-25T04:07:53.093236Z","steps":["trace[642352434] 'process raft request' (duration: 199.047842ms)","trace[642352434] 'compare' (duration: 104.15162ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:07:53.093307Z","caller":"traceutil/trace.go:171","msg":"trace[522516380] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:21953; }","duration":"211.04343ms","start":"2026-09-25T04:07:52.882255Z","end":"2026-09-25T04:07:53.093298Z","steps":["trace[522516380] 'agreement among raft nodes before linearized reading' (duration: 210.891777ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:07:53.093266Z","caller":"traceutil/trace.go:171","msg":"trace[786342225] transaction","detail":"{read_only:false; response_revision:21952; number_of_response:1; }","duration":"303.722163ms","start":"2026-09-25T04:07:52.789509Z","end":"2026-09-25T04:07:53.093231Z","steps":["trace[786342225] 'process raft request' (duration: 303.499558ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:07:53.093308Z","caller":"traceutil/trace.go:171","msg":"trace[1847309384] transaction","detail":"{read_only:false; response_revision:21953; number_of_response:1; }","duration":"303.238362ms","start":"2026-09-25T04:07:52.790046Z","end":"2026-09-25T04:07:53.093284Z","steps":["trace[1847309384] 'process raft request' (duration: 303.015787ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:07:53.093487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:07:52.790029Z","time spent":"303.336414ms","remote":"127.0.0.1:59938","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-09-25T04:07:53.093488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:07:52.789379Z","time spent":"303.98715ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:07:53.093504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:07:52.789342Z","time spent":"303.988699ms","remote":"127.0.0.1:59938","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-09-25T04:07:53.263175Z","caller":"traceutil/trace.go:171","msg":"trace[807917016] transaction","detail":"{read_only:false; response_revision:21954; number_of_response:1; }","duration":"144.980467ms","start":"2026-09-25T04:07:53.118165Z","end":"2026-09-25T04:07:53.263146Z","steps":["trace[807917016] 'process raft request' (duration: 135.088215ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:11:54.014185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.130539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-09-25T04:11:54.01427Z","caller":"traceutil/trace.go:171","msg":"trace[286823826] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:24443; }","duration":"238.261622ms","start":"2026-09-25T04:11:53.775991Z","end":"2026-09-25T04:11:54.014253Z","steps":["trace[286823826] 'range keys from in-memory index tree' (duration: 237.925375ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:12:09.512465Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21730} {"level":"info","ts":"2026-09-25T04:12:09.624541Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21730,"took":"107.857182ms","hash":4097873866,"current-db-size-bytes":56754176,"current-db-size":"57 MB","current-db-size-in-use-bytes":41746432,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-09-25T04:12:09.624614Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4097873866,"revision":21730,"compact-revision":19614} {"level":"info","ts":"2026-09-25T04:14:49.449983Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006990.wal"} {"level":"info","ts":"2026-09-25T04:15:20.57583Z","caller":"traceutil/trace.go:171","msg":"trace[860002932] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"114.812816ms","start":"2026-09-25T04:15:20.46099Z","end":"2026-09-25T04:15:20.575803Z","steps":["trace[860002932] 'process raft request' (duration: 114.058199ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:26.101168Z","caller":"traceutil/trace.go:171","msg":"trace[2047356293] transaction","detail":"{read_only:false; response_revision:26568; number_of_response:1; }","duration":"230.49606ms","start":"2026-09-25T04:15:25.870651Z","end":"2026-09-25T04:15:26.101147Z","steps":["trace[2047356293] 'process raft request' (duration: 230.346647ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:26.121991Z","caller":"traceutil/trace.go:171","msg":"trace[656141626] transaction","detail":"{read_only:false; response_revision:26569; number_of_response:1; }","duration":"250.447398ms","start":"2026-09-25T04:15:25.871513Z","end":"2026-09-25T04:15:26.12196Z","steps":["trace[656141626] 'process raft request' (duration: 250.235254ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:26.122304Z","caller":"traceutil/trace.go:171","msg":"trace[840230965] transaction","detail":"{read_only:false; response_revision:26570; number_of_response:1; }","duration":"242.660913ms","start":"2026-09-25T04:15:25.87961Z","end":"2026-09-25T04:15:26.122271Z","steps":["trace[840230965] 'process raft request' (duration: 242.289495ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:26.122425Z","caller":"traceutil/trace.go:171","msg":"trace[1260386159] transaction","detail":"{read_only:false; response_revision:26571; number_of_response:1; }","duration":"237.484207ms","start":"2026-09-25T04:15:25.884835Z","end":"2026-09-25T04:15:26.122319Z","steps":["trace[1260386159] 'process raft request' (duration: 237.301293ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:26.682678Z","caller":"traceutil/trace.go:171","msg":"trace[1856844855] transaction","detail":"{read_only:false; response_revision:26573; number_of_response:1; }","duration":"239.884782ms","start":"2026-09-25T04:15:26.442768Z","end":"2026-09-25T04:15:26.682653Z","steps":["trace[1856844855] 'process raft request' (duration: 239.739849ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:30.969775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.977003ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:30.969899Z","caller":"traceutil/trace.go:171","msg":"trace[516936327] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"202.353438ms","start":"2026-09-25T04:15:30.767521Z","end":"2026-09-25T04:15:30.969875Z","steps":["trace[516936327] 'process raft request' (duration: 81.195811ms)","trace[516936327] 'compare' (duration: 120.842091ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:31.417831Z","caller":"traceutil/trace.go:171","msg":"trace[1226597752] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"150.66414ms","start":"2026-09-25T04:15:31.267137Z","end":"2026-09-25T04:15:31.417801Z","steps":["trace[1226597752] 'process raft request' (duration: 98.483049ms)","trace[1226597752] 'compare' (duration: 51.923425ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:31.651511Z","caller":"traceutil/trace.go:171","msg":"trace[71833847] linearizableReadLoop","detail":"{readStateIndex:27495; appliedIndex:27494; }","duration":"170.613837ms","start":"2026-09-25T04:15:31.480871Z","end":"2026-09-25T04:15:31.651484Z","steps":["trace[71833847] 'read index received' (duration: 170.522985ms)","trace[71833847] 'applied index is now lower than readState.Index' (duration: 89.662µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:31.651646Z","caller":"traceutil/trace.go:171","msg":"trace[183729585] transaction","detail":"{read_only:false; response_revision:26612; number_of_response:1; }","duration":"174.555037ms","start":"2026-09-25T04:15:31.47705Z","end":"2026-09-25T04:15:31.651605Z","steps":["trace[183729585] 'process raft request' (duration: 174.273739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:31.651762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.880814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:15:31.651807Z","caller":"traceutil/trace.go:171","msg":"trace[526357036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26612; }","duration":"170.964805ms","start":"2026-09-25T04:15:31.480829Z","end":"2026-09-25T04:15:31.651793Z","steps":["trace[526357036] 'agreement among raft nodes before linearized reading' (duration: 170.786371ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:31.705685Z","caller":"traceutil/trace.go:171","msg":"trace[1909875944] transaction","detail":"{read_only:false; response_revision:26613; number_of_response:1; }","duration":"188.318264ms","start":"2026-09-25T04:15:31.517335Z","end":"2026-09-25T04:15:31.705653Z","steps":["trace[1909875944] 'process raft request' (duration: 188.092549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:31.705805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.009392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-25T04:15:31.705881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.508749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:15:31.705907Z","caller":"traceutil/trace.go:171","msg":"trace[127289751] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26613; }","duration":"186.160166ms","start":"2026-09-25T04:15:31.519721Z","end":"2026-09-25T04:15:31.705881Z","steps":["trace[127289751] 'agreement among raft nodes before linearized reading' (duration: 185.861259ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:31.705948Z","caller":"traceutil/trace.go:171","msg":"trace[424515175] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26613; }","duration":"184.595051ms","start":"2026-09-25T04:15:31.521335Z","end":"2026-09-25T04:15:31.70593Z","steps":["trace[424515175] 'agreement among raft nodes before linearized reading' (duration: 184.377276ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:32.414286Z","caller":"traceutil/trace.go:171","msg":"trace[1783486255] linearizableReadLoop","detail":"{readStateIndex:27503; appliedIndex:27499; }","duration":"206.817159ms","start":"2026-09-25T04:15:32.207447Z","end":"2026-09-25T04:15:32.414264Z","steps":["trace[1783486255] 'read index received' (duration: 3.963808ms)","trace[1783486255] 'applied index is now lower than readState.Index' (duration: 202.852281ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:32.414398Z","caller":"traceutil/trace.go:171","msg":"trace[1388027703] transaction","detail":"{read_only:false; response_revision:26618; number_of_response:1; }","duration":"273.868384ms","start":"2026-09-25T04:15:32.140485Z","end":"2026-09-25T04:15:32.414354Z","steps":["trace[1388027703] 'process raft request' (duration: 273.518666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:32.414444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.945382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:32.414428Z","caller":"traceutil/trace.go:171","msg":"trace[1793034989] transaction","detail":"{read_only:false; response_revision:26620; number_of_response:1; }","duration":"267.989042ms","start":"2026-09-25T04:15:32.146407Z","end":"2026-09-25T04:15:32.414396Z","steps":["trace[1793034989] 'process raft request' (duration: 267.791917ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:32.414482Z","caller":"traceutil/trace.go:171","msg":"trace[1513595161] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26620; }","duration":"207.057534ms","start":"2026-09-25T04:15:32.207411Z","end":"2026-09-25T04:15:32.414469Z","steps":["trace[1513595161] 'agreement among raft nodes before linearized reading' (duration: 206.938082ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:32.414401Z","caller":"traceutil/trace.go:171","msg":"trace[703039477] transaction","detail":"{read_only:false; response_revision:26619; number_of_response:1; }","duration":"272.699317ms","start":"2026-09-25T04:15:32.141661Z","end":"2026-09-25T04:15:32.41436Z","steps":["trace[703039477] 'process raft request' (duration: 272.496993ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:32.607762Z","caller":"traceutil/trace.go:171","msg":"trace[436618519] transaction","detail":"{read_only:false; response_revision:26621; number_of_response:1; }","duration":"118.818236ms","start":"2026-09-25T04:15:32.4889Z","end":"2026-09-25T04:15:32.607719Z","steps":["trace[436618519] 'process raft request' (duration: 106.245753ms)","trace[436618519] 'compare' (duration: 12.314957ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:32.917124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.923906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:32.917663Z","caller":"traceutil/trace.go:171","msg":"trace[1356633106] transaction","detail":"{read_only:false; response_revision:26622; number_of_response:1; }","duration":"197.172134ms","start":"2026-09-25T04:15:32.720463Z","end":"2026-09-25T04:15:32.917635Z","steps":["trace[1356633106] 'process raft request' (duration: 45.659394ms)","trace[1356633106] 'compare' (duration: 150.427515ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:33.215562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.922179ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-09-25T04:15:33.517795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.55033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:33.518067Z","caller":"traceutil/trace.go:171","msg":"trace[2060510772] linearizableReadLoop","detail":"{readStateIndex:27507; appliedIndex:27506; }","duration":"287.188243ms","start":"2026-09-25T04:15:33.230862Z","end":"2026-09-25T04:15:33.51805Z","steps":["trace[2060510772] 'read index received' (duration: 102.283385ms)","trace[2060510772] 'applied index is now lower than readState.Index' (duration: 184.903238ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:33.518154Z","caller":"traceutil/trace.go:171","msg":"trace[524469996] transaction","detail":"{read_only:false; response_revision:26623; number_of_response:1; }","duration":"301.601956ms","start":"2026-09-25T04:15:33.21654Z","end":"2026-09-25T04:15:33.518142Z","steps":["trace[524469996] 'process raft request' (duration: 116.591346ms)","trace[524469996] 'compare' (duration: 184.253473ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:33.518229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.216515Z","time spent":"301.660828ms","remote":"127.0.0.1:59680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":121,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:33.518377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.202387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-09-25T04:15:33.518435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.934983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:15:33.518436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.54898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:33.518506Z","caller":"traceutil/trace.go:171","msg":"trace[1970585621] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26623; }","duration":"191.051165ms","start":"2026-09-25T04:15:33.327435Z","end":"2026-09-25T04:15:33.518486Z","steps":["trace[1970585621] 'agreement among raft nodes before linearized reading' (duration: 190.911332ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:33.51852Z","caller":"traceutil/trace.go:171","msg":"trace[1277097351] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:26623; }","duration":"287.680494ms","start":"2026-09-25T04:15:33.230817Z","end":"2026-09-25T04:15:33.518497Z","steps":["trace[1277097351] 'agreement among raft nodes before linearized reading' (duration: 287.53472ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:33.518457Z","caller":"traceutil/trace.go:171","msg":"trace[2110101290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26623; }","duration":"252.275459ms","start":"2026-09-25T04:15:33.266148Z","end":"2026-09-25T04:15:33.518424Z","steps":["trace[2110101290] 'agreement among raft nodes before linearized reading' (duration: 252.132896ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.832407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.235772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:33.832839Z","caller":"traceutil/trace.go:171","msg":"trace[1613977168] transaction","detail":"{read_only:false; response_revision:26624; number_of_response:1; }","duration":"308.280365ms","start":"2026-09-25T04:15:33.524503Z","end":"2026-09-25T04:15:33.832784Z","steps":["trace[1613977168] 'process raft request' (duration: 148.566413ms)","trace[1613977168] 'compare' (duration: 158.827023ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:33.832988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.524489Z","time spent":"308.419498ms","remote":"127.0.0.1:59938","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-09-25T04:15:33.832983Z","caller":"traceutil/trace.go:171","msg":"trace[415841925] linearizableReadLoop","detail":"{readStateIndex:27508; appliedIndex:27507; }","duration":"308.04744ms","start":"2026-09-25T04:15:33.524909Z","end":"2026-09-25T04:15:33.832957Z","steps":["trace[415841925] 'read index received' (duration: 148.173734ms)","trace[415841925] 'applied index is now lower than readState.Index' (duration: 159.871246ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:33.833107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.177503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:33.833191Z","caller":"traceutil/trace.go:171","msg":"trace[1913664193] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26624; }","duration":"308.287206ms","start":"2026-09-25T04:15:33.524878Z","end":"2026-09-25T04:15:33.833165Z","steps":["trace[1913664193] 'agreement among raft nodes before linearized reading' (duration: 308.157263ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.833281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.524865Z","time spent":"308.400028ms","remote":"127.0.0.1:59870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-09-25T04:15:33.83371Z","caller":"traceutil/trace.go:171","msg":"trace[797458801] transaction","detail":"{read_only:false; response_revision:26627; number_of_response:1; }","duration":"122.351954ms","start":"2026-09-25T04:15:33.711343Z","end":"2026-09-25T04:15:33.833695Z","steps":["trace[797458801] 'process raft request' (duration: 122.309624ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:33.833719Z","caller":"traceutil/trace.go:171","msg":"trace[1789592088] transaction","detail":"{read_only:false; response_revision:26625; number_of_response:1; }","duration":"308.720316ms","start":"2026-09-25T04:15:33.524982Z","end":"2026-09-25T04:15:33.833702Z","steps":["trace[1789592088] 'process raft request' (duration: 308.50318ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:33.833801Z","caller":"traceutil/trace.go:171","msg":"trace[552721466] transaction","detail":"{read_only:false; response_revision:26626; number_of_response:1; }","duration":"177.324648ms","start":"2026-09-25T04:15:33.656456Z","end":"2026-09-25T04:15:33.83378Z","steps":["trace[552721466] 'process raft request' (duration: 177.151824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.833892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.524952Z","time spent":"308.89165ms","remote":"127.0.0.1:59938","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-09-25T04:15:33.870604Z","caller":"traceutil/trace.go:171","msg":"trace[1327588469] transaction","detail":"{read_only:false; response_revision:26628; number_of_response:1; }","duration":"143.08355ms","start":"2026-09-25T04:15:33.727487Z","end":"2026-09-25T04:15:33.87057Z","steps":["trace[1327588469] 'process raft request' (duration: 142.829494ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.870742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.75609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:15:33.870825Z","caller":"traceutil/trace.go:171","msg":"trace[534399732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26628; }","duration":"137.897083ms","start":"2026-09-25T04:15:33.732907Z","end":"2026-09-25T04:15:33.870804Z","steps":["trace[534399732] 'agreement among raft nodes before linearized reading' (duration: 137.621037ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.870821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.185961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:15:33.870888Z","caller":"traceutil/trace.go:171","msg":"trace[1355966445] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:26628; }","duration":"344.268823ms","start":"2026-09-25T04:15:33.526602Z","end":"2026-09-25T04:15:33.870871Z","steps":["trace[1355966445] 'agreement among raft nodes before linearized reading' (duration: 344.019648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.870871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.737841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-25T04:15:33.870937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.526568Z","time spent":"344.360105ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-09-25T04:15:33.870958Z","caller":"traceutil/trace.go:171","msg":"trace[2127376165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26628; }","duration":"154.859084ms","start":"2026-09-25T04:15:33.716078Z","end":"2026-09-25T04:15:33.870937Z","steps":["trace[2127376165] 'agreement among raft nodes before linearized reading' (duration: 154.67268ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:33.871183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.163229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:15:33.871313Z","caller":"traceutil/trace.go:171","msg":"trace[484768821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26628; }","duration":"138.344363ms","start":"2026-09-25T04:15:33.732952Z","end":"2026-09-25T04:15:33.871296Z","steps":["trace[484768821] 'agreement among raft nodes before linearized reading' (duration: 137.562765ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:34.151828Z","caller":"traceutil/trace.go:171","msg":"trace[269156492] transaction","detail":"{read_only:false; response_revision:26629; number_of_response:1; }","duration":"275.895378ms","start":"2026-09-25T04:15:33.875908Z","end":"2026-09-25T04:15:34.151803Z","steps":["trace[269156492] 'process raft request' (duration: 275.545061ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:34.196991Z","caller":"traceutil/trace.go:171","msg":"trace[1194141697] linearizableReadLoop","detail":"{readStateIndex:27515; appliedIndex:27513; }","duration":"319.850704ms","start":"2026-09-25T04:15:33.877115Z","end":"2026-09-25T04:15:34.196966Z","steps":["trace[1194141697] 'read index received' (duration: 274.513168ms)","trace[1194141697] 'applied index is now lower than readState.Index' (duration: 45.336526ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:34.197308Z","caller":"traceutil/trace.go:171","msg":"trace[2034136984] transaction","detail":"{read_only:false; response_revision:26630; number_of_response:1; }","duration":"320.579711ms","start":"2026-09-25T04:15:33.876716Z","end":"2026-09-25T04:15:34.197296Z","steps":["trace[2034136984] 'process raft request' (duration: 320.005908ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.197419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.876691Z","time spent":"320.654302ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:34.197608Z","caller":"traceutil/trace.go:171","msg":"trace[457058987] transaction","detail":"{read_only:false; response_revision:26631; number_of_response:1; }","duration":"320.835506ms","start":"2026-09-25T04:15:33.876759Z","end":"2026-09-25T04:15:34.197594Z","steps":["trace[457058987] 'process raft request' (duration: 320.10435ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.19767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.876748Z","time spent":"320.890627ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:34.197818Z","caller":"traceutil/trace.go:171","msg":"trace[1887399829] transaction","detail":"{read_only:false; response_revision:26632; number_of_response:1; }","duration":"153.40731ms","start":"2026-09-25T04:15:34.044401Z","end":"2026-09-25T04:15:34.197808Z","steps":["trace[1887399829] 'process raft request' (duration: 152.51713ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.197976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.862517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:34.198015Z","caller":"traceutil/trace.go:171","msg":"trace[1629646866] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:26632; }","duration":"320.936369ms","start":"2026-09-25T04:15:33.877069Z","end":"2026-09-25T04:15:34.198005Z","steps":["trace[1629646866] 'agreement among raft nodes before linearized reading' (duration: 320.856758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.198045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:33.877054Z","time spent":"320.98336ms","remote":"127.0.0.1:59952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-09-25T04:15:34.198188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.87766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:34.198237Z","caller":"traceutil/trace.go:171","msg":"trace[184997258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26632; }","duration":"115.954851ms","start":"2026-09-25T04:15:34.082274Z","end":"2026-09-25T04:15:34.198228Z","steps":["trace[184997258] 'agreement among raft nodes before linearized reading' (duration: 115.89097ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:34.582985Z","caller":"traceutil/trace.go:171","msg":"trace[533041060] transaction","detail":"{read_only:false; response_revision:26634; number_of_response:1; }","duration":"162.098356ms","start":"2026-09-25T04:15:34.420858Z","end":"2026-09-25T04:15:34.582957Z","steps":["trace[533041060] 'process raft request' (duration: 161.895352ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.835775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.082429ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:34.835985Z","caller":"traceutil/trace.go:171","msg":"trace[1496939211] transaction","detail":"{read_only:false; response_revision:26635; number_of_response:1; }","duration":"414.832335ms","start":"2026-09-25T04:15:34.421132Z","end":"2026-09-25T04:15:34.835964Z","steps":["trace[1496939211] 'process raft request' (duration: 267.46685ms)","trace[1496939211] 'compare' (duration: 146.855574ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:34.836055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:34.421117Z","time spent":"414.905966ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:34.83628Z","caller":"traceutil/trace.go:171","msg":"trace[1788601716] transaction","detail":"{read_only:false; response_revision:26636; number_of_response:1; }","duration":"413.665499ms","start":"2026-09-25T04:15:34.422598Z","end":"2026-09-25T04:15:34.836264Z","steps":["trace[1788601716] 'process raft request' (duration: 413.302251ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:34.836457Z","caller":"traceutil/trace.go:171","msg":"trace[1347937527] linearizableReadLoop","detail":"{readStateIndex:27521; appliedIndex:27518; }","duration":"104.864592ms","start":"2026-09-25T04:15:34.731575Z","end":"2026-09-25T04:15:34.83644Z","steps":["trace[1347937527] 'read index received' (duration: 43.191µs)","trace[1347937527] 'applied index is now lower than readState.Index' (duration: 104.7707ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:34.836442Z","caller":"traceutil/trace.go:171","msg":"trace[908729408] transaction","detail":"{read_only:false; response_revision:26637; number_of_response:1; }","duration":"222.836608ms","start":"2026-09-25T04:15:34.613574Z","end":"2026-09-25T04:15:34.836411Z","steps":["trace[908729408] 'process raft request' (duration: 222.617703ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:34.836568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:34.422572Z","time spent":"413.865463ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:34.836813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.238832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-09-25T04:15:34.836851Z","caller":"traceutil/trace.go:171","msg":"trace[231016814] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:26637; }","duration":"105.318782ms","start":"2026-09-25T04:15:34.731521Z","end":"2026-09-25T04:15:34.83684Z","steps":["trace[231016814] 'agreement among raft nodes before linearized reading' (duration: 105.170149ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:36.061323Z","caller":"traceutil/trace.go:171","msg":"trace[1526374366] transaction","detail":"{read_only:false; response_revision:26639; number_of_response:1; }","duration":"222.8579ms","start":"2026-09-25T04:15:35.838437Z","end":"2026-09-25T04:15:36.061295Z","steps":["trace[1526374366] 'process raft request' (duration: 222.686436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.293322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.133076ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:36.293518Z","caller":"traceutil/trace.go:171","msg":"trace[1281741203] linearizableReadLoop","detail":"{readStateIndex:27526; appliedIndex:27522; }","duration":"404.167117ms","start":"2026-09-25T04:15:35.889335Z","end":"2026-09-25T04:15:36.293502Z","steps":["trace[1281741203] 'read index received' (duration: 171.853236ms)","trace[1281741203] 'applied index is now lower than readState.Index' (duration: 232.312991ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:36.293597Z","caller":"traceutil/trace.go:171","msg":"trace[670244462] transaction","detail":"{read_only:false; response_revision:26641; number_of_response:1; }","duration":"451.856017ms","start":"2026-09-25T04:15:35.841731Z","end":"2026-09-25T04:15:36.293587Z","steps":["trace[670244462] 'process raft request' (duration: 451.677073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.293655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:35.841714Z","time spent":"451.910038ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:36.293876Z","caller":"traceutil/trace.go:171","msg":"trace[52034585] transaction","detail":"{read_only:false; response_revision:26640; number_of_response:1; }","duration":"454.896834ms","start":"2026-09-25T04:15:35.838942Z","end":"2026-09-25T04:15:36.293839Z","steps":["trace[52034585] 'process raft request' (duration: 329.166054ms)","trace[52034585] 'compare' (duration: 124.999923ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:36.294069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:35.83892Z","time spent":"455.040788ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:36.294065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.2596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:36.294256Z","caller":"traceutil/trace.go:171","msg":"trace[981276729] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/servers/; range_end:/registry/openstack.k-orc.cloud/servers0; response_count:0; response_revision:26642; }","duration":"314.452403ms","start":"2026-09-25T04:15:35.979704Z","end":"2026-09-25T04:15:36.294156Z","steps":["trace[981276729] 'agreement among raft nodes before linearized reading' (duration: 314.185987ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.294684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.331933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:15:36.294806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.616857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:36.294857Z","caller":"traceutil/trace.go:171","msg":"trace[1528290566] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26642; }","duration":"211.690829ms","start":"2026-09-25T04:15:36.08315Z","end":"2026-09-25T04:15:36.294841Z","steps":["trace[1528290566] 'agreement among raft nodes before linearized reading' (duration: 211.630887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.294897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.514107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:15:36.294854Z","caller":"traceutil/trace.go:171","msg":"trace[1529881833] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:26642; }","duration":"405.535117ms","start":"2026-09-25T04:15:35.889293Z","end":"2026-09-25T04:15:36.294828Z","steps":["trace[1529881833] 'agreement among raft nodes before linearized reading' (duration: 405.325193ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:36.294959Z","caller":"traceutil/trace.go:171","msg":"trace[2105419915] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:26642; }","duration":"245.63074ms","start":"2026-09-25T04:15:36.049315Z","end":"2026-09-25T04:15:36.294946Z","steps":["trace[2105419915] 'agreement among raft nodes before linearized reading' (duration: 245.516747ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.294981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:35.889274Z","time spent":"405.687981ms","remote":"127.0.0.1:56240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:15:36.294726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.411234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:15:36.295048Z","caller":"traceutil/trace.go:171","msg":"trace[984674686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26642; }","duration":"138.792933ms","start":"2026-09-25T04:15:36.156246Z","end":"2026-09-25T04:15:36.295039Z","steps":["trace[984674686] 'agreement among raft nodes before linearized reading' (duration: 138.332073ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:36.294912Z","caller":"traceutil/trace.go:171","msg":"trace[674446196] transaction","detail":"{read_only:false; response_revision:26642; number_of_response:1; }","duration":"452.04585ms","start":"2026-09-25T04:15:35.842847Z","end":"2026-09-25T04:15:36.294893Z","steps":["trace[674446196] 'process raft request' (duration: 450.611848ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:36.295125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:35.979682Z","time spent":"314.610117ms","remote":"127.0.0.1:49414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/servers/\" range_end:\"/registry/openstack.k-orc.cloud/servers0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:15:36.295198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:35.84283Z","time spent":"452.304196ms","remote":"127.0.0.1:59938","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-09-25T04:15:46.594955Z","caller":"traceutil/trace.go:171","msg":"trace[1308911558] transaction","detail":"{read_only:false; response_revision:26715; number_of_response:1; }","duration":"248.122057ms","start":"2026-09-25T04:15:46.346808Z","end":"2026-09-25T04:15:46.59493Z","steps":["trace[1308911558] 'process raft request' (duration: 247.941543ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:46.660741Z","caller":"traceutil/trace.go:171","msg":"trace[2101955255] linearizableReadLoop","detail":"{readStateIndex:27602; appliedIndex:27600; }","duration":"302.842503ms","start":"2026-09-25T04:15:46.35787Z","end":"2026-09-25T04:15:46.660713Z","steps":["trace[2101955255] 'read index received' (duration: 236.848894ms)","trace[2101955255] 'applied index is now lower than readState.Index' (duration: 65.992439ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:46.661182Z","caller":"traceutil/trace.go:171","msg":"trace[2096362015] transaction","detail":"{read_only:false; response_revision:26716; number_of_response:1; }","duration":"308.05562ms","start":"2026-09-25T04:15:46.353098Z","end":"2026-09-25T04:15:46.661154Z","steps":["trace[2096362015] 'process raft request' (duration: 307.459666ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.661338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.353069Z","time spent":"308.194614ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:46.6614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.515009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:15:46.661481Z","caller":"traceutil/trace.go:171","msg":"trace[858992907] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26716; }","duration":"303.648662ms","start":"2026-09-25T04:15:46.357813Z","end":"2026-09-25T04:15:46.661462Z","steps":["trace[858992907] 'agreement among raft nodes before linearized reading' (duration: 303.378626ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.661538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.357794Z","time spent":"303.728203ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-25T04:15:46.87284Z","caller":"traceutil/trace.go:171","msg":"trace[685794850] linearizableReadLoop","detail":"{readStateIndex:27603; appliedIndex:27602; }","duration":"212.005316ms","start":"2026-09-25T04:15:46.660814Z","end":"2026-09-25T04:15:46.87282Z","steps":["trace[685794850] 'read index received' (duration: 318.148µs)","trace[685794850] 'applied index is now lower than readState.Index' (duration: 211.686388ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:46.872963Z","caller":"traceutil/trace.go:171","msg":"trace[1192744953] transaction","detail":"{read_only:false; response_revision:26717; number_of_response:1; }","duration":"240.682599ms","start":"2026-09-25T04:15:46.63227Z","end":"2026-09-25T04:15:46.872952Z","steps":["trace[1192744953] 'process raft request' (duration: 238.924879ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:46.873081Z","caller":"traceutil/trace.go:171","msg":"trace[846404608] transaction","detail":"{read_only:false; response_revision:26718; number_of_response:1; }","duration":"208.22363ms","start":"2026-09-25T04:15:46.664837Z","end":"2026-09-25T04:15:46.87306Z","steps":["trace[846404608] 'process raft request' (duration: 207.909293ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.873193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.222422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:15:46.873245Z","caller":"traceutil/trace.go:171","msg":"trace[2057208935] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:26718; }","duration":"443.317504ms","start":"2026-09-25T04:15:46.429918Z","end":"2026-09-25T04:15:46.873235Z","steps":["trace[2057208935] 'agreement among raft nodes before linearized reading' (duration: 443.198271ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.873277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.429909Z","time spent":"443.357756ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:15:46.873426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.108381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:15:46.873451Z","caller":"traceutil/trace.go:171","msg":"trace[1213646145] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:26718; }","duration":"168.163682ms","start":"2026-09-25T04:15:46.705282Z","end":"2026-09-25T04:15:46.873445Z","steps":["trace[1213646145] 'agreement among raft nodes before linearized reading' (duration: 168.113761ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.873484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.856874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:15:46.873525Z","caller":"traceutil/trace.go:171","msg":"trace[1508063343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26718; }","duration":"500.957867ms","start":"2026-09-25T04:15:46.372558Z","end":"2026-09-25T04:15:46.873516Z","steps":["trace[1508063343] 'agreement among raft nodes before linearized reading' (duration: 500.811044ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.87356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.372546Z","time spent":"501.006998ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-25T04:15:46.873531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.568528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-25T04:15:46.873607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.248774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-25T04:15:46.873585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.94519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:15:46.873665Z","caller":"traceutil/trace.go:171","msg":"trace[1586238801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26718; }","duration":"501.334976ms","start":"2026-09-25T04:15:46.372315Z","end":"2026-09-25T04:15:46.87365Z","steps":["trace[1586238801] 'agreement among raft nodes before linearized reading' (duration: 501.166822ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.873702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.3723Z","time spent":"501.394197ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-09-25T04:15:46.873705Z","caller":"traceutil/trace.go:171","msg":"trace[584078901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26718; }","duration":"515.116925ms","start":"2026-09-25T04:15:46.35856Z","end":"2026-09-25T04:15:46.873677Z","steps":["trace[584078901] 'agreement among raft nodes before linearized reading' (duration: 514.767287ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.873764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.358539Z","time spent":"515.211437ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-25T04:15:46.873622Z","caller":"traceutil/trace.go:171","msg":"trace[164546098] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26718; }","duration":"500.69221ms","start":"2026-09-25T04:15:46.372921Z","end":"2026-09-25T04:15:46.873613Z","steps":["trace[164546098] 'agreement among raft nodes before linearized reading' (duration: 500.477515ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:46.87388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.372912Z","time spent":"500.922275ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-25T04:15:47.239046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.432313ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:15:47.239497Z","caller":"traceutil/trace.go:171","msg":"trace[887378610] transaction","detail":"{read_only:false; response_revision:26720; number_of_response:1; }","duration":"360.114569ms","start":"2026-09-25T04:15:46.879341Z","end":"2026-09-25T04:15:47.239455Z","steps":["trace[887378610] 'process raft request' (duration: 152.194515ms)","trace[887378610] 'compare' (duration: 206.562263ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:47.239667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.879306Z","time spent":"360.274632ms","remote":"127.0.0.1:59938","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-09-25T04:15:47.240181Z","caller":"traceutil/trace.go:171","msg":"trace[2125808040] linearizableReadLoop","detail":"{readStateIndex:27611; appliedIndex:27610; }","duration":"198.427681ms","start":"2026-09-25T04:15:47.041742Z","end":"2026-09-25T04:15:47.24017Z","steps":["trace[2125808040] 'read index received' (duration: 198.315779ms)","trace[2125808040] 'applied index is now lower than readState.Index' (duration: 111.602µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:47.240272Z","caller":"traceutil/trace.go:171","msg":"trace[1460809984] transaction","detail":"{read_only:false; response_revision:26723; number_of_response:1; }","duration":"354.679886ms","start":"2026-09-25T04:15:46.885574Z","end":"2026-09-25T04:15:47.240253Z","steps":["trace[1460809984] 'process raft request' (duration: 353.714165ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:47.240281Z","caller":"traceutil/trace.go:171","msg":"trace[887213216] transaction","detail":"{read_only:false; response_revision:26721; number_of_response:1; }","duration":"359.414942ms","start":"2026-09-25T04:15:46.880849Z","end":"2026-09-25T04:15:47.240264Z","steps":["trace[887213216] 'process raft request' (duration: 358.286607ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:47.240282Z","caller":"traceutil/trace.go:171","msg":"trace[1211973926] transaction","detail":"{read_only:false; response_revision:26725; number_of_response:1; }","duration":"352.040347ms","start":"2026-09-25T04:15:46.888203Z","end":"2026-09-25T04:15:47.240244Z","steps":["trace[1211973926] 'process raft request' (duration: 351.892533ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.240328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.580894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:47.240352Z","caller":"traceutil/trace.go:171","msg":"trace[145152918] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:26725; }","duration":"198.649486ms","start":"2026-09-25T04:15:47.041694Z","end":"2026-09-25T04:15:47.240344Z","steps":["trace[145152918] 'agreement among raft nodes before linearized reading' (duration: 198.536323ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.240353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.880834Z","time spent":"359.485043ms","remote":"127.0.0.1:59938","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-09-25T04:15:47.240304Z","caller":"traceutil/trace.go:171","msg":"trace[709278086] transaction","detail":"{read_only:false; response_revision:26722; number_of_response:1; }","duration":"359.056264ms","start":"2026-09-25T04:15:46.881198Z","end":"2026-09-25T04:15:47.240254Z","steps":["trace[709278086] 'process raft request' (duration: 357.98861ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.24039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.885558Z","time spent":"354.759417ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:47.240429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.888162Z","time spent":"352.18696ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:47.240489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.881177Z","time spent":"359.223088ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:47.239385Z","caller":"traceutil/trace.go:171","msg":"trace[1959462745] transaction","detail":"{read_only:false; response_revision:26724; number_of_response:1; }","duration":"353.740354ms","start":"2026-09-25T04:15:46.885627Z","end":"2026-09-25T04:15:47.239368Z","steps":["trace[1959462745] 'process raft request' (duration: 353.694553ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.240711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:46.885612Z","time spent":"355.047824ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:47.296708Z","caller":"traceutil/trace.go:171","msg":"trace[293552952] transaction","detail":"{read_only:false; response_revision:26726; number_of_response:1; }","duration":"252.120615ms","start":"2026-09-25T04:15:47.044563Z","end":"2026-09-25T04:15:47.296684Z","steps":["trace[293552952] 'process raft request' (duration: 252.006033ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.297055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.68312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:47.297136Z","caller":"traceutil/trace.go:171","msg":"trace[1785525151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26726; }","duration":"212.814024ms","start":"2026-09-25T04:15:47.0843Z","end":"2026-09-25T04:15:47.297114Z","steps":["trace[1785525151] 'agreement among raft nodes before linearized reading' (duration: 212.653919ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:47.57259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.60094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:47.572675Z","caller":"traceutil/trace.go:171","msg":"trace[492518826] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:26726; }","duration":"229.735433ms","start":"2026-09-25T04:15:47.342922Z","end":"2026-09-25T04:15:47.572658Z","steps":["trace[492518826] 'count revisions from in-memory index tree' (duration: 229.496508ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:48.598429Z","caller":"traceutil/trace.go:171","msg":"trace[450093210] linearizableReadLoop","detail":"{readStateIndex:27613; appliedIndex:27612; }","duration":"356.047457ms","start":"2026-09-25T04:15:48.242359Z","end":"2026-09-25T04:15:48.598407Z","steps":["trace[450093210] 'read index received' (duration: 355.960005ms)","trace[450093210] 'applied index is now lower than readState.Index' (duration: 86.512µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:48.598689Z","caller":"traceutil/trace.go:171","msg":"trace[745479362] transaction","detail":"{read_only:false; response_revision:26727; number_of_response:1; }","duration":"496.307084ms","start":"2026-09-25T04:15:48.102369Z","end":"2026-09-25T04:15:48.598676Z","steps":["trace[745479362] 'process raft request' (duration: 495.903885ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:48.598738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.355553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:15:48.598792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.102349Z","time spent":"496.378075ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:48.598817Z","caller":"traceutil/trace.go:171","msg":"trace[1428822121] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:26727; }","duration":"356.453515ms","start":"2026-09-25T04:15:48.242343Z","end":"2026-09-25T04:15:48.598796Z","steps":["trace[1428822121] 'agreement among raft nodes before linearized reading' (duration: 356.305322ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:48.598863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.242284Z","time spent":"356.567378ms","remote":"127.0.0.1:37718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:15:49.203265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.707617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-25T04:15:49.203469Z","caller":"traceutil/trace.go:171","msg":"trace[579962574] linearizableReadLoop","detail":"{readStateIndex:27614; appliedIndex:27613; }","duration":"604.962651ms","start":"2026-09-25T04:15:48.598489Z","end":"2026-09-25T04:15:49.203452Z","steps":["trace[579962574] 'read index received' (duration: 57.024339ms)","trace[579962574] 'applied index is now lower than readState.Index' (duration: 547.937372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:49.203587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.841101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:49.203622Z","caller":"traceutil/trace.go:171","msg":"trace[1473211009] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:26727; }","duration":"955.903112ms","start":"2026-09-25T04:15:48.247707Z","end":"2026-09-25T04:15:49.20361Z","steps":["trace[1473211009] 'agreement among raft nodes before linearized reading' (duration: 955.836381ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.203662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.2477Z","time spent":"955.952293ms","remote":"127.0.0.1:49438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:15:49.204197Z","caller":"traceutil/trace.go:171","msg":"trace[360623987] transaction","detail":"{read_only:false; response_revision:26728; number_of_response:1; }","duration":"603.18617ms","start":"2026-09-25T04:15:48.600974Z","end":"2026-09-25T04:15:49.20416Z","steps":["trace[360623987] 'process raft request' (duration: 602.388503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.204391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.600956Z","time spent":"603.343853ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:49.237149Z","caller":"traceutil/trace.go:171","msg":"trace[1528524914] transaction","detail":"{read_only:false; response_revision:26729; number_of_response:1; }","duration":"567.623002ms","start":"2026-09-25T04:15:48.669487Z","end":"2026-09-25T04:15:49.23711Z","steps":["trace[1528524914] 'process raft request' (duration: 566.122979ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.237253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.34469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:15:49.237281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.06963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:49.237314Z","caller":"traceutil/trace.go:171","msg":"trace[116638350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26732; }","duration":"153.446113ms","start":"2026-09-25T04:15:49.083852Z","end":"2026-09-25T04:15:49.237298Z","steps":["trace[116638350] 'agreement among raft nodes before linearized reading' (duration: 153.31846ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:49.237357Z","caller":"traceutil/trace.go:171","msg":"trace[1922213352] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:0; response_revision:26732; }","duration":"596.181553ms","start":"2026-09-25T04:15:48.641154Z","end":"2026-09-25T04:15:49.237336Z","steps":["trace[1922213352] 'agreement among raft nodes before linearized reading' (duration: 596.01222ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.237357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.669463Z","time spent":"567.796937ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:49.237418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.641141Z","time spent":"596.265545ms","remote":"127.0.0.1:56256","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-09-25T04:15:49.237439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.535545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:15:49.237489Z","caller":"traceutil/trace.go:171","msg":"trace[1816197583] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26732; }","duration":"347.590287ms","start":"2026-09-25T04:15:48.889885Z","end":"2026-09-25T04:15:49.237475Z","steps":["trace[1816197583] 'agreement among raft nodes before linearized reading' (duration: 347.349911ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:49.23752Z","caller":"traceutil/trace.go:171","msg":"trace[155054432] transaction","detail":"{read_only:false; response_revision:26732; number_of_response:1; }","duration":"342.032712ms","start":"2026-09-25T04:15:48.895476Z","end":"2026-09-25T04:15:49.237508Z","steps":["trace[155054432] 'process raft request' (duration: 341.642254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.237532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.889856Z","time spent":"347.665589ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-25T04:15:49.237613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.895464Z","time spent":"342.080753ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":628,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:49.237626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.247651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:49.237665Z","caller":"traceutil/trace.go:171","msg":"trace[803809517] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:26732; }","duration":"101.316182ms","start":"2026-09-25T04:15:49.136337Z","end":"2026-09-25T04:15:49.237653Z","steps":["trace[803809517] 'agreement among raft nodes before linearized reading' (duration: 101.250081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.237635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.524321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:15:49.237647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.436093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:15:49.237759Z","caller":"traceutil/trace.go:171","msg":"trace[51971539] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:26732; }","duration":"526.709144ms","start":"2026-09-25T04:15:48.711021Z","end":"2026-09-25T04:15:49.23773Z","steps":["trace[51971539] 'agreement among raft nodes before linearized reading' (duration: 526.461999ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:49.237654Z","caller":"traceutil/trace.go:171","msg":"trace[1782603838] transaction","detail":"{read_only:false; response_revision:26730; number_of_response:1; }","duration":"470.448863ms","start":"2026-09-25T04:15:48.76718Z","end":"2026-09-25T04:15:49.237629Z","steps":["trace[1782603838] 'process raft request' (duration: 469.816739ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.237822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.710999Z","time spent":"526.809947ms","remote":"127.0.0.1:50382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"info","ts":"2026-09-25T04:15:49.237619Z","caller":"traceutil/trace.go:171","msg":"trace[245293650] transaction","detail":"{read_only:false; response_revision:26731; number_of_response:1; }","duration":"359.506394ms","start":"2026-09-25T04:15:48.878061Z","end":"2026-09-25T04:15:49.237568Z","steps":["trace[245293650] 'process raft request' (duration: 359.020493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.23798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.767148Z","time spent":"470.702939ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:15:49.237782Z","caller":"traceutil/trace.go:171","msg":"trace[1958031727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26732; }","duration":"359.654198ms","start":"2026-09-25T04:15:48.878096Z","end":"2026-09-25T04:15:49.23775Z","steps":["trace[1958031727] 'agreement among raft nodes before linearized reading' (duration: 359.207398ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:49.23807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.878076Z","time spent":"359.976914ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-25T04:15:49.238011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:15:48.878045Z","time spent":"359.906053ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:15:49.23983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.597741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance\" ","response":"range_response_count:1 size:419124"} {"level":"info","ts":"2026-09-25T04:15:49.239927Z","caller":"traceutil/trace.go:171","msg":"trace[1726022746] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeatures/monitoring/instance; range_end:; response_count:1; response_revision:26732; }","duration":"299.730713ms","start":"2026-09-25T04:15:48.940171Z","end":"2026-09-25T04:15:49.239901Z","steps":["trace[1726022746] 'agreement among raft nodes before linearized reading' (duration: 297.269408ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:50.691588Z","caller":"traceutil/trace.go:171","msg":"trace[1359531169] linearizableReadLoop","detail":"{readStateIndex:27691; appliedIndex:27690; }","duration":"118.14596ms","start":"2026-09-25T04:15:50.573415Z","end":"2026-09-25T04:15:50.691561Z","steps":["trace[1359531169] 'read index received' (duration: 117.980897ms)","trace[1359531169] 'applied index is now lower than readState.Index' (duration: 163.903µs)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:50.69174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.292123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:50.691709Z","caller":"traceutil/trace.go:171","msg":"trace[674934570] transaction","detail":"{read_only:false; response_revision:26804; number_of_response:1; }","duration":"128.134064ms","start":"2026-09-25T04:15:50.563529Z","end":"2026-09-25T04:15:50.691663Z","steps":["trace[674934570] 'process raft request' (duration: 127.823717ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:50.691784Z","caller":"traceutil/trace.go:171","msg":"trace[404581519] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:26804; }","duration":"118.375576ms","start":"2026-09-25T04:15:50.57339Z","end":"2026-09-25T04:15:50.691766Z","steps":["trace[404581519] 'agreement among raft nodes before linearized reading' (duration: 118.281354ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:15:50.783539Z","caller":"traceutil/trace.go:171","msg":"trace[164820537] transaction","detail":"{read_only:false; response_revision:26805; number_of_response:1; }","duration":"178.424143ms","start":"2026-09-25T04:15:50.605069Z","end":"2026-09-25T04:15:50.783493Z","steps":["trace[164820537] 'process raft request' (duration: 87.237008ms)","trace[164820537] 'compare' (duration: 90.9768ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:15:52.889795Z","caller":"traceutil/trace.go:171","msg":"trace[703938098] transaction","detail":"{read_only:false; response_revision:26819; number_of_response:1; }","duration":"101.171779ms","start":"2026-09-25T04:15:52.788604Z","end":"2026-09-25T04:15:52.889775Z","steps":["trace[703938098] 'process raft request' (duration: 101.023066ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:53.02104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.573366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.220\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-09-25T04:15:53.021117Z","caller":"traceutil/trace.go:171","msg":"trace[1496298219] range","detail":"{range_begin:/registry/masterleases/162.253.55.220; range_end:; response_count:1; response_revision:26819; }","duration":"108.689268ms","start":"2026-09-25T04:15:52.912409Z","end":"2026-09-25T04:15:53.021099Z","steps":["trace[1496298219] 'range keys from in-memory index tree' (duration: 108.409862ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:15:58.4342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.130585ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-25T04:15:58.434331Z","caller":"traceutil/trace.go:171","msg":"trace[1477251942] linearizableReadLoop","detail":"{readStateIndex:27775; appliedIndex:27774; }","duration":"215.881693ms","start":"2026-09-25T04:15:58.218419Z","end":"2026-09-25T04:15:58.4343Z","steps":["trace[1477251942] 'read index received' (duration: 87.580734ms)","trace[1477251942] 'applied index is now lower than readState.Index' (duration: 128.299679ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:15:58.434402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.980735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:15:58.434528Z","caller":"traceutil/trace.go:171","msg":"trace[1244385686] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26886; }","duration":"216.143248ms","start":"2026-09-25T04:15:58.218371Z","end":"2026-09-25T04:15:58.434515Z","steps":["trace[1244385686] 'agreement among raft nodes before linearized reading' (duration: 215.987525ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:07.090965Z","caller":"traceutil/trace.go:171","msg":"trace[704199819] transaction","detail":"{read_only:false; response_revision:26953; number_of_response:1; }","duration":"141.224538ms","start":"2026-09-25T04:16:06.949718Z","end":"2026-09-25T04:16:07.090942Z","steps":["trace[704199819] 'process raft request' (duration: 140.714417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:07.329459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.152165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/addressscopes/\" range_end:\"/registry/openstack.k-orc.cloud/addressscopes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:07.329885Z","caller":"traceutil/trace.go:171","msg":"trace[1674155647] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/addressscopes/; range_end:/registry/openstack.k-orc.cloud/addressscopes0; response_count:0; response_revision:26953; }","duration":"145.630276ms","start":"2026-09-25T04:16:07.184204Z","end":"2026-09-25T04:16:07.329834Z","steps":["trace[1674155647] 'count revisions from in-memory index tree' (duration: 145.087174ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.392056Z","caller":"traceutil/trace.go:171","msg":"trace[2128863617] linearizableReadLoop","detail":"{readStateIndex:27860; appliedIndex:27859; }","duration":"103.42194ms","start":"2026-09-25T04:16:09.28861Z","end":"2026-09-25T04:16:09.392032Z","steps":["trace[2128863617] 'read index received' (duration: 103.382039ms)","trace[2128863617] 'applied index is now lower than readState.Index' (duration: 38.741µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:09.392171Z","caller":"traceutil/trace.go:171","msg":"trace[1792019533] transaction","detail":"{read_only:false; response_revision:26968; number_of_response:1; }","duration":"120.334679ms","start":"2026-09-25T04:16:09.271794Z","end":"2026-09-25T04:16:09.392128Z","steps":["trace[1792019533] 'process raft request' (duration: 120.100624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.392346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.729957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-25T04:16:09.392451Z","caller":"traceutil/trace.go:171","msg":"trace[712383057] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26968; }","duration":"103.87606ms","start":"2026-09-25T04:16:09.288549Z","end":"2026-09-25T04:16:09.392425Z","steps":["trace[712383057] 'agreement among raft nodes before linearized reading' (duration: 103.612805ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.643787Z","caller":"traceutil/trace.go:171","msg":"trace[1289366709] transaction","detail":"{read_only:false; response_revision:26969; number_of_response:1; }","duration":"308.93304ms","start":"2026-09-25T04:16:09.334828Z","end":"2026-09-25T04:16:09.643761Z","steps":["trace[1289366709] 'process raft request' (duration: 308.713665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.644057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.334806Z","time spent":"309.134454ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:09.644068Z","caller":"traceutil/trace.go:171","msg":"trace[1184736585] transaction","detail":"{read_only:false; response_revision:26970; number_of_response:1; }","duration":"304.786747ms","start":"2026-09-25T04:16:09.339262Z","end":"2026-09-25T04:16:09.644049Z","steps":["trace[1184736585] 'process raft request' (duration: 304.449959ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.644362Z","caller":"traceutil/trace.go:171","msg":"trace[1675120858] transaction","detail":"{read_only:false; response_revision:26971; number_of_response:1; }","duration":"267.45775ms","start":"2026-09-25T04:16:09.376889Z","end":"2026-09-25T04:16:09.644347Z","steps":["trace[1675120858] 'process raft request' (duration: 267.074082ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.644392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.339186Z","time spent":"305.105774ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:09.644434Z","caller":"traceutil/trace.go:171","msg":"trace[464186621] transaction","detail":"{read_only:false; response_revision:26972; number_of_response:1; }","duration":"267.332678ms","start":"2026-09-25T04:16:09.377067Z","end":"2026-09-25T04:16:09.6444Z","steps":["trace[464186621] 'process raft request' (duration: 267.182944ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.644681Z","caller":"traceutil/trace.go:171","msg":"trace[1001269719] transaction","detail":"{read_only:false; response_revision:26973; number_of_response:1; }","duration":"266.893836ms","start":"2026-09-25T04:16:09.377767Z","end":"2026-09-25T04:16:09.64466Z","steps":["trace[1001269719] 'process raft request' (duration: 266.583069ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.644798Z","caller":"traceutil/trace.go:171","msg":"trace[1136782558] linearizableReadLoop","detail":"{readStateIndex:27867; appliedIndex:27860; }","duration":"252.628717ms","start":"2026-09-25T04:16:09.392128Z","end":"2026-09-25T04:16:09.644757Z","steps":["trace[1136782558] 'read index received' (duration: 251.418369ms)","trace[1136782558] 'applied index is now lower than readState.Index' (duration: 1.209417ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:09.644969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.133196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:16:09.64503Z","caller":"traceutil/trace.go:171","msg":"trace[235829760] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26975; }","duration":"260.250838ms","start":"2026-09-25T04:16:09.384763Z","end":"2026-09-25T04:16:09.645014Z","steps":["trace[235829760] 'agreement among raft nodes before linearized reading' (duration: 260.034413ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.644678Z","caller":"traceutil/trace.go:171","msg":"trace[1518398880] transaction","detail":"{read_only:false; response_revision:26974; number_of_response:1; }","duration":"266.095468ms","start":"2026-09-25T04:16:09.378567Z","end":"2026-09-25T04:16:09.644663Z","steps":["trace[1518398880] 'process raft request' (duration: 266.013317ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.250058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:16:09.645582Z","caller":"traceutil/trace.go:171","msg":"trace[931307617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26975; }","duration":"260.81793ms","start":"2026-09-25T04:16:09.384744Z","end":"2026-09-25T04:16:09.645562Z","steps":["trace[931307617] 'agreement among raft nodes before linearized reading' (duration: 260.153746ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.933953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:16:09.646185Z","caller":"traceutil/trace.go:171","msg":"trace[1013502096] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26975; }","duration":"228.924015ms","start":"2026-09-25T04:16:09.417241Z","end":"2026-09-25T04:16:09.646165Z","steps":["trace[1013502096] 'agreement among raft nodes before linearized reading' (duration: 227.861991ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.228902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-09-25T04:16:09.64649Z","caller":"traceutil/trace.go:171","msg":"trace[1593613323] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26975; }","duration":"345.551112ms","start":"2026-09-25T04:16:09.300894Z","end":"2026-09-25T04:16:09.646445Z","steps":["trace[1593613323] 'agreement among raft nodes before linearized reading' (duration: 344.181711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.646579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:09.300875Z","time spent":"345.670945ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"info","ts":"2026-09-25T04:16:09.644999Z","caller":"traceutil/trace.go:171","msg":"trace[271331316] transaction","detail":"{read_only:false; response_revision:26975; number_of_response:1; }","duration":"265.839123ms","start":"2026-09-25T04:16:09.379143Z","end":"2026-09-25T04:16:09.644982Z","steps":["trace[271331316] 'process raft request' (duration: 265.488156ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.378083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinesets/\" range_end:\"/registry/cluster.x-k8s.io/machinesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:09.646783Z","caller":"traceutil/trace.go:171","msg":"trace[1995876140] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinesets/; range_end:/registry/cluster.x-k8s.io/machinesets0; response_count:0; response_revision:26975; }","duration":"192.932768ms","start":"2026-09-25T04:16:09.453823Z","end":"2026-09-25T04:16:09.646756Z","steps":["trace[1995876140] 'agreement among raft nodes before linearized reading' (duration: 191.426503ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.567345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:16:09.647315Z","caller":"traceutil/trace.go:171","msg":"trace[152988824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26975; }","duration":"262.613591ms","start":"2026-09-25T04:16:09.38468Z","end":"2026-09-25T04:16:09.647294Z","steps":["trace[152988824] 'agreement among raft nodes before linearized reading' (duration: 260.479832ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:09.645334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.766996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:16:09.647685Z","caller":"traceutil/trace.go:171","msg":"trace[1517238300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26975; }","duration":"266.13941ms","start":"2026-09-25T04:16:09.381532Z","end":"2026-09-25T04:16:09.647672Z","steps":["trace[1517238300] 'agreement among raft nodes before linearized reading' (duration: 263.721726ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.799655Z","caller":"traceutil/trace.go:171","msg":"trace[1581456044] transaction","detail":"{read_only:false; response_revision:26977; number_of_response:1; }","duration":"149.354699ms","start":"2026-09-25T04:16:09.650278Z","end":"2026-09-25T04:16:09.799633Z","steps":["trace[1581456044] 'process raft request' (duration: 149.158045ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.799892Z","caller":"traceutil/trace.go:171","msg":"trace[770413373] transaction","detail":"{read_only:false; response_revision:26979; number_of_response:1; }","duration":"148.201785ms","start":"2026-09-25T04:16:09.651675Z","end":"2026-09-25T04:16:09.799877Z","steps":["trace[770413373] 'process raft request' (duration: 148.094972ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.799922Z","caller":"traceutil/trace.go:171","msg":"trace[1187865739] transaction","detail":"{read_only:false; response_revision:26978; number_of_response:1; }","duration":"149.614285ms","start":"2026-09-25T04:16:09.650277Z","end":"2026-09-25T04:16:09.799892Z","steps":["trace[1187865739] 'process raft request' (duration: 149.285828ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.800145Z","caller":"traceutil/trace.go:171","msg":"trace[1739017452] transaction","detail":"{read_only:false; response_revision:26981; number_of_response:1; }","duration":"147.697002ms","start":"2026-09-25T04:16:09.65242Z","end":"2026-09-25T04:16:09.800117Z","steps":["trace[1739017452] 'process raft request' (duration: 147.59859ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:09.80023Z","caller":"traceutil/trace.go:171","msg":"trace[341211430] transaction","detail":"{read_only:false; response_revision:26980; number_of_response:1; }","duration":"147.833565ms","start":"2026-09-25T04:16:09.652352Z","end":"2026-09-25T04:16:09.800186Z","steps":["trace[341211430] 'process raft request' (duration: 147.482038ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:11.934339Z","caller":"traceutil/trace.go:171","msg":"trace[1837410105] transaction","detail":"{read_only:false; response_revision:26984; number_of_response:1; }","duration":"283.818825ms","start":"2026-09-25T04:16:11.650488Z","end":"2026-09-25T04:16:11.934307Z","steps":["trace[1837410105] 'process raft request' (duration: 283.525269ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.163956Z","caller":"traceutil/trace.go:171","msg":"trace[1879244957] transaction","detail":"{read_only:false; response_revision:26986; number_of_response:1; }","duration":"512.989127ms","start":"2026-09-25T04:16:11.650943Z","end":"2026-09-25T04:16:12.163933Z","steps":["trace[1879244957] 'process raft request' (duration: 512.901355ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.16398Z","caller":"traceutil/trace.go:171","msg":"trace[1360106111] transaction","detail":"{read_only:false; response_revision:26985; number_of_response:1; }","duration":"513.013328ms","start":"2026-09-25T04:16:11.650917Z","end":"2026-09-25T04:16:12.16393Z","steps":["trace[1360106111] 'process raft request' (duration: 512.795683ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.164114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.650923Z","time spent":"513.10382ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.164249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.650907Z","time spent":"513.192601ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:12.16448Z","caller":"traceutil/trace.go:171","msg":"trace[1880755808] transaction","detail":"{read_only:false; response_revision:26987; number_of_response:1; }","duration":"513.044778ms","start":"2026-09-25T04:16:11.651419Z","end":"2026-09-25T04:16:12.164464Z","steps":["trace[1880755808] 'process raft request' (duration: 512.476636ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.164548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.651391Z","time spent":"513.122599ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:12.164625Z","caller":"traceutil/trace.go:171","msg":"trace[778495055] transaction","detail":"{read_only:false; response_revision:26989; number_of_response:1; }","duration":"512.463236ms","start":"2026-09-25T04:16:11.652153Z","end":"2026-09-25T04:16:12.164616Z","steps":["trace[778495055] 'process raft request' (duration: 512.342733ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.16463Z","caller":"traceutil/trace.go:171","msg":"trace[1343346818] transaction","detail":"{read_only:false; response_revision:26988; number_of_response:1; }","duration":"513.017758ms","start":"2026-09-25T04:16:11.651599Z","end":"2026-09-25T04:16:12.164616Z","steps":["trace[1343346818] 'process raft request' (duration: 512.754762ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.164642Z","caller":"traceutil/trace.go:171","msg":"trace[1799633484] linearizableReadLoop","detail":"{readStateIndex:27882; appliedIndex:27876; }","duration":"486.526753ms","start":"2026-09-25T04:16:11.678093Z","end":"2026-09-25T04:16:12.16462Z","steps":["trace[1799633484] 'read index received' (duration: 256.197146ms)","trace[1799633484] 'applied index is now lower than readState.Index' (duration: 230.328787ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:12.164726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.651558Z","time spent":"513.10225ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.164674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.65214Z","time spent":"512.508286ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.164902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.80295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-25T04:16:12.164924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.630469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-09-25T04:16:12.164952Z","caller":"traceutil/trace.go:171","msg":"trace[1831372560] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26990; }","duration":"486.884902ms","start":"2026-09-25T04:16:11.678053Z","end":"2026-09-25T04:16:12.164938Z","steps":["trace[1831372560] 'agreement among raft nodes before linearized reading' (duration: 486.684346ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.164984Z","caller":"traceutil/trace.go:171","msg":"trace[1374668323] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26990; }","duration":"450.766251ms","start":"2026-09-25T04:16:11.714201Z","end":"2026-09-25T04:16:12.164967Z","steps":["trace[1374668323] 'agreement among raft nodes before linearized reading' (duration: 450.571286ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:12.165026Z","caller":"traceutil/trace.go:171","msg":"trace[2102811657] transaction","detail":"{read_only:false; response_revision:26990; number_of_response:1; }","duration":"512.67054ms","start":"2026-09-25T04:16:11.652343Z","end":"2026-09-25T04:16:12.165013Z","steps":["trace[2102811657] 'process raft request' (duration: 512.238189ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.165087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.65233Z","time spent":"512.726971ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:12.165116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.06845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:16:12.165142Z","caller":"traceutil/trace.go:171","msg":"trace[1341592826] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26990; }","duration":"361.214792ms","start":"2026-09-25T04:16:11.80392Z","end":"2026-09-25T04:16:12.165135Z","steps":["trace[1341592826] 'agreement among raft nodes before linearized reading' (duration: 361.12818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.165027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.714182Z","time spent":"450.835483ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":555,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-09-25T04:16:12.165181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.543758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:16:12.165253Z","caller":"traceutil/trace.go:171","msg":"trace[1734561161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26990; }","duration":"360.623399ms","start":"2026-09-25T04:16:11.804616Z","end":"2026-09-25T04:16:12.165239Z","steps":["trace[1734561161] 'agreement among raft nodes before linearized reading' (duration: 360.495247ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.165294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.248644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-09-25T04:16:12.165302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.804609Z","time spent":"360.683421ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-25T04:16:12.165322Z","caller":"traceutil/trace.go:171","msg":"trace[260394705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26990; }","duration":"361.320265ms","start":"2026-09-25T04:16:11.803994Z","end":"2026-09-25T04:16:12.165315Z","steps":["trace[260394705] 'agreement among raft nodes before linearized reading' (duration: 361.210873ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.165353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.803971Z","time spent":"361.377376ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-25T04:16:12.165081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.63787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-25T04:16:12.165177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.912606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddresses/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddresses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:12.165421Z","caller":"traceutil/trace.go:171","msg":"trace[444398635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26990; }","duration":"360.976937ms","start":"2026-09-25T04:16:11.804431Z","end":"2026-09-25T04:16:12.165408Z","steps":["trace[444398635] 'agreement among raft nodes before linearized reading' (duration: 360.519527ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.165458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.804402Z","time spent":"361.048499ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-25T04:16:12.165191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.803894Z","time spent":"361.271234ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-25T04:16:12.165648Z","caller":"traceutil/trace.go:171","msg":"trace[49329745] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddresses/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddresses0; response_count:0; response_revision:26990; }","duration":"291.671233ms","start":"2026-09-25T04:16:11.873952Z","end":"2026-09-25T04:16:12.165623Z","steps":["trace[49329745] 'agreement among raft nodes before linearized reading' (duration: 290.912816ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:12.164989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:11.678039Z","time spent":"486.940163ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-25T04:16:14.572908Z","caller":"traceutil/trace.go:171","msg":"trace[539269996] linearizableReadLoop","detail":"{readStateIndex:27908; appliedIndex:27907; }","duration":"169.094312ms","start":"2026-09-25T04:16:14.40379Z","end":"2026-09-25T04:16:14.572884Z","steps":["trace[539269996] 'read index received' (duration: 168.877028ms)","trace[539269996] 'applied index is now lower than readState.Index' (duration: 216.394µs)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:14.572949Z","caller":"traceutil/trace.go:171","msg":"trace[1636307354] transaction","detail":"{read_only:false; response_revision:27015; number_of_response:1; }","duration":"175.521657ms","start":"2026-09-25T04:16:14.397409Z","end":"2026-09-25T04:16:14.57293Z","steps":["trace[1636307354] 'process raft request' (duration: 175.317183ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:14.573233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.4134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-09-25T04:16:14.573268Z","caller":"traceutil/trace.go:171","msg":"trace[617537031] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:27015; }","duration":"169.514712ms","start":"2026-09-25T04:16:14.403742Z","end":"2026-09-25T04:16:14.573257Z","steps":["trace[617537031] 'agreement among raft nodes before linearized reading' (duration: 169.327727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:14.999149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.435514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:14.999272Z","caller":"traceutil/trace.go:171","msg":"trace[1236846559] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:27015; }","duration":"339.572267ms","start":"2026-09-25T04:16:14.659637Z","end":"2026-09-25T04:16:14.99921Z","steps":["trace[1236846559] 'count revisions from in-memory index tree' (duration: 339.345432ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:14.99932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:14.659619Z","time spent":"339.689369ms","remote":"127.0.0.1:37680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:14.9994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.35942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:14.999473Z","caller":"traceutil/trace.go:171","msg":"trace[2053371246] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:27015; }","duration":"186.484313ms","start":"2026-09-25T04:16:14.81297Z","end":"2026-09-25T04:16:14.999454Z","steps":["trace[2053371246] 'count revisions from in-memory index tree' (duration: 186.261417ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:14.999571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.813213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedrainrules/\" range_end:\"/registry/cluster.x-k8s.io/machinedrainrules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:14.999643Z","caller":"traceutil/trace.go:171","msg":"trace[856487399] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedrainrules/; range_end:/registry/cluster.x-k8s.io/machinedrainrules0; response_count:0; response_revision:27015; }","duration":"125.958435ms","start":"2026-09-25T04:16:14.873665Z","end":"2026-09-25T04:16:14.999623Z","steps":["trace[856487399] 'count revisions from in-memory index tree' (duration: 125.693368ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:16.325123Z","caller":"traceutil/trace.go:171","msg":"trace[485730594] transaction","detail":"{read_only:false; response_revision:27030; number_of_response:1; }","duration":"122.657452ms","start":"2026-09-25T04:16:16.202438Z","end":"2026-09-25T04:16:16.325095Z","steps":["trace[485730594] 'process raft request' (duration: 69.673393ms)","trace[485730594] 'compare' (duration: 52.872367ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:16.443976Z","caller":"traceutil/trace.go:171","msg":"trace[1944587822] transaction","detail":"{read_only:false; response_revision:27031; number_of_response:1; }","duration":"240.561287ms","start":"2026-09-25T04:16:16.203396Z","end":"2026-09-25T04:16:16.443957Z","steps":["trace[1944587822] 'process raft request' (duration: 230.319857ms)","trace[1944587822] 'compare' (duration: 10.121037ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:16.444239Z","caller":"traceutil/trace.go:171","msg":"trace[1345686102] linearizableReadLoop","detail":"{readStateIndex:27927; appliedIndex:27922; }","duration":"238.009569ms","start":"2026-09-25T04:16:16.20619Z","end":"2026-09-25T04:16:16.4442Z","steps":["trace[1345686102] 'read index received' (duration: 65.932629ms)","trace[1345686102] 'applied index is now lower than readState.Index' (duration: 172.07619ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:16.444278Z","caller":"traceutil/trace.go:171","msg":"trace[1333238064] transaction","detail":"{read_only:false; response_revision:27032; number_of_response:1; }","duration":"240.728891ms","start":"2026-09-25T04:16:16.20354Z","end":"2026-09-25T04:16:16.444269Z","steps":["trace[1333238064] 'process raft request' (duration: 240.379433ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:16.44437Z","caller":"traceutil/trace.go:171","msg":"trace[1705569107] transaction","detail":"{read_only:false; response_revision:27034; number_of_response:1; }","duration":"239.89526ms","start":"2026-09-25T04:16:16.204459Z","end":"2026-09-25T04:16:16.444354Z","steps":["trace[1705569107] 'process raft request' (duration: 239.708436ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:16.444421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.217173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-09-25T04:16:16.444395Z","caller":"traceutil/trace.go:171","msg":"trace[288601055] transaction","detail":"{read_only:false; response_revision:27033; number_of_response:1; }","duration":"240.416794ms","start":"2026-09-25T04:16:16.203945Z","end":"2026-09-25T04:16:16.444362Z","steps":["trace[288601055] 'process raft request' (duration: 240.147648ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:16.444448Z","caller":"traceutil/trace.go:171","msg":"trace[1368521030] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27034; }","duration":"238.285655ms","start":"2026-09-25T04:16:16.206156Z","end":"2026-09-25T04:16:16.444442Z","steps":["trace[1368521030] 'agreement among raft nodes before linearized reading' (duration: 238.186673ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:16.444677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.939859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-25T04:16:16.444677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.839269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-09-25T04:16:16.4447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.036924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-09-25T04:16:16.444713Z","caller":"traceutil/trace.go:171","msg":"trace[1814496265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27034; }","duration":"177.88839ms","start":"2026-09-25T04:16:16.266818Z","end":"2026-09-25T04:16:16.444707Z","steps":["trace[1814496265] 'agreement among raft nodes before linearized reading' (duration: 177.763617ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:16.444713Z","caller":"traceutil/trace.go:171","msg":"trace[787419389] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27034; }","duration":"173.02538ms","start":"2026-09-25T04:16:16.271677Z","end":"2026-09-25T04:16:16.444703Z","steps":["trace[787419389] 'agreement among raft nodes before linearized reading' (duration: 172.912298ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:16.444737Z","caller":"traceutil/trace.go:171","msg":"trace[451657481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27034; }","duration":"174.106286ms","start":"2026-09-25T04:16:16.27062Z","end":"2026-09-25T04:16:16.444726Z","steps":["trace[451657481] 'agreement among raft nodes before linearized reading' (duration: 173.983233ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:16.445131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.036521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-09-25T04:16:16.445319Z","caller":"traceutil/trace.go:171","msg":"trace[1815427164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27034; }","duration":"173.232185ms","start":"2026-09-25T04:16:16.272067Z","end":"2026-09-25T04:16:16.445299Z","steps":["trace[1815427164] 'agreement among raft nodes before linearized reading' (duration: 172.53828ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:18.441161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.892527ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-25T04:16:18.441832Z","caller":"traceutil/trace.go:171","msg":"trace[1995785656] transaction","detail":"{read_only:false; response_revision:27045; number_of_response:1; }","duration":"111.376199ms","start":"2026-09-25T04:16:18.330436Z","end":"2026-09-25T04:16:18.441812Z","steps":["trace[1995785656] 'process raft request' (duration: 110.842957ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:22.620959Z","caller":"traceutil/trace.go:171","msg":"trace[1281030209] transaction","detail":"{read_only:false; response_revision:27074; number_of_response:1; }","duration":"125.874543ms","start":"2026-09-25T04:16:22.495054Z","end":"2026-09-25T04:16:22.620929Z","steps":["trace[1281030209] 'process raft request' (duration: 59.251819ms)","trace[1281030209] 'compare' (duration: 66.499312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:22.620907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.574656ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:22.621067Z","caller":"traceutil/trace.go:171","msg":"trace[1737998289] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:27073; }","duration":"104.74753ms","start":"2026-09-25T04:16:22.516291Z","end":"2026-09-25T04:16:22.621039Z","steps":["trace[1737998289] 'range keys from in-memory index tree' (duration: 104.555365ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:22.688973Z","caller":"traceutil/trace.go:171","msg":"trace[1045692652] transaction","detail":"{read_only:false; response_revision:27075; number_of_response:1; }","duration":"159.351265ms","start":"2026-09-25T04:16:22.529603Z","end":"2026-09-25T04:16:22.688955Z","steps":["trace[1045692652] 'process raft request' (duration: 159.200202ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:22.689314Z","caller":"traceutil/trace.go:171","msg":"trace[1934540072] transaction","detail":"{read_only:false; response_revision:27076; number_of_response:1; }","duration":"158.603197ms","start":"2026-09-25T04:16:22.530688Z","end":"2026-09-25T04:16:22.689291Z","steps":["trace[1934540072] 'process raft request' (duration: 158.225929ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:22.689363Z","caller":"traceutil/trace.go:171","msg":"trace[335712034] linearizableReadLoop","detail":"{readStateIndex:27971; appliedIndex:27967; }","duration":"144.424889ms","start":"2026-09-25T04:16:22.544911Z","end":"2026-09-25T04:16:22.689336Z","steps":["trace[335712034] 'read index received' (duration: 9.412791ms)","trace[335712034] 'applied index is now lower than readState.Index' (duration: 135.010448ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:22.689498Z","caller":"traceutil/trace.go:171","msg":"trace[1357796125] transaction","detail":"{read_only:false; response_revision:27077; number_of_response:1; }","duration":"158.310811ms","start":"2026-09-25T04:16:22.53117Z","end":"2026-09-25T04:16:22.689481Z","steps":["trace[1357796125] 'process raft request' (duration: 157.996174ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:22.689569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.644854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:16:22.689609Z","caller":"traceutil/trace.go:171","msg":"trace[1995863216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27077; }","duration":"144.734817ms","start":"2026-09-25T04:16:22.544864Z","end":"2026-09-25T04:16:22.689599Z","steps":["trace[1995863216] 'agreement among raft nodes before linearized reading' (duration: 144.605853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:22.689692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.333703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:16:22.689717Z","caller":"traceutil/trace.go:171","msg":"trace[559083380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27077; }","duration":"138.387385ms","start":"2026-09-25T04:16:22.551323Z","end":"2026-09-25T04:16:22.689711Z","steps":["trace[559083380] 'agreement among raft nodes before linearized reading' (duration: 138.295203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:22.689781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.897261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:16:22.689856Z","caller":"traceutil/trace.go:171","msg":"trace[1373378487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27077; }","duration":"137.006864ms","start":"2026-09-25T04:16:22.552832Z","end":"2026-09-25T04:16:22.689839Z","steps":["trace[1373378487] 'agreement among raft nodes before linearized reading' (duration: 136.792299ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:22.689847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.982293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:16:22.689907Z","caller":"traceutil/trace.go:171","msg":"trace[1064419001] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27077; }","duration":"141.066405ms","start":"2026-09-25T04:16:22.548825Z","end":"2026-09-25T04:16:22.689891Z","steps":["trace[1064419001] 'agreement among raft nodes before linearized reading' (duration: 140.8674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:22.690193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.046582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:16:22.690272Z","caller":"traceutil/trace.go:171","msg":"trace[1423230292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27077; }","duration":"144.165315ms","start":"2026-09-25T04:16:22.54609Z","end":"2026-09-25T04:16:22.690255Z","steps":["trace[1423230292] 'agreement among raft nodes before linearized reading' (duration: 143.474789ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:24.380364Z","caller":"traceutil/trace.go:171","msg":"trace[927232969] transaction","detail":"{read_only:false; response_revision:27087; number_of_response:1; }","duration":"115.124492ms","start":"2026-09-25T04:16:24.26518Z","end":"2026-09-25T04:16:24.380304Z","steps":["trace[927232969] 'process raft request' (duration: 114.549449ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.072904Z","caller":"traceutil/trace.go:171","msg":"trace[2057773453] transaction","detail":"{read_only:false; response_revision:27103; number_of_response:1; }","duration":"687.354298ms","start":"2026-09-25T04:16:26.385527Z","end":"2026-09-25T04:16:27.072881Z","steps":["trace[2057773453] 'process raft request' (duration: 687.198154ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.073114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.385505Z","time spent":"687.537932ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.109274Z","caller":"traceutil/trace.go:171","msg":"trace[1898327343] linearizableReadLoop","detail":"{readStateIndex:28003; appliedIndex:27997; }","duration":"349.862178ms","start":"2026-09-25T04:16:26.759393Z","end":"2026-09-25T04:16:27.109255Z","steps":["trace[1898327343] 'read index received' (duration: 313.323578ms)","trace[1898327343] 'applied index is now lower than readState.Index' (duration: 36.53747ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:27.109641Z","caller":"traceutil/trace.go:171","msg":"trace[1439548103] transaction","detail":"{read_only:false; response_revision:27104; number_of_response:1; }","duration":"602.608668ms","start":"2026-09-25T04:16:26.507014Z","end":"2026-09-25T04:16:27.109623Z","steps":["trace[1439548103] 'process raft request' (duration: 601.945473ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.109902Z","caller":"traceutil/trace.go:171","msg":"trace[1880103849] transaction","detail":"{read_only:false; response_revision:27108; number_of_response:1; }","duration":"400.849481ms","start":"2026-09-25T04:16:26.709032Z","end":"2026-09-25T04:16:27.109882Z","steps":["trace[1880103849] 'process raft request' (duration: 400.160796ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.110009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.709025Z","time spent":"400.923563ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.11012Z","caller":"traceutil/trace.go:171","msg":"trace[1378324139] transaction","detail":"{read_only:false; response_revision:27105; number_of_response:1; }","duration":"478.151746ms","start":"2026-09-25T04:16:26.631962Z","end":"2026-09-25T04:16:27.110114Z","steps":["trace[1378324139] 'process raft request' (duration: 477.117193ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.11015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.631938Z","time spent":"478.195917ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.11024Z","caller":"traceutil/trace.go:171","msg":"trace[193251717] transaction","detail":"{read_only:false; response_revision:27106; number_of_response:1; }","duration":"408.457663ms","start":"2026-09-25T04:16:26.701775Z","end":"2026-09-25T04:16:27.110233Z","steps":["trace[193251717] 'process raft request' (duration: 407.342978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.110271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.70175Z","time spent":"408.508234ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-25T04:16:27.110332Z","caller":"traceutil/trace.go:171","msg":"trace[895544140] transaction","detail":"{read_only:false; response_revision:27107; number_of_response:1; }","duration":"405.058686ms","start":"2026-09-25T04:16:26.705269Z","end":"2026-09-25T04:16:27.110327Z","steps":["trace[895544140] 'process raft request' (duration: 403.884969ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.11036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.705249Z","time spent":"405.096288ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:27.109909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.506989Z","time spent":"602.843652ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-25T04:16:27.110757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.333842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:16:27.110778Z","caller":"traceutil/trace.go:171","msg":"trace[1416227169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27108; }","duration":"351.387363ms","start":"2026-09-25T04:16:26.759386Z","end":"2026-09-25T04:16:27.110773Z","steps":["trace[1416227169] 'agreement among raft nodes before linearized reading' (duration: 351.288541ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.110791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.759372Z","time spent":"351.415794ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-25T04:16:27.110942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.440253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterctl.cluster.x-k8s.io/providers/\" range_end:\"/registry/clusterctl.cluster.x-k8s.io/providers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-25T04:16:27.110959Z","caller":"traceutil/trace.go:171","msg":"trace[907966387] range","detail":"{range_begin:/registry/clusterctl.cluster.x-k8s.io/providers/; range_end:/registry/clusterctl.cluster.x-k8s.io/providers0; response_count:0; response_revision:27108; }","duration":"149.462463ms","start":"2026-09-25T04:16:26.961492Z","end":"2026-09-25T04:16:27.110955Z","steps":["trace[907966387] 'agreement among raft nodes before linearized reading' (duration: 149.430623ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.111059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.075843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-25T04:16:27.111074Z","caller":"traceutil/trace.go:171","msg":"trace[674521485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:27108; }","duration":"346.120555ms","start":"2026-09-25T04:16:26.76495Z","end":"2026-09-25T04:16:27.111071Z","steps":["trace[674521485] 'agreement among raft nodes before linearized reading' (duration: 346.071784ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.111085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.764931Z","time spent":"346.151085ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-25T04:16:27.111161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.072675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:16:27.111177Z","caller":"traceutil/trace.go:171","msg":"trace[1285039563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27108; }","duration":"351.105336ms","start":"2026-09-25T04:16:26.760067Z","end":"2026-09-25T04:16:27.111172Z","steps":["trace[1285039563] 'agreement among raft nodes before linearized reading' (duration: 351.065905ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.111189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.760057Z","time spent":"351.127306ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-25T04:16:27.111302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.817252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-25T04:16:27.111319Z","caller":"traceutil/trace.go:171","msg":"trace[1154798724] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27108; }","duration":"351.886464ms","start":"2026-09-25T04:16:26.759428Z","end":"2026-09-25T04:16:27.111315Z","steps":["trace[1154798724] 'agreement among raft nodes before linearized reading' (duration: 351.844643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.111329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.759405Z","time spent":"351.921604ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-09-25T04:16:27.111166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.759732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:16:27.111526Z","caller":"traceutil/trace.go:171","msg":"trace[1118658687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27108; }","duration":"352.145789ms","start":"2026-09-25T04:16:26.759362Z","end":"2026-09-25T04:16:27.111508Z","steps":["trace[1118658687] 'agreement among raft nodes before linearized reading' (duration: 350.575084ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:27.111651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:26.75935Z","time spent":"352.282412ms","remote":"127.0.0.1:59938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-25T04:16:27.504487Z","caller":"traceutil/trace.go:171","msg":"trace[1599737502] transaction","detail":"{read_only:false; response_revision:27114; number_of_response:1; }","duration":"265.385093ms","start":"2026-09-25T04:16:27.23908Z","end":"2026-09-25T04:16:27.504465Z","steps":["trace[1599737502] 'process raft request' (duration: 265.27108ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.505424Z","caller":"traceutil/trace.go:171","msg":"trace[208773459] linearizableReadLoop","detail":"{readStateIndex:28010; appliedIndex:28008; }","duration":"173.052912ms","start":"2026-09-25T04:16:27.332349Z","end":"2026-09-25T04:16:27.505402Z","steps":["trace[208773459] 'read index received' (duration: 171.948417ms)","trace[208773459] 'applied index is now lower than readState.Index' (duration: 1.103255ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:27.505669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.313828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-09-25T04:16:27.505711Z","caller":"traceutil/trace.go:171","msg":"trace[816608980] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:27115; }","duration":"173.41071ms","start":"2026-09-25T04:16:27.332289Z","end":"2026-09-25T04:16:27.5057Z","steps":["trace[816608980] 'agreement among raft nodes before linearized reading' (duration: 173.211115ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:27.505691Z","caller":"traceutil/trace.go:171","msg":"trace[323120040] transaction","detail":"{read_only:false; response_revision:27115; number_of_response:1; }","duration":"248.193018ms","start":"2026-09-25T04:16:27.257452Z","end":"2026-09-25T04:16:27.505645Z","steps":["trace[323120040] 'process raft request' (duration: 247.822159ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:28.229679Z","caller":"traceutil/trace.go:171","msg":"trace[1754797697] transaction","detail":"{read_only:false; response_revision:27116; number_of_response:1; }","duration":"142.087278ms","start":"2026-09-25T04:16:28.087568Z","end":"2026-09-25T04:16:28.229655Z","steps":["trace[1754797697] 'process raft request' (duration: 141.918144ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.04509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.649129ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-25T04:16:29.045236Z","caller":"traceutil/trace.go:171","msg":"trace[203255506] linearizableReadLoop","detail":"{readStateIndex:28012; appliedIndex:28011; }","duration":"496.985658ms","start":"2026-09-25T04:16:28.548212Z","end":"2026-09-25T04:16:29.045198Z","steps":["trace[203255506] 'read index received' (duration: 74.702µs)","trace[203255506] 'applied index is now lower than readState.Index' (duration: 496.909376ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:29.045347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.122291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:29.045424Z","caller":"traceutil/trace.go:171","msg":"trace[1880597427] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:27116; }","duration":"497.242083ms","start":"2026-09-25T04:16:28.548156Z","end":"2026-09-25T04:16:29.045398Z","steps":["trace[1880597427] 'agreement among raft nodes before linearized reading' (duration: 497.120621ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.045456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-25T04:16:28.548125Z","time spent":"497.323945ms","remote":"127.0.0.1:59992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-09-25T04:16:29.279825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.634288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:29.279939Z","caller":"traceutil/trace.go:171","msg":"trace[2068216049] linearizableReadLoop","detail":"{readStateIndex:28019; appliedIndex:28018; }","duration":"148.750006ms","start":"2026-09-25T04:16:29.131172Z","end":"2026-09-25T04:16:29.279922Z","steps":["trace[2068216049] 'read index received' (duration: 26.867303ms)","trace[2068216049] 'applied index is now lower than readState.Index' (duration: 121.881363ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:29.279989Z","caller":"traceutil/trace.go:171","msg":"trace[28443843] transaction","detail":"{read_only:false; response_revision:27123; number_of_response:1; }","duration":"149.547334ms","start":"2026-09-25T04:16:29.130408Z","end":"2026-09-25T04:16:29.279955Z","steps":["trace[28443843] 'process raft request' (duration: 27.705801ms)","trace[28443843] 'compare' (duration: 121.502996ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:29.280128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.130391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-25T04:16:29.280139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.952241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-09-25T04:16:29.280206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.858399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-09-25T04:16:29.280256Z","caller":"traceutil/trace.go:171","msg":"trace[1447414161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27123; }","duration":"149.091735ms","start":"2026-09-25T04:16:29.131113Z","end":"2026-09-25T04:16:29.280205Z","steps":["trace[1447414161] 'agreement among raft nodes before linearized reading' (duration: 148.864299ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.280169Z","caller":"traceutil/trace.go:171","msg":"trace[735321343] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27123; }","duration":"114.194721ms","start":"2026-09-25T04:16:29.165963Z","end":"2026-09-25T04:16:29.280157Z","steps":["trace[735321343] 'agreement among raft nodes before linearized reading' (duration: 114.11021ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.280297Z","caller":"traceutil/trace.go:171","msg":"trace[1951433833] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27123; }","duration":"148.978872ms","start":"2026-09-25T04:16:29.131298Z","end":"2026-09-25T04:16:29.280277Z","steps":["trace[1951433833] 'agreement among raft nodes before linearized reading' (duration: 148.730047ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.280323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.331715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:16:29.280422Z","caller":"traceutil/trace.go:171","msg":"trace[1808964726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27123; }","duration":"147.403996ms","start":"2026-09-25T04:16:29.13296Z","end":"2026-09-25T04:16:29.280364Z","steps":["trace[1808964726] 'agreement among raft nodes before linearized reading' (duration: 147.162081ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.2805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.931456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-09-25T04:16:29.280583Z","caller":"traceutil/trace.go:171","msg":"trace[379354438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:27123; }","duration":"147.042119ms","start":"2026-09-25T04:16:29.133529Z","end":"2026-09-25T04:16:29.280571Z","steps":["trace[379354438] 'agreement among raft nodes before linearized reading' (duration: 146.855224ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.498011Z","caller":"traceutil/trace.go:171","msg":"trace[751175642] linearizableReadLoop","detail":"{readStateIndex:28023; appliedIndex:28020; }","duration":"148.067522ms","start":"2026-09-25T04:16:29.349915Z","end":"2026-09-25T04:16:29.497983Z","steps":["trace[751175642] 'read index received' (duration: 96.830493ms)","trace[751175642] 'applied index is now lower than readState.Index' (duration: 51.236059ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:29.498054Z","caller":"traceutil/trace.go:171","msg":"trace[2132627038] transaction","detail":"{read_only:false; response_revision:27125; number_of_response:1; }","duration":"212.807384ms","start":"2026-09-25T04:16:29.285214Z","end":"2026-09-25T04:16:29.498022Z","steps":["trace[2132627038] 'process raft request' (duration: 161.519754ms)","trace[2132627038] 'compare' (duration: 50.960243ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:29.498154Z","caller":"traceutil/trace.go:171","msg":"trace[1212443900] transaction","detail":"{read_only:false; response_revision:27126; number_of_response:1; }","duration":"212.861725ms","start":"2026-09-25T04:16:29.285261Z","end":"2026-09-25T04:16:29.498123Z","steps":["trace[1212443900] 'process raft request' (duration: 212.594029ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:29.498346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.414789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-09-25T04:16:29.498365Z","caller":"traceutil/trace.go:171","msg":"trace[1042609884] transaction","detail":"{read_only:false; response_revision:27127; number_of_response:1; }","duration":"212.917416ms","start":"2026-09-25T04:16:29.285422Z","end":"2026-09-25T04:16:29.498339Z","steps":["trace[1042609884] 'process raft request' (duration: 212.505607ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.498409Z","caller":"traceutil/trace.go:171","msg":"trace[1903053410] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:27127; }","duration":"148.539662ms","start":"2026-09-25T04:16:29.349853Z","end":"2026-09-25T04:16:29.498393Z","steps":["trace[1903053410] 'agreement among raft nodes before linearized reading' (duration: 148.237375ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:29.729853Z","caller":"traceutil/trace.go:171","msg":"trace[1169718690] transaction","detail":"{read_only:false; response_revision:27129; number_of_response:1; }","duration":"139.378796ms","start":"2026-09-25T04:16:29.590437Z","end":"2026-09-25T04:16:29.729816Z","steps":["trace[1169718690] 'process raft request' (duration: 132.198535ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:30.804829Z","caller":"traceutil/trace.go:171","msg":"trace[176165034] transaction","detail":"{read_only:false; response_revision:27131; number_of_response:1; }","duration":"120.015511ms","start":"2026-09-25T04:16:30.684779Z","end":"2026-09-25T04:16:30.804794Z","steps":["trace[176165034] 'process raft request' (duration: 119.798036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:38.529917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.541458ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-25T04:16:38.530342Z","caller":"traceutil/trace.go:171","msg":"trace[56334176] transaction","detail":"{read_only:false; response_revision:27188; number_of_response:1; }","duration":"224.67624ms","start":"2026-09-25T04:16:38.305631Z","end":"2026-09-25T04:16:38.530308Z","steps":["trace[56334176] 'process raft request' (duration: 224.386593ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:41.156788Z","caller":"traceutil/trace.go:171","msg":"trace[1674847553] transaction","detail":"{read_only:false; response_revision:27206; number_of_response:1; }","duration":"101.621051ms","start":"2026-09-25T04:16:41.05514Z","end":"2026-09-25T04:16:41.156761Z","steps":["trace[1674847553] 'process raft request' (duration: 101.433587ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:49.92636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.802865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-25T04:16:49.926559Z","caller":"traceutil/trace.go:171","msg":"trace[424297907] transaction","detail":"{read_only:false; response_revision:27276; number_of_response:1; }","duration":"167.509568ms","start":"2026-09-25T04:16:49.759031Z","end":"2026-09-25T04:16:49.926541Z","steps":["trace[424297907] 'process raft request' (duration: 167.470227ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:49.926696Z","caller":"traceutil/trace.go:171","msg":"trace[389426760] transaction","detail":"{read_only:false; response_revision:27275; number_of_response:1; }","duration":"200.958997ms","start":"2026-09-25T04:16:49.725705Z","end":"2026-09-25T04:16:49.926664Z","steps":["trace[389426760] 'process raft request' (duration: 200.740802ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:49.926709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.244153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-09-25T04:16:49.926733Z","caller":"traceutil/trace.go:171","msg":"trace[1811549424] linearizableReadLoop","detail":"{readStateIndex:28174; appliedIndex:28172; }","duration":"258.09702ms","start":"2026-09-25T04:16:49.668444Z","end":"2026-09-25T04:16:49.926541Z","steps":["trace[1811549424] 'read index received' (duration: 27.916207ms)","trace[1811549424] 'applied index is now lower than readState.Index' (duration: 230.178753ms)"],"step_count":2} {"level":"info","ts":"2026-09-25T04:16:49.926784Z","caller":"traceutil/trace.go:171","msg":"trace[1790010622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:27276; }","duration":"258.355046ms","start":"2026-09-25T04:16:49.668412Z","end":"2026-09-25T04:16:49.926767Z","steps":["trace[1790010622] 'agreement among raft nodes before linearized reading' (duration: 258.133841ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:16:49.926777Z","caller":"traceutil/trace.go:171","msg":"trace[407724103] transaction","detail":"{read_only:false; response_revision:27274; number_of_response:1; }","duration":"260.082154ms","start":"2026-09-25T04:16:49.66666Z","end":"2026-09-25T04:16:49.926743Z","steps":["trace[407724103] 'process raft request' (duration: 107.800138ms)","trace[407724103] 'compare' (duration: 151.623751ms)"],"step_count":2} {"level":"warn","ts":"2026-09-25T04:16:49.92698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.76812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19234"} {"level":"info","ts":"2026-09-25T04:16:49.927026Z","caller":"traceutil/trace.go:171","msg":"trace[831203874] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:27276; }","duration":"137.847842ms","start":"2026-09-25T04:16:49.789164Z","end":"2026-09-25T04:16:49.927012Z","steps":["trace[831203874] 'agreement among raft nodes before linearized reading' (duration: 137.690978ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:50.187965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.284727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:50.188043Z","caller":"traceutil/trace.go:171","msg":"trace[2030193519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:27277; }","duration":"103.42158ms","start":"2026-09-25T04:16:50.084602Z","end":"2026-09-25T04:16:50.188024Z","steps":["trace[2030193519] 'range keys from in-memory index tree' (duration: 103.189065ms)"],"step_count":1} {"level":"warn","ts":"2026-09-25T04:16:50.188147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.092816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-25T04:16:50.188255Z","caller":"traceutil/trace.go:171","msg":"trace[1372144234] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:27277; }","duration":"129.232549ms","start":"2026-09-25T04:16:50.058982Z","end":"2026-09-25T04:16:50.188215Z","steps":["trace[1372144234] 'range keys from in-memory index tree' (duration: 128.994983ms)"],"step_count":1} {"level":"info","ts":"2026-09-25T04:17:09.518746Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24615} {"level":"info","ts":"2026-09-25T04:17:09.671581Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24615,"took":"147.606091ms","hash":3582868231,"current-db-size-bytes":59301888,"current-db-size":"59 MB","current-db-size-in-use-bytes":36913152,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-09-25T04:17:09.671649Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3582868231,"revision":24615,"compact-revision":21730} {"level":"info","ts":"2026-09-25T04:19:53.981536Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:19:53.989307Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-09-25T04:19:53.989389Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-09-25T04:22:09.525403Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27431} {"level":"info","ts":"2026-09-25T04:22:09.656622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27431,"took":"126.968369ms","hash":1485758006,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":37928960,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-09-25T04:22:09.656695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1485758006,"revision":27431,"compact-revision":24615} {"level":"info","ts":"2026-09-25T04:27:09.534356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30302} {"level":"info","ts":"2026-09-25T04:27:09.695897Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30302,"took":"157.264131ms","hash":3087764340,"current-db-size-bytes":60989440,"current-db-size":"61 MB","current-db-size-in-use-bytes":31318016,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-25T04:27:09.695973Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3087764340,"revision":30302,"compact-revision":27431} {"level":"info","ts":"2026-09-25T04:32:09.540913Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32554} {"level":"info","ts":"2026-09-25T04:32:09.704796Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32554,"took":"159.265847ms","hash":205817010,"current-db-size-bytes":62029824,"current-db-size":"62 MB","current-db-size-in-use-bytes":30380032,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:32:09.704866Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":205817010,"revision":32554,"compact-revision":30302} {"level":"info","ts":"2026-09-25T04:37:09.548911Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34777} {"level":"info","ts":"2026-09-25T04:37:09.68691Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34777,"took":"130.617704ms","hash":3949242980,"current-db-size-bytes":62029824,"current-db-size":"62 MB","current-db-size-in-use-bytes":30441472,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-09-25T04:37:09.686984Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3949242980,"revision":34777,"compact-revision":32554} {"level":"info","ts":"2026-09-25T04:40:53.730037Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"4c62f064ba268721","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-25T04:40:53.736719Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-09-25T04:40:53.736811Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-09-25T04:42:09.555001Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37004} {"level":"info","ts":"2026-09-25T04:42:09.686645Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37004,"took":"126.099275ms","hash":3079023282,"current-db-size-bytes":62029824,"current-db-size":"62 MB","current-db-size-in-use-bytes":32235520,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-09-25T04:42:09.686815Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3079023282,"revision":37004,"compact-revision":34777} {"level":"info","ts":"2026-09-25T04:47:09.563495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39346} {"level":"info","ts":"2026-09-25T04:47:09.695446Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39346,"took":"127.902585ms","hash":1465797357,"current-db-size-bytes":64147456,"current-db-size":"64 MB","current-db-size-in-use-bytes":31260672,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-25T04:47:09.695521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1465797357,"revision":39346,"compact-revision":37004} {"level":"info","ts":"2026-09-25T04:52:09.572524Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41573} {"level":"info","ts":"2026-09-25T04:52:09.698368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41573,"took":"121.583174ms","hash":400358142,"current-db-size-bytes":64147456,"current-db-size":"64 MB","current-db-size-in-use-bytes":30932992,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-25T04:52:09.698451Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":400358142,"revision":41573,"compact-revision":39346}