{"level":"warn","ts":"2026-04-09T22:29:34.176913Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:29:34.17703Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.156:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.156:2380","--initial-cluster=instance=https://199.204.45.156:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.156:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.156: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-04-09T22:29:34.177131Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-09T22:29:34.177146Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-09T22:29:34.177179Z","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-04-09T22:29:34.178238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"]} {"level":"info","ts":"2026-04-09T22:29:34.17837Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.156: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-04-09T22:29:34.18341Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.238076ms"} {"level":"info","ts":"2026-04-09T22:29:34.198744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5ac240a6daec2148","cluster-id":"62163637a46c33c2"} {"level":"info","ts":"2026-04-09T22:29:34.198848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=()"} {"level":"info","ts":"2026-04-09T22:29:34.198873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 0"} {"level":"info","ts":"2026-04-09T22:29:34.198886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5ac240a6daec2148 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-09T22:29:34.198902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became follower at term 1"} {"level":"info","ts":"2026-04-09T22:29:34.198949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"warn","ts":"2026-04-09T22:29:34.206038Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-09T22:29:34.210162Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-09T22:29:34.211881Z","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-04-09T22:29:34.215501Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5ac240a6daec2148","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-09T22:29:34.215766Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5ac240a6daec2148","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-09T22:29:34.215786Z","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-04-09T22:29:34.216073Z","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-04-09T22:29:34.2161Z","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-04-09T22:29:34.216512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 switched to configuration voters=(6539860694277038408)"} {"level":"info","ts":"2026-04-09T22:29:34.216694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","added-peer-id":"5ac240a6daec2148","added-peer-peer-urls":["https://199.204.45.156:2380"]} {"level":"info","ts":"2026-04-09T22:29:34.218593Z","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-04-09T22:29:34.218703Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-09T22:29:34.218736Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.156:2380"} {"level":"info","ts":"2026-04-09T22:29:34.218952Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5ac240a6daec2148","initial-advertise-peer-urls":["https://199.204.45.156:2380"],"listen-peer-urls":["https://199.204.45.156:2380"],"advertise-client-urls":["https://199.204.45.156:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.156:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-09T22:29:34.219018Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-09T22:29:34.500121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 is starting a new election at term 1"} {"level":"info","ts":"2026-04-09T22:29:34.500209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-09T22:29:34.500239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgPreVoteResp from 5ac240a6daec2148 at term 1"} {"level":"info","ts":"2026-04-09T22:29:34.500265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became candidate at term 2"} {"level":"info","ts":"2026-04-09T22:29:34.500414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 received MsgVoteResp from 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-09T22:29:34.500441Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5ac240a6daec2148 became leader at term 2"} {"level":"info","ts":"2026-04-09T22:29:34.500625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5ac240a6daec2148 elected leader 5ac240a6daec2148 at term 2"} {"level":"info","ts":"2026-04-09T22:29:34.503771Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:34.503923Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:34.504263Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-09T22:29:34.504333Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-09T22:29:34.503728Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-09T22:29:34.50515Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62163637a46c33c2","local-member-id":"5ac240a6daec2148","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:34.505334Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5ac240a6daec2148","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.156:2379]}","request-path":"/0/members/5ac240a6daec2148/attributes","cluster-id":"62163637a46c33c2","publish-timeout":"7s"} {"level":"info","ts":"2026-04-09T22:29:34.505508Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:34.505658Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-09T22:29:34.507152Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-09T22:29:34.511384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.156:2379"} {"level":"warn","ts":"2026-04-09T22:29:57.870195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.056512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:29:57.870323Z","caller":"traceutil/trace.go:171","msg":"trace[2059737228] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:416; }","duration":"157.236977ms","start":"2026-04-09T22:29:57.713065Z","end":"2026-04-09T22:29:57.870302Z","steps":["trace[2059737228] 'range keys from in-memory index tree' (duration: 156.941999ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:07.960412Z","caller":"traceutil/trace.go:171","msg":"trace[1447775887] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"101.115465ms","start":"2026-04-09T22:30:07.858233Z","end":"2026-04-09T22:30:07.959349Z","steps":["trace[1447775887] 'process raft request' (duration: 87.119345ms)","trace[1447775887] 'compare' (duration: 13.620249ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:07.960746Z","caller":"traceutil/trace.go:171","msg":"trace[1260339952] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"100.778845ms","start":"2026-04-09T22:30:07.85851Z","end":"2026-04-09T22:30:07.959289Z","steps":["trace[1260339952] 'process raft request' (duration: 100.620091ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:08.051359Z","caller":"traceutil/trace.go:171","msg":"trace[671804312] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:519; }","duration":"103.599811ms","start":"2026-04-09T22:30:07.947738Z","end":"2026-04-09T22:30:08.051338Z","steps":["trace[671804312] 'read index received' (duration: 103.469038ms)","trace[671804312] 'applied index is now lower than readState.Index' (duration: 130.063µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:08.051393Z","caller":"traceutil/trace.go:171","msg":"trace[1617299799] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"166.449318ms","start":"2026-04-09T22:30:07.884884Z","end":"2026-04-09T22:30:08.051333Z","steps":["trace[1617299799] 'process raft request' (duration: 166.206671ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:08.051472Z","caller":"traceutil/trace.go:171","msg":"trace[793391560] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"139.579818ms","start":"2026-04-09T22:30:07.911881Z","end":"2026-04-09T22:30:08.051461Z","steps":["trace[793391560] 'process raft request' (duration: 139.400734ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:08.051409Z","caller":"traceutil/trace.go:171","msg":"trace[1179112535] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"145.422627ms","start":"2026-04-09T22:30:07.905941Z","end":"2026-04-09T22:30:08.051364Z","steps":["trace[1179112535] 'process raft request' (duration: 145.274713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:08.051627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.864789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:30:08.051671Z","caller":"traceutil/trace.go:171","msg":"trace[1960296113] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:503; }","duration":"103.958251ms","start":"2026-04-09T22:30:07.947703Z","end":"2026-04-09T22:30:08.051661Z","steps":["trace[1960296113] 'agreement among raft nodes before linearized reading' (duration: 103.767126ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:14.516391Z","caller":"traceutil/trace.go:171","msg":"trace[142033103] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"123.650815ms","start":"2026-04-09T22:30:14.392715Z","end":"2026-04-09T22:30:14.516366Z","steps":["trace[142033103] 'process raft request' (duration: 123.48769ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:14.860974Z","caller":"traceutil/trace.go:171","msg":"trace[472529679] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:603; }","duration":"111.133857ms","start":"2026-04-09T22:30:14.749759Z","end":"2026-04-09T22:30:14.860893Z","steps":["trace[472529679] 'read index received' (duration: 104.794705ms)","trace[472529679] 'applied index is now lower than readState.Index' (duration: 6.337582ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:30:14.861113Z","caller":"traceutil/trace.go:171","msg":"trace[1930515536] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"155.917862ms","start":"2026-04-09T22:30:14.705157Z","end":"2026-04-09T22:30:14.861075Z","steps":["trace[1930515536] 'process raft request' (duration: 149.689243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:14.861292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.504116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T22:30:14.861357Z","caller":"traceutil/trace.go:171","msg":"trace[107365849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"111.63708ms","start":"2026-04-09T22:30:14.749702Z","end":"2026-04-09T22:30:14.861339Z","steps":["trace[107365849] 'agreement among raft nodes before linearized reading' (duration: 111.426404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.102973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.5039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:30:15.103072Z","caller":"traceutil/trace.go:171","msg":"trace[810542036] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"237.777063ms","start":"2026-04-09T22:30:14.865277Z","end":"2026-04-09T22:30:15.103054Z","steps":["trace[810542036] 'compare' (duration: 229.403116ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.371843Z","caller":"traceutil/trace.go:171","msg":"trace[262873234] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"378.991406ms","start":"2026-04-09T22:30:14.992798Z","end":"2026-04-09T22:30:15.37179Z","steps":["trace[262873234] 'read index received' (duration: 357.618996ms)","trace[262873234] 'applied index is now lower than readState.Index' (duration: 21.37155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:15.37207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.247573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.372103Z","caller":"traceutil/trace.go:171","msg":"trace[1300121282] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"379.327266ms","start":"2026-04-09T22:30:14.992768Z","end":"2026-04-09T22:30:15.372095Z","steps":["trace[1300121282] 'agreement among raft nodes before linearized reading' (duration: 379.218133ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:14.99275Z","time spent":"379.372536ms","remote":"127.0.0.1:37980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:15.371955Z","caller":"traceutil/trace.go:171","msg":"trace[1006104437] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"451.867284ms","start":"2026-04-09T22:30:14.920069Z","end":"2026-04-09T22:30:15.371936Z","steps":["trace[1006104437] 'process raft request' (duration: 430.870934ms)","trace[1006104437] 'compare' (duration: 20.687042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:30:15.372301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:14.920029Z","time spent":"452.213804ms","remote":"127.0.0.1:58392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-09T22:30:15.37245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.849264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.372478Z","caller":"traceutil/trace.go:171","msg":"trace[40621028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"227.941257ms","start":"2026-04-09T22:30:15.14453Z","end":"2026-04-09T22:30:15.372471Z","steps":["trace[40621028] 'agreement among raft nodes before linearized reading' (duration: 227.896776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.137564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.372607Z","caller":"traceutil/trace.go:171","msg":"trace[716106323] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"203.190585ms","start":"2026-04-09T22:30:15.16941Z","end":"2026-04-09T22:30:15.3726Z","steps":["trace[716106323] 'agreement among raft nodes before linearized reading' (duration: 203.154334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.262147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.372731Z","caller":"traceutil/trace.go:171","msg":"trace[1236977322] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"203.338459ms","start":"2026-04-09T22:30:15.169386Z","end":"2026-04-09T22:30:15.372725Z","steps":["trace[1236977322] 'agreement among raft nodes before linearized reading' (duration: 203.301918ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.568728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.734626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.964784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.678282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.001436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373001Z","caller":"traceutil/trace.go:171","msg":"trace[761094062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"257.020446ms","start":"2026-04-09T22:30:15.115975Z","end":"2026-04-09T22:30:15.372995Z","steps":["trace[761094062] 'agreement among raft nodes before linearized reading' (duration: 256.992245ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.442923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.37303Z","caller":"traceutil/trace.go:171","msg":"trace[1744102278] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"290.499175ms","start":"2026-04-09T22:30:15.082522Z","end":"2026-04-09T22:30:15.373022Z","steps":["trace[1744102278] 'agreement among raft nodes before linearized reading' (duration: 290.451323ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.373009Z","caller":"traceutil/trace.go:171","msg":"trace[1343302110] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"289.787936ms","start":"2026-04-09T22:30:15.083195Z","end":"2026-04-09T22:30:15.372983Z","steps":["trace[1343302110] 'agreement among raft nodes before linearized reading' (duration: 289.636322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.084346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.812297ms","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\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.84781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373113Z","caller":"traceutil/trace.go:171","msg":"trace[355690593] 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:586; }","duration":"313.944951ms","start":"2026-04-09T22:30:15.059157Z","end":"2026-04-09T22:30:15.373102Z","steps":["trace[355690593] 'agreement among raft nodes before linearized reading' (duration: 313.793837ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.373111Z","caller":"traceutil/trace.go:171","msg":"trace[328578387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"156.22102ms","start":"2026-04-09T22:30:15.216875Z","end":"2026-04-09T22:30:15.373096Z","steps":["trace[328578387] 'agreement among raft nodes before linearized reading' (duration: 156.147338ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.373132Z","caller":"traceutil/trace.go:171","msg":"trace[727702497] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"156.283071ms","start":"2026-04-09T22:30:15.216841Z","end":"2026-04-09T22:30:15.373124Z","steps":["trace[727702497] 'agreement among raft nodes before linearized reading' (duration: 156.121377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.059139Z","time spent":"314.027533ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:15.372914Z","caller":"traceutil/trace.go:171","msg":"trace[660881472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"257.115778ms","start":"2026-04-09T22:30:15.115779Z","end":"2026-04-09T22:30:15.372895Z","steps":["trace[660881472] 'agreement among raft nodes before linearized reading' (duration: 256.937973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.738634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373269Z","caller":"traceutil/trace.go:171","msg":"trace[1693679862] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"227.207866ms","start":"2026-04-09T22:30:15.146054Z","end":"2026-04-09T22:30:15.373262Z","steps":["trace[1693679862] 'agreement among raft nodes before linearized reading' (duration: 226.746644ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.113133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-b5lhd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-09T22:30:15.372909Z","caller":"traceutil/trace.go:171","msg":"trace[2070865216] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"380.049115ms","start":"2026-04-09T22:30:14.992794Z","end":"2026-04-09T22:30:15.372843Z","steps":["trace[2070865216] 'agreement among raft nodes before linearized reading' (duration: 379.636054ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.373331Z","caller":"traceutil/trace.go:171","msg":"trace[488007972] range","detail":"{range_begin:/registry/pods/kube-system/cilium-b5lhd; range_end:; response_count:1; response_revision:586; }","duration":"133.255697ms","start":"2026-04-09T22:30:15.240056Z","end":"2026-04-09T22:30:15.373312Z","steps":["trace[488007972] 'agreement among raft nodes before linearized reading' (duration: 132.408864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.43745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:14.992773Z","time spent":"380.58185ms","remote":"127.0.0.1:37980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:30:15.373387Z","caller":"traceutil/trace.go:171","msg":"trace[984733939] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"109.46737ms","start":"2026-04-09T22:30:15.263914Z","end":"2026-04-09T22:30:15.373381Z","steps":["trace[984733939] 'agreement among raft nodes before linearized reading' (duration: 109.43666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.132559ms","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\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373464Z","caller":"traceutil/trace.go:171","msg":"trace[1777470397] 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:586; }","duration":"313.874528ms","start":"2026-04-09T22:30:15.05958Z","end":"2026-04-09T22:30:15.373455Z","steps":["trace[1777470397] 'agreement among raft nodes before linearized reading' (duration: 313.076047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.580594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.890626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373506Z","caller":"traceutil/trace.go:171","msg":"trace[2001736639] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"109.613395ms","start":"2026-04-09T22:30:15.263888Z","end":"2026-04-09T22:30:15.373501Z","steps":["trace[2001736639] 'agreement among raft nodes before linearized reading' (duration: 109.581794ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.373531Z","caller":"traceutil/trace.go:171","msg":"trace[1711356970] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"291.013918ms","start":"2026-04-09T22:30:15.082502Z","end":"2026-04-09T22:30:15.373516Z","steps":["trace[1711356970] 'agreement among raft nodes before linearized reading' (duration: 290.891605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.3737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.200805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.400613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373745Z","caller":"traceutil/trace.go:171","msg":"trace[997999957] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"336.273427ms","start":"2026-04-09T22:30:15.037454Z","end":"2026-04-09T22:30:15.373728Z","steps":["trace[997999957] 'agreement among raft nodes before linearized reading' (duration: 336.125303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.331062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.373765Z","caller":"traceutil/trace.go:171","msg":"trace[980310274] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"129.454553ms","start":"2026-04-09T22:30:15.244294Z","end":"2026-04-09T22:30:15.373749Z","steps":["trace[980310274] 'agreement among raft nodes before linearized reading' (duration: 129.389972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.037444Z","time spent":"336.317648ms","remote":"127.0.0.1:37994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:30:15.37378Z","caller":"traceutil/trace.go:171","msg":"trace[525193558] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"125.408604ms","start":"2026-04-09T22:30:15.24836Z","end":"2026-04-09T22:30:15.373769Z","steps":["trace[525193558] 'agreement among raft nodes before linearized reading' (duration: 125.27041ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.372954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.442481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.37379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.620159ms","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":"warn","ts":"2026-04-09T22:30:15.373487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.059572Z","time spent":"313.90779ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"info","ts":"2026-04-09T22:30:15.373853Z","caller":"traceutil/trace.go:171","msg":"trace[973218968] 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:586; }","duration":"314.727422ms","start":"2026-04-09T22:30:15.05911Z","end":"2026-04-09T22:30:15.373837Z","steps":["trace[973218968] 'agreement among raft nodes before linearized reading' (duration: 314.633889ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.00205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.059098Z","time spent":"314.795144ms","remote":"127.0.0.1:38006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:30:15.373935Z","caller":"traceutil/trace.go:171","msg":"trace[504868830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"336.03463ms","start":"2026-04-09T22:30:15.037895Z","end":"2026-04-09T22:30:15.373929Z","steps":["trace[504868830] 'agreement among raft nodes before linearized reading' (duration: 336.002269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.401911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.037887Z","time spent":"336.060951ms","remote":"127.0.0.1:37994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-04-09T22:30:15.373906Z","caller":"traceutil/trace.go:171","msg":"trace[2074146251] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:586; }","duration":"229.359965ms","start":"2026-04-09T22:30:15.144457Z","end":"2026-04-09T22:30:15.373817Z","steps":["trace[2074146251] 'agreement among raft nodes before linearized reading' (duration: 228.476761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.373983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.95275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.632815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.372932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.221884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.374008Z","caller":"traceutil/trace.go:171","msg":"trace[1548515202] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:586; }","duration":"380.97663ms","start":"2026-04-09T22:30:14.993022Z","end":"2026-04-09T22:30:15.373999Z","steps":["trace[1548515202] 'agreement among raft nodes before linearized reading' (duration: 380.939019ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.374002Z","caller":"traceutil/trace.go:171","msg":"trace[493091122] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:586; }","duration":"336.490923ms","start":"2026-04-09T22:30:15.037468Z","end":"2026-04-09T22:30:15.373959Z","steps":["trace[493091122] 'agreement among raft nodes before linearized reading' (duration: 336.37452ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.374026Z","caller":"traceutil/trace.go:171","msg":"trace[2010669571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:586; }","duration":"152.335365ms","start":"2026-04-09T22:30:15.221681Z","end":"2026-04-09T22:30:15.374016Z","steps":["trace[2010669571] 'agreement among raft nodes before linearized reading' (duration: 151.213554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.374034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:14.992999Z","time spent":"381.028092ms","remote":"127.0.0.1:37980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-04-09T22:30:15.374051Z","caller":"traceutil/trace.go:171","msg":"trace[817977136] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:586; }","duration":"110.350335ms","start":"2026-04-09T22:30:15.263663Z","end":"2026-04-09T22:30:15.374013Z","steps":["trace[817977136] 'agreement among raft nodes before linearized reading' (duration: 109.654826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.374072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.403493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:30:15.373617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.031362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:30:15.372853Z","caller":"traceutil/trace.go:171","msg":"trace[463374662] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"202.732442ms","start":"2026-04-09T22:30:15.17009Z","end":"2026-04-09T22:30:15.372822Z","steps":["trace[463374662] 'agreement among raft nodes before linearized reading' (duration: 202.498116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.374049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:15.037422Z","time spent":"336.612516ms","remote":"127.0.0.1:37994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-04-09T22:30:15.374249Z","caller":"traceutil/trace.go:171","msg":"trace[740643959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"377.545427ms","start":"2026-04-09T22:30:14.99662Z","end":"2026-04-09T22:30:15.374166Z","steps":["trace[740643959] 'agreement among raft nodes before linearized reading' (duration: 377.352862ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:15.374234Z","caller":"traceutil/trace.go:171","msg":"trace[1730121627] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:586; }","duration":"129.658659ms","start":"2026-04-09T22:30:15.244568Z","end":"2026-04-09T22:30:15.374227Z","steps":["trace[1730121627] 'agreement among raft nodes before linearized reading' (duration: 129.029152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:30:15.374313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:30:14.996615Z","time spent":"377.68652ms","remote":"127.0.0.1:58114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-09T22:30:32.48264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.069852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-09T22:30:32.482698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.602783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-frctf\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-09T22:30:32.482777Z","caller":"traceutil/trace.go:171","msg":"trace[263453517] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"269.290728ms","start":"2026-04-09T22:30:32.21346Z","end":"2026-04-09T22:30:32.482751Z","steps":["trace[263453517] 'range keys from in-memory index tree' (duration: 268.777024ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:30:32.482824Z","caller":"traceutil/trace.go:171","msg":"trace[1203889514] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-frctf; range_end:; response_count:1; response_revision:752; }","duration":"134.787238ms","start":"2026-04-09T22:30:32.348001Z","end":"2026-04-09T22:30:32.482788Z","steps":["trace[1203889514] 'range keys from in-memory index tree' (duration: 134.366127ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:00.056089Z","caller":"traceutil/trace.go:171","msg":"trace[919408348] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"110.232333ms","start":"2026-04-09T22:30:59.945831Z","end":"2026-04-09T22:31:00.056064Z","steps":["trace[919408348] 'process raft request' (duration: 110.039717ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:45.728137Z","caller":"traceutil/trace.go:171","msg":"trace[100263393] transaction","detail":"{read_only:false; response_revision:1810; number_of_response:1; }","duration":"104.68144ms","start":"2026-04-09T22:31:45.62342Z","end":"2026-04-09T22:31:45.728101Z","steps":["trace[100263393] 'process raft request' (duration: 104.470544ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.01334Z","caller":"traceutil/trace.go:171","msg":"trace[2058873934] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"280.66896ms","start":"2026-04-09T22:31:45.732639Z","end":"2026-04-09T22:31:46.013308Z","steps":["trace[2058873934] 'process raft request' (duration: 265.766086ms)","trace[2058873934] 'compare' (duration: 14.110772ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:46.030064Z","caller":"traceutil/trace.go:171","msg":"trace[1136145631] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"290.767031ms","start":"2026-04-09T22:31:45.73926Z","end":"2026-04-09T22:31:46.030027Z","steps":["trace[1136145631] 'process raft request' (duration: 289.990779ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.030152Z","caller":"traceutil/trace.go:171","msg":"trace[1320269887] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1878; }","duration":"272.363209ms","start":"2026-04-09T22:31:45.757763Z","end":"2026-04-09T22:31:46.030126Z","steps":["trace[1320269887] 'read index received' (duration: 240.70735ms)","trace[1320269887] 'applied index is now lower than readState.Index' (duration: 31.652679ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:46.030229Z","caller":"traceutil/trace.go:171","msg":"trace[237364500] transaction","detail":"{read_only:false; response_revision:1813; number_of_response:1; }","duration":"186.138883ms","start":"2026-04-09T22:31:45.844069Z","end":"2026-04-09T22:31:46.030208Z","steps":["trace[237364500] 'process raft request' (duration: 185.896086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:46.030429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.859581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T22:31:46.030456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.672648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f326b28b-dbfc-410f-ad23-af29e80c298b\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-04-09T22:31:46.030583Z","caller":"traceutil/trace.go:171","msg":"trace[1498018502] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1813; }","duration":"111.022295ms","start":"2026-04-09T22:31:45.919498Z","end":"2026-04-09T22:31:46.03052Z","steps":["trace[1498018502] 'agreement among raft nodes before linearized reading' (duration: 110.794859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.030604Z","caller":"traceutil/trace.go:171","msg":"trace[2095722457] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f326b28b-dbfc-410f-ad23-af29e80c298b; range_end:; response_count:1; response_revision:1813; }","duration":"272.822382ms","start":"2026-04-09T22:31:45.757757Z","end":"2026-04-09T22:31:46.030579Z","steps":["trace[2095722457] 'agreement among raft nodes before linearized reading' (duration: 272.508743ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.214764Z","caller":"traceutil/trace.go:171","msg":"trace[891394249] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"178.264944ms","start":"2026-04-09T22:31:46.036472Z","end":"2026-04-09T22:31:46.214737Z","steps":["trace[891394249] 'process raft request' (duration: 139.881027ms)","trace[891394249] 'compare' (duration: 38.015167ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:46.214944Z","caller":"traceutil/trace.go:171","msg":"trace[1145096912] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1881; }","duration":"175.554859ms","start":"2026-04-09T22:31:46.039366Z","end":"2026-04-09T22:31:46.214921Z","steps":["trace[1145096912] 'read index received' (duration: 27.790152ms)","trace[1145096912] 'applied index is now lower than readState.Index' (duration: 147.763817ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:46.21504Z","caller":"traceutil/trace.go:171","msg":"trace[1430953582] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"178.387347ms","start":"2026-04-09T22:31:46.036614Z","end":"2026-04-09T22:31:46.215001Z","steps":["trace[1430953582] 'process raft request' (duration: 178.13847ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.215203Z","caller":"traceutil/trace.go:171","msg":"trace[692979472] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"178.692346ms","start":"2026-04-09T22:31:46.036485Z","end":"2026-04-09T22:31:46.215177Z","steps":["trace[692979472] 'process raft request' (duration: 178.142061ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:31:46.215352Z","caller":"traceutil/trace.go:171","msg":"trace[243829548] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"176.025542ms","start":"2026-04-09T22:31:46.039296Z","end":"2026-04-09T22:31:46.215322Z","steps":["trace[243829548] 'process raft request' (duration: 175.544399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:46.215336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.819576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T22:31:46.215679Z","caller":"traceutil/trace.go:171","msg":"trace[1882818550] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1818; }","duration":"176.31377ms","start":"2026-04-09T22:31:46.039336Z","end":"2026-04-09T22:31:46.21565Z","steps":["trace[1882818550] 'agreement among raft nodes before linearized reading' (duration: 175.687673ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:46.44726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.205926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-09T22:31:46.447352Z","caller":"traceutil/trace.go:171","msg":"trace[750919241] transaction","detail":"{read_only:false; response_revision:1819; number_of_response:1; }","duration":"158.767063ms","start":"2026-04-09T22:31:46.28857Z","end":"2026-04-09T22:31:46.447337Z","steps":["trace[750919241] 'process raft request' (duration: 45.389572ms)","trace[750919241] 'compare' (duration: 112.951379ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:53.814529Z","caller":"traceutil/trace.go:171","msg":"trace[1014590523] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"117.980378ms","start":"2026-04-09T22:31:53.696517Z","end":"2026-04-09T22:31:53.814498Z","steps":["trace[1014590523] 'process raft request' (duration: 91.798781ms)","trace[1014590523] 'compare' (duration: 25.92839ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:57.594521Z","caller":"traceutil/trace.go:171","msg":"trace[1891552966] linearizableReadLoop","detail":"{readStateIndex:1983; appliedIndex:1982; }","duration":"330.603278ms","start":"2026-04-09T22:31:57.263893Z","end":"2026-04-09T22:31:57.594496Z","steps":["trace[1891552966] 'read index received' (duration: 330.376572ms)","trace[1891552966] 'applied index is now lower than readState.Index' (duration: 225.746µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:31:57.594597Z","caller":"traceutil/trace.go:171","msg":"trace[2067167460] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"357.737542ms","start":"2026-04-09T22:31:57.236825Z","end":"2026-04-09T22:31:57.594563Z","steps":["trace[2067167460] 'process raft request' (duration: 357.520446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:57.594662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.752842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:31:57.594709Z","caller":"traceutil/trace.go:171","msg":"trace[928231132] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1913; }","duration":"330.857315ms","start":"2026-04-09T22:31:57.263839Z","end":"2026-04-09T22:31:57.594697Z","steps":["trace[928231132] 'agreement among raft nodes before linearized reading' (duration: 330.765532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:57.594753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:57.263822Z","time spent":"330.917087ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-04-09T22:31:57.594762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:57.236804Z","time spent":"357.870565ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:31:59.966762Z","caller":"traceutil/trace.go:171","msg":"trace[815917949] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"363.622725ms","start":"2026-04-09T22:31:59.603105Z","end":"2026-04-09T22:31:59.966728Z","steps":["trace[815917949] 'process raft request' (duration: 363.410389ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:31:59.967007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:59.603088Z","time spent":"363.78042ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:32:00.002542Z","caller":"traceutil/trace.go:171","msg":"trace[1195734530] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"353.616468ms","start":"2026-04-09T22:31:59.648902Z","end":"2026-04-09T22:32:00.002519Z","steps":["trace[1195734530] 'process raft request' (duration: 353.455613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:00.002689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:59.64889Z","time spent":"353.7196ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:32:00.002804Z","caller":"traceutil/trace.go:171","msg":"trace[1392303083] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"348.032832ms","start":"2026-04-09T22:31:59.654764Z","end":"2026-04-09T22:32:00.002797Z","steps":["trace[1392303083] 'process raft request' (duration: 347.712973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:00.002839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:31:59.654746Z","time spent":"348.076423ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:32:30.311557Z","caller":"traceutil/trace.go:171","msg":"trace[2096496246] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"164.671707ms","start":"2026-04-09T22:32:30.146858Z","end":"2026-04-09T22:32:30.311529Z","steps":["trace[2096496246] 'process raft request' (duration: 101.146836ms)","trace[2096496246] 'compare' (duration: 63.418218ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:30.365757Z","caller":"traceutil/trace.go:171","msg":"trace[1492201921] transaction","detail":"{read_only:false; response_revision:2103; number_of_response:1; }","duration":"218.739ms","start":"2026-04-09T22:32:30.146992Z","end":"2026-04-09T22:32:30.365731Z","steps":["trace[1492201921] 'process raft request' (duration: 218.599056ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:30.366008Z","caller":"traceutil/trace.go:171","msg":"trace[481013201] linearizableReadLoop","detail":"{readStateIndex:2181; appliedIndex:2178; }","duration":"137.574159ms","start":"2026-04-09T22:32:30.228378Z","end":"2026-04-09T22:32:30.365952Z","steps":["trace[481013201] 'read index received' (duration: 19.613725ms)","trace[481013201] 'applied index is now lower than readState.Index' (duration: 117.957094ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:30.366167Z","caller":"traceutil/trace.go:171","msg":"trace[1190032355] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"154.009317ms","start":"2026-04-09T22:32:30.212108Z","end":"2026-04-09T22:32:30.366117Z","steps":["trace[1190032355] 'process raft request' (duration: 153.574205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:30.36637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.963669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-09T22:32:30.366427Z","caller":"traceutil/trace.go:171","msg":"trace[1670915025] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2104; }","duration":"138.084173ms","start":"2026-04-09T22:32:30.228323Z","end":"2026-04-09T22:32:30.366407Z","steps":["trace[1670915025] 'agreement among raft nodes before linearized reading' (duration: 137.839016ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:36.507411Z","caller":"traceutil/trace.go:171","msg":"trace[510807466] transaction","detail":"{read_only:false; response_revision:2133; number_of_response:1; }","duration":"168.937503ms","start":"2026-04-09T22:32:36.338432Z","end":"2026-04-09T22:32:36.507369Z","steps":["trace[510807466] 'process raft request' (duration: 168.716867ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:36.65414Z","caller":"traceutil/trace.go:171","msg":"trace[2016059403] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2210; }","duration":"265.957227ms","start":"2026-04-09T22:32:36.388159Z","end":"2026-04-09T22:32:36.654116Z","steps":["trace[2016059403] 'read index received' (duration: 119.006343ms)","trace[2016059403] 'applied index is now lower than readState.Index' (duration: 146.949594ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:32:36.654197Z","caller":"traceutil/trace.go:171","msg":"trace[474375483] transaction","detail":"{read_only:false; response_revision:2134; number_of_response:1; }","duration":"271.58256ms","start":"2026-04-09T22:32:36.382599Z","end":"2026-04-09T22:32:36.654181Z","steps":["trace[474375483] 'process raft request' (duration: 228.142356ms)","trace[474375483] 'compare' (duration: 43.168747ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:32:36.654389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.762499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-09T22:32:36.654422Z","caller":"traceutil/trace.go:171","msg":"trace[1154552703] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"226.859981ms","start":"2026-04-09T22:32:36.42755Z","end":"2026-04-09T22:32:36.65441Z","steps":["trace[1154552703] 'agreement among raft nodes before linearized reading' (duration: 226.745928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:32:36.654421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.366333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-04-09T22:32:36.654425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.238455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T22:32:36.654493Z","caller":"traceutil/trace.go:171","msg":"trace[388847647] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2134; }","duration":"246.464756ms","start":"2026-04-09T22:32:36.408016Z","end":"2026-04-09T22:32:36.65448Z","steps":["trace[388847647] 'agreement among raft nodes before linearized reading' (duration: 246.2508ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:32:36.654511Z","caller":"traceutil/trace.go:171","msg":"trace[1385321965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2134; }","duration":"266.361117ms","start":"2026-04-09T22:32:36.388131Z","end":"2026-04-09T22:32:36.654492Z","steps":["trace[1385321965] 'agreement among raft nodes before linearized reading' (duration: 266.10477ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:02.693454Z","caller":"traceutil/trace.go:171","msg":"trace[1102618674] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"101.838252ms","start":"2026-04-09T22:34:02.59159Z","end":"2026-04-09T22:34:02.693428Z","steps":["trace[1102618674] 'process raft request' (duration: 101.639227ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:02.756133Z","caller":"traceutil/trace.go:171","msg":"trace[19370381] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"121.25652ms","start":"2026-04-09T22:34:02.634844Z","end":"2026-04-09T22:34:02.7561Z","steps":["trace[19370381] 'process raft request' (duration: 95.001854ms)","trace[19370381] 'compare' (duration: 26.094791ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:03.080014Z","caller":"traceutil/trace.go:171","msg":"trace[2103813204] transaction","detail":"{read_only:false; response_revision:2794; number_of_response:1; }","duration":"130.587137ms","start":"2026-04-09T22:34:02.949375Z","end":"2026-04-09T22:34:03.079962Z","steps":["trace[2103813204] 'process raft request' (duration: 130.33938ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:34:03.284101Z","caller":"traceutil/trace.go:171","msg":"trace[1276577873] linearizableReadLoop","detail":"{readStateIndex:2899; appliedIndex:2898; }","duration":"164.990597ms","start":"2026-04-09T22:34:03.119085Z","end":"2026-04-09T22:34:03.284076Z","steps":["trace[1276577873] 'read index received' (duration: 145.561521ms)","trace[1276577873] 'applied index is now lower than readState.Index' (duration: 19.427736ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:34:03.284231Z","caller":"traceutil/trace.go:171","msg":"trace[138903945] transaction","detail":"{read_only:false; response_revision:2795; number_of_response:1; }","duration":"199.087078ms","start":"2026-04-09T22:34:03.08509Z","end":"2026-04-09T22:34:03.284177Z","steps":["trace[138903945] 'process raft request' (duration: 179.466577ms)","trace[138903945] 'compare' (duration: 19.414396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:34:03.284345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.244024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-09T22:34:03.284392Z","caller":"traceutil/trace.go:171","msg":"trace[619865574] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2795; }","duration":"165.351677ms","start":"2026-04-09T22:34:03.119027Z","end":"2026-04-09T22:34:03.284378Z","steps":["trace[619865574] 'agreement among raft nodes before linearized reading' (duration: 165.193892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:03.284532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.71972ms","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-04-09T22:34:03.284558Z","caller":"traceutil/trace.go:171","msg":"trace[2087179541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2795; }","duration":"109.805623ms","start":"2026-04-09T22:34:03.174746Z","end":"2026-04-09T22:34:03.284552Z","steps":["trace[2087179541] 'agreement among raft nodes before linearized reading' (duration: 109.746371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:34:03.284694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.454882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T22:34:03.284778Z","caller":"traceutil/trace.go:171","msg":"trace[258185821] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2795; }","duration":"164.666838ms","start":"2026-04-09T22:34:03.120091Z","end":"2026-04-09T22:34:03.284757Z","steps":["trace[258185821] 'agreement among raft nodes before linearized reading' (duration: 164.395901ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:36:34.270076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.444811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:36:34.270182Z","caller":"traceutil/trace.go:171","msg":"trace[992004513] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3793; }","duration":"119.582604ms","start":"2026-04-09T22:36:34.150574Z","end":"2026-04-09T22:36:34.270156Z","steps":["trace[992004513] 'range keys from in-memory index tree' (duration: 119.369088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:36:34.270153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.756958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-09T22:36:34.270252Z","caller":"traceutil/trace.go:171","msg":"trace[30349934] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3793; }","duration":"137.898743ms","start":"2026-04-09T22:36:34.132335Z","end":"2026-04-09T22:36:34.270233Z","steps":["trace[30349934] 'range keys from in-memory index tree' (duration: 137.570293ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:37:57.830245Z","caller":"traceutil/trace.go:171","msg":"trace[370411099] transaction","detail":"{read_only:false; response_revision:4769; number_of_response:1; }","duration":"109.25495ms","start":"2026-04-09T22:37:57.720972Z","end":"2026-04-09T22:37:57.830227Z","steps":["trace[370411099] 'process raft request' (duration: 109.078766ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:37:57.830237Z","caller":"traceutil/trace.go:171","msg":"trace[921016195] linearizableReadLoop","detail":"{readStateIndex:4947; appliedIndex:4946; }","duration":"101.56339ms","start":"2026-04-09T22:37:57.728645Z","end":"2026-04-09T22:37:57.830208Z","steps":["trace[921016195] 'read index received' (duration: 101.260252ms)","trace[921016195] 'applied index is now lower than readState.Index' (duration: 302.248µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:37:57.830463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.795266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1261"} {"level":"info","ts":"2026-04-09T22:37:57.830499Z","caller":"traceutil/trace.go:171","msg":"trace[198201264] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4769; }","duration":"101.877559ms","start":"2026-04-09T22:37:57.728614Z","end":"2026-04-09T22:37:57.830491Z","steps":["trace[198201264] 'agreement among raft nodes before linearized reading' (duration: 101.688713ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.667477Z","caller":"traceutil/trace.go:171","msg":"trace[1544323009] transaction","detail":"{read_only:false; response_revision:4848; number_of_response:1; }","duration":"216.581438ms","start":"2026-04-09T22:38:00.450871Z","end":"2026-04-09T22:38:00.667453Z","steps":["trace[1544323009] 'process raft request' (duration: 216.427864ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.679599Z","caller":"traceutil/trace.go:171","msg":"trace[287143806] linearizableReadLoop","detail":"{readStateIndex:5032; appliedIndex:5029; }","duration":"203.062338ms","start":"2026-04-09T22:38:00.476518Z","end":"2026-04-09T22:38:00.67958Z","steps":["trace[287143806] 'read index received' (duration: 190.832533ms)","trace[287143806] 'applied index is now lower than readState.Index' (duration: 12.228555ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:00.679666Z","caller":"traceutil/trace.go:171","msg":"trace[1289191823] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"224.473644ms","start":"2026-04-09T22:38:00.455154Z","end":"2026-04-09T22:38:00.679627Z","steps":["trace[1289191823] 'process raft request' (duration: 224.30875ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.679772Z","caller":"traceutil/trace.go:171","msg":"trace[415495709] transaction","detail":"{read_only:false; response_revision:4849; number_of_response:1; }","duration":"226.357155ms","start":"2026-04-09T22:38:00.453382Z","end":"2026-04-09T22:38:00.67974Z","steps":["trace[415495709] 'process raft request' (duration: 222.270993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:00.679994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.819902ms","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":"warn","ts":"2026-04-09T22:38:00.680047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.510571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-09T22:38:00.680105Z","caller":"traceutil/trace.go:171","msg":"trace[946117168] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4851; }","duration":"179.949186ms","start":"2026-04-09T22:38:00.50013Z","end":"2026-04-09T22:38:00.680079Z","steps":["trace[946117168] 'agreement among raft nodes before linearized reading' (duration: 179.606956ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:00.680124Z","caller":"traceutil/trace.go:171","msg":"trace[1746422274] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4851; }","duration":"203.652824ms","start":"2026-04-09T22:38:00.476445Z","end":"2026-04-09T22:38:00.680098Z","steps":["trace[1746422274] 'agreement among raft nodes before linearized reading' (duration: 203.401117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:04.606452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.306243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:38:04.606579Z","caller":"traceutil/trace.go:171","msg":"trace[897056287] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:4980; }","duration":"245.499799ms","start":"2026-04-09T22:38:04.361044Z","end":"2026-04-09T22:38:04.606544Z","steps":["trace[897056287] 'count revisions from in-memory index tree' (duration: 245.083288ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:04.619391Z","caller":"traceutil/trace.go:171","msg":"trace[246607657] linearizableReadLoop","detail":"{readStateIndex:5166; appliedIndex:5165; }","duration":"130.477991ms","start":"2026-04-09T22:38:04.488884Z","end":"2026-04-09T22:38:04.619362Z","steps":["trace[246607657] 'read index received' (duration: 130.326717ms)","trace[246607657] 'applied index is now lower than readState.Index' (duration: 149.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:04.619461Z","caller":"traceutil/trace.go:171","msg":"trace[1269792331] transaction","detail":"{read_only:false; response_revision:4981; number_of_response:1; }","duration":"156.075821ms","start":"2026-04-09T22:38:04.463359Z","end":"2026-04-09T22:38:04.619435Z","steps":["trace[1269792331] 'process raft request' (duration: 155.784693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:04.62058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.677284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:719272"} {"level":"info","ts":"2026-04-09T22:38:04.620658Z","caller":"traceutil/trace.go:171","msg":"trace[1708691914] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4981; }","duration":"131.799057ms","start":"2026-04-09T22:38:04.488837Z","end":"2026-04-09T22:38:04.620636Z","steps":["trace[1708691914] 'agreement among raft nodes before linearized reading' (duration: 130.646876ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:04.643231Z","caller":"traceutil/trace.go:171","msg":"trace[490143559] transaction","detail":"{read_only:false; response_revision:4982; number_of_response:1; }","duration":"114.619997ms","start":"2026-04-09T22:38:04.528591Z","end":"2026-04-09T22:38:04.643211Z","steps":["trace[490143559] 'process raft request' (duration: 114.37353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:05.025715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.56057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:38:05.025806Z","caller":"traceutil/trace.go:171","msg":"trace[43938623] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4986; }","duration":"131.722025ms","start":"2026-04-09T22:38:04.894065Z","end":"2026-04-09T22:38:05.025787Z","steps":["trace[43938623] 'range keys from in-memory index tree' (duration: 131.260333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:05.025816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.75957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T22:38:05.025918Z","caller":"traceutil/trace.go:171","msg":"trace[661255077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4986; }","duration":"122.921974ms","start":"2026-04-09T22:38:04.902972Z","end":"2026-04-09T22:38:05.025894Z","steps":["trace[661255077] 'range keys from in-memory index tree' (duration: 122.539983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:16.973669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.449749ms","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-04-09T22:38:16.973795Z","caller":"traceutil/trace.go:171","msg":"trace[1298196778] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5219; }","duration":"119.647425ms","start":"2026-04-09T22:38:16.854123Z","end":"2026-04-09T22:38:16.973771Z","steps":["trace[1298196778] 'range keys from in-memory index tree' (duration: 119.254334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:16.973904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.44258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T22:38:16.974004Z","caller":"traceutil/trace.go:171","msg":"trace[139079423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5219; }","duration":"149.599175ms","start":"2026-04-09T22:38:16.824385Z","end":"2026-04-09T22:38:16.973984Z","steps":["trace[139079423] 'range keys from in-memory index tree' (duration: 149.256865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:16.974183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.380995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-04-09T22:38:16.974261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.650605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-54tfs\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-04-09T22:38:16.974342Z","caller":"traceutil/trace.go:171","msg":"trace[1926960052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5219; }","duration":"154.575821ms","start":"2026-04-09T22:38:16.819736Z","end":"2026-04-09T22:38:16.974312Z","steps":["trace[1926960052] 'range keys from in-memory index tree' (duration: 154.056246ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:16.974391Z","caller":"traceutil/trace.go:171","msg":"trace[281186609] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-54tfs; range_end:; response_count:1; response_revision:5219; }","duration":"119.8328ms","start":"2026-04-09T22:38:16.854519Z","end":"2026-04-09T22:38:16.974352Z","steps":["trace[281186609] 'range keys from in-memory index tree' (duration: 119.387458ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:16.973681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.218668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:38:16.974544Z","caller":"traceutil/trace.go:171","msg":"trace[212903888] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5219; }","duration":"156.148503ms","start":"2026-04-09T22:38:16.81837Z","end":"2026-04-09T22:38:16.974518Z","steps":["trace[212903888] 'count revisions from in-memory index tree' (duration: 155.084894ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.071702Z","caller":"traceutil/trace.go:171","msg":"trace[656301843] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"107.164463ms","start":"2026-04-09T22:38:24.964505Z","end":"2026-04-09T22:38:25.07167Z","steps":["trace[656301843] 'process raft request' (duration: 106.878145ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.358069Z","caller":"traceutil/trace.go:171","msg":"trace[376327831] transaction","detail":"{read_only:false; response_revision:5394; number_of_response:1; }","duration":"393.352055ms","start":"2026-04-09T22:38:24.96465Z","end":"2026-04-09T22:38:25.358002Z","steps":["trace[376327831] 'process raft request' (duration: 302.880059ms)","trace[376327831] 'compare' (duration: 90.259191ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:38:25.35816Z","caller":"traceutil/trace.go:171","msg":"trace[1924929686] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"392.875422ms","start":"2026-04-09T22:38:24.965272Z","end":"2026-04-09T22:38:25.358148Z","steps":["trace[1924929686] 'process raft request' (duration: 392.630156ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.358213Z","caller":"traceutil/trace.go:171","msg":"trace[342012764] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"392.558904ms","start":"2026-04-09T22:38:24.965639Z","end":"2026-04-09T22:38:25.358198Z","steps":["trace[342012764] 'process raft request' (duration: 392.306867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.358252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:24.965249Z","time spent":"392.951875ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:38:25.358301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:24.965633Z","time spent":"392.617775ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1623,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:38:25.358157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.46853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-09T22:38:25.358355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:24.964629Z","time spent":"393.551671ms","remote":"127.0.0.1:58458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1607,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:38:25.35845Z","caller":"traceutil/trace.go:171","msg":"trace[735924359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5396; }","duration":"362.773699ms","start":"2026-04-09T22:38:24.995616Z","end":"2026-04-09T22:38:25.35839Z","steps":["trace[735924359] 'agreement among raft nodes before linearized reading' (duration: 362.487321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.358707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:24.995595Z","time spent":"363.069887ms","remote":"127.0.0.1:58114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-09T22:38:25.358079Z","caller":"traceutil/trace.go:171","msg":"trace[892530163] linearizableReadLoop","detail":"{readStateIndex:5594; appliedIndex:5590; }","duration":"362.347197ms","start":"2026-04-09T22:38:24.995676Z","end":"2026-04-09T22:38:25.358023Z","steps":["trace[892530163] 'read index received' (duration: 75.742503ms)","trace[892530163] 'applied index is now lower than readState.Index' (duration: 286.603334ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:38:25.359095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.045648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-09T22:38:25.359192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.246398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-09T22:38:25.35923Z","caller":"traceutil/trace.go:171","msg":"trace[2061958076] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5396; }","duration":"239.30886ms","start":"2026-04-09T22:38:25.119911Z","end":"2026-04-09T22:38:25.35922Z","steps":["trace[2061958076] 'agreement among raft nodes before linearized reading' (duration: 239.172746ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.359195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.507248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-09T22:38:25.359292Z","caller":"traceutil/trace.go:171","msg":"trace[2124659139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5396; }","duration":"143.672482ms","start":"2026-04-09T22:38:25.215593Z","end":"2026-04-09T22:38:25.359265Z","steps":["trace[2124659139] 'agreement among raft nodes before linearized reading' (duration: 143.306182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.35934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.549226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-09T22:38:25.359384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.199275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-09T22:38:25.359399Z","caller":"traceutil/trace.go:171","msg":"trace[1488240848] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5396; }","duration":"220.632708ms","start":"2026-04-09T22:38:25.138747Z","end":"2026-04-09T22:38:25.35938Z","steps":["trace[1488240848] 'agreement among raft nodes before linearized reading' (duration: 220.393862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.359437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.213356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5588"} {"level":"info","ts":"2026-04-09T22:38:25.359414Z","caller":"traceutil/trace.go:171","msg":"trace[499766154] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5396; }","duration":"228.289308ms","start":"2026-04-09T22:38:25.131117Z","end":"2026-04-09T22:38:25.359407Z","steps":["trace[499766154] 'agreement among raft nodes before linearized reading' (duration: 228.222826ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.359505Z","caller":"traceutil/trace.go:171","msg":"trace[1033245419] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5396; }","duration":"281.288789ms","start":"2026-04-09T22:38:25.078196Z","end":"2026-04-09T22:38:25.359485Z","steps":["trace[1033245419] 'agreement among raft nodes before linearized reading' (duration: 281.028452ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.359188Z","caller":"traceutil/trace.go:171","msg":"trace[567390053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5396; }","duration":"332.15962ms","start":"2026-04-09T22:38:25.027003Z","end":"2026-04-09T22:38:25.359162Z","steps":["trace[567390053] 'agreement among raft nodes before linearized reading' (duration: 331.901283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.359555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:25.026991Z","time spent":"332.556452ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-09T22:38:25.359562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.085159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-09T22:38:25.359591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.935688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-04-09T22:38:25.359614Z","caller":"traceutil/trace.go:171","msg":"trace[168062804] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:5396; }","duration":"256.171791ms","start":"2026-04-09T22:38:25.103428Z","end":"2026-04-09T22:38:25.359599Z","steps":["trace[168062804] 'agreement among raft nodes before linearized reading' (duration: 256.063618ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.359632Z","caller":"traceutil/trace.go:171","msg":"trace[611939713] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5396; }","duration":"331.000819ms","start":"2026-04-09T22:38:25.028621Z","end":"2026-04-09T22:38:25.359622Z","steps":["trace[611939713] 'agreement among raft nodes before linearized reading' (duration: 330.879456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.35967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:25.028607Z","time spent":"331.053761ms","remote":"127.0.0.1:58282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":1803,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" "} {"level":"warn","ts":"2026-04-09T22:38:25.359946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.887881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5287ff56-a3da-4b95-a205-7811450343a0\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-04-09T22:38:25.360016Z","caller":"traceutil/trace.go:171","msg":"trace[1581480205] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5287ff56-a3da-4b95-a205-7811450343a0; range_end:; response_count:1; response_revision:5396; }","duration":"329.592341ms","start":"2026-04-09T22:38:25.030404Z","end":"2026-04-09T22:38:25.359996Z","steps":["trace[1581480205] 'agreement among raft nodes before linearized reading' (duration: 328.8128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:38:25.360059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:38:25.030393Z","time spent":"329.651222ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3788,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5287ff56-a3da-4b95-a205-7811450343a0\" "} {"level":"warn","ts":"2026-04-09T22:38:25.360676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.692794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-04-09T22:38:25.360758Z","caller":"traceutil/trace.go:171","msg":"trace[1386953758] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5396; }","duration":"151.925718ms","start":"2026-04-09T22:38:25.208807Z","end":"2026-04-09T22:38:25.360732Z","steps":["trace[1386953758] 'agreement among raft nodes before linearized reading' (duration: 150.51872ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.556499Z","caller":"traceutil/trace.go:171","msg":"trace[1949911725] transaction","detail":"{read_only:false; response_revision:5404; number_of_response:1; }","duration":"169.103458ms","start":"2026-04-09T22:38:25.387368Z","end":"2026-04-09T22:38:25.556471Z","steps":["trace[1949911725] 'process raft request' (duration: 159.68289ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.557021Z","caller":"traceutil/trace.go:171","msg":"trace[1228933836] transaction","detail":"{read_only:false; response_revision:5405; number_of_response:1; }","duration":"123.487519ms","start":"2026-04-09T22:38:25.433497Z","end":"2026-04-09T22:38:25.556985Z","steps":["trace[1228933836] 'process raft request' (duration: 123.201501ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.557127Z","caller":"traceutil/trace.go:171","msg":"trace[1390282645] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"122.986236ms","start":"2026-04-09T22:38:25.434113Z","end":"2026-04-09T22:38:25.557099Z","steps":["trace[1390282645] 'process raft request' (duration: 122.76901ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.557131Z","caller":"traceutil/trace.go:171","msg":"trace[200151118] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"122.891903ms","start":"2026-04-09T22:38:25.434196Z","end":"2026-04-09T22:38:25.557088Z","steps":["trace[200151118] 'process raft request' (duration: 122.750239ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.557568Z","caller":"traceutil/trace.go:171","msg":"trace[58310521] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"123.295735ms","start":"2026-04-09T22:38:25.43426Z","end":"2026-04-09T22:38:25.557555Z","steps":["trace[58310521] 'process raft request' (duration: 122.7638ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.557788Z","caller":"traceutil/trace.go:171","msg":"trace[713656813] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"123.445099ms","start":"2026-04-09T22:38:25.434333Z","end":"2026-04-09T22:38:25.557779Z","steps":["trace[713656813] 'process raft request' (duration: 123.142461ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:38:25.55803Z","caller":"traceutil/trace.go:171","msg":"trace[1632687011] transaction","detail":"{read_only:false; response_revision:5410; number_of_response:1; }","duration":"123.793858ms","start":"2026-04-09T22:38:25.434212Z","end":"2026-04-09T22:38:25.558006Z","steps":["trace[1632687011] 'process raft request' (duration: 123.460949ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:39:34.551087Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2969} {"level":"info","ts":"2026-04-09T22:39:34.628667Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2969,"took":"75.524605ms","hash":2102265215,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":33320960,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-09T22:39:34.628769Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2102265215,"revision":2969,"compact-revision":-1} {"level":"info","ts":"2026-04-09T22:39:48.237912Z","caller":"traceutil/trace.go:171","msg":"trace[958410676] linearizableReadLoop","detail":"{readStateIndex:6358; appliedIndex:6357; }","duration":"169.16477ms","start":"2026-04-09T22:39:48.068701Z","end":"2026-04-09T22:39:48.237865Z","steps":["trace[958410676] 'read index received' (duration: 168.513153ms)","trace[958410676] 'applied index is now lower than readState.Index' (duration: 649.007µs)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:39:48.238505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.770376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-04-09T22:39:48.238662Z","caller":"traceutil/trace.go:171","msg":"trace[1525331889] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:6140; }","duration":"170.006833ms","start":"2026-04-09T22:39:48.068614Z","end":"2026-04-09T22:39:48.238621Z","steps":["trace[1525331889] 'agreement among raft nodes before linearized reading' (duration: 169.468228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:39:48.239488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.370511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:1 size:6094"} {"level":"info","ts":"2026-04-09T22:39:48.23972Z","caller":"traceutil/trace.go:171","msg":"trace[104583513] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:1; response_revision:6140; }","duration":"167.545566ms","start":"2026-04-09T22:39:48.07204Z","end":"2026-04-09T22:39:48.239585Z","steps":["trace[104583513] 'agreement among raft nodes before linearized reading' (duration: 167.285709ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:30.908413Z","caller":"traceutil/trace.go:171","msg":"trace[1984741341] transaction","detail":"{read_only:false; response_revision:7276; number_of_response:1; }","duration":"199.725207ms","start":"2026-04-09T22:42:30.708663Z","end":"2026-04-09T22:42:30.908388Z","steps":["trace[1984741341] 'process raft request' (duration: 199.582013ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.153297Z","caller":"traceutil/trace.go:171","msg":"trace[1259226533] transaction","detail":"{read_only:false; response_revision:7278; number_of_response:1; }","duration":"234.61663ms","start":"2026-04-09T22:42:30.918634Z","end":"2026-04-09T22:42:31.15325Z","steps":["trace[1259226533] 'process raft request' (duration: 234.451616ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.153379Z","caller":"traceutil/trace.go:171","msg":"trace[496068425] transaction","detail":"{read_only:false; response_revision:7279; number_of_response:1; }","duration":"122.540891ms","start":"2026-04-09T22:42:31.030817Z","end":"2026-04-09T22:42:31.153358Z","steps":["trace[496068425] 'process raft request' (duration: 122.356576ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:31.153379Z","caller":"traceutil/trace.go:171","msg":"trace[691414093] transaction","detail":"{read_only:false; response_revision:7277; number_of_response:1; }","duration":"444.195722ms","start":"2026-04-09T22:42:30.709144Z","end":"2026-04-09T22:42:31.15334Z","steps":["trace[691414093] 'process raft request' (duration: 399.121633ms)","trace[691414093] 'compare' (duration: 44.589895ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:42:31.153452Z","caller":"traceutil/trace.go:171","msg":"trace[909662264] linearizableReadLoop","detail":"{readStateIndex:7535; appliedIndex:7533; }","duration":"246.615745ms","start":"2026-04-09T22:42:30.906772Z","end":"2026-04-09T22:42:31.153388Z","steps":["trace[909662264] 'read index received' (duration: 1.477971ms)","trace[909662264] 'applied index is now lower than readState.Index' (duration: 245.131694ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:42:31.153687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:30.709131Z","time spent":"444.45757ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:42:31.153717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.923744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:42:31.1538Z","caller":"traceutil/trace.go:171","msg":"trace[1303792393] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7279; }","duration":"247.059378ms","start":"2026-04-09T22:42:30.906716Z","end":"2026-04-09T22:42:31.153775Z","steps":["trace[1303792393] 'agreement among raft nodes before linearized reading' (duration: 246.77128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.157393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.523505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T22:42:31.15745Z","caller":"traceutil/trace.go:171","msg":"trace[1548701478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7280; }","duration":"250.651788ms","start":"2026-04-09T22:42:30.906785Z","end":"2026-04-09T22:42:31.157436Z","steps":["trace[1548701478] 'agreement among raft nodes before linearized reading' (duration: 250.443292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.157792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.575082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-09T22:42:31.157846Z","caller":"traceutil/trace.go:171","msg":"trace[1021347660] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:7280; }","duration":"180.687855ms","start":"2026-04-09T22:42:30.977143Z","end":"2026-04-09T22:42:31.157831Z","steps":["trace[1021347660] 'agreement among raft nodes before linearized reading' (duration: 180.576252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.158102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.590484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:42:31.158142Z","caller":"traceutil/trace.go:171","msg":"trace[32314431] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:7280; }","duration":"145.655757ms","start":"2026-04-09T22:42:31.012477Z","end":"2026-04-09T22:42:31.158132Z","steps":["trace[32314431] 'agreement among raft nodes before linearized reading' (duration: 145.586265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.158255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.843802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-04-09T22:42:31.15829Z","caller":"traceutil/trace.go:171","msg":"trace[739747946] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7280; }","duration":"203.897333ms","start":"2026-04-09T22:42:30.95438Z","end":"2026-04-09T22:42:31.158277Z","steps":["trace[739747946] 'agreement among raft nodes before linearized reading' (duration: 203.80278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:31.158637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.417058ms","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-04-09T22:42:31.158691Z","caller":"traceutil/trace.go:171","msg":"trace[2096994554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7280; }","duration":"247.49448ms","start":"2026-04-09T22:42:30.911188Z","end":"2026-04-09T22:42:31.158682Z","steps":["trace[2096994554] 'agreement among raft nodes before linearized reading' (duration: 247.216962ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:42:33.905214Z","caller":"traceutil/trace.go:171","msg":"trace[1950336053] linearizableReadLoop","detail":"{readStateIndex:7550; appliedIndex:7549; }","duration":"271.006777ms","start":"2026-04-09T22:42:33.634184Z","end":"2026-04-09T22:42:33.905191Z","steps":["trace[1950336053] 'read index received' (duration: 265.03467ms)","trace[1950336053] 'applied index is now lower than readState.Index' (duration: 5.967096ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:42:33.905426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.204122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/podmonitors/\" range_end:\"/registry/monitoring.coreos.com/podmonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:42:33.905466Z","caller":"traceutil/trace.go:171","msg":"trace[313035309] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:7291; }","duration":"271.311945ms","start":"2026-04-09T22:42:33.634144Z","end":"2026-04-09T22:42:33.905456Z","steps":["trace[313035309] 'agreement among raft nodes before linearized reading' (duration: 271.209652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:33.936915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.794216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-09T22:42:33.936998Z","caller":"traceutil/trace.go:171","msg":"trace[58242990] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:7295; }","duration":"301.94146ms","start":"2026-04-09T22:42:33.635038Z","end":"2026-04-09T22:42:33.93698Z","steps":["trace[58242990] 'agreement among raft nodes before linearized reading' (duration: 301.743355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:42:33.937037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:42:33.635022Z","time spent":"302.006003ms","remote":"127.0.0.1:45902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":1,"response size":29,"request content":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true "} {"level":"info","ts":"2026-04-09T22:44:21.688112Z","caller":"traceutil/trace.go:171","msg":"trace[2133458983] transaction","detail":"{read_only:false; response_revision:8170; number_of_response:1; }","duration":"186.359435ms","start":"2026-04-09T22:44:21.501704Z","end":"2026-04-09T22:44:21.688064Z","steps":["trace[2133458983] 'process raft request' (duration: 186.072567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:44:33.815035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.115143ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-09T22:44:34.612368Z","caller":"traceutil/trace.go:171","msg":"trace[816897786] transaction","detail":"{read_only:false; response_revision:8235; number_of_response:1; }","duration":"159.094482ms","start":"2026-04-09T22:44:34.453241Z","end":"2026-04-09T22:44:34.612335Z","steps":["trace[816897786] 'process raft request' (duration: 158.892457ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:34.633421Z","caller":"traceutil/trace.go:171","msg":"trace[1659227874] transaction","detail":"{read_only:false; response_revision:8236; number_of_response:1; }","duration":"173.315439ms","start":"2026-04-09T22:44:34.460085Z","end":"2026-04-09T22:44:34.633401Z","steps":["trace[1659227874] 'process raft request' (duration: 173.071782ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:34.633412Z","caller":"traceutil/trace.go:171","msg":"trace[1655829357] transaction","detail":"{read_only:false; response_revision:8237; number_of_response:1; }","duration":"172.62728ms","start":"2026-04-09T22:44:34.460759Z","end":"2026-04-09T22:44:34.633386Z","steps":["trace[1655829357] 'process raft request' (duration: 172.535948ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:34.687844Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6023} {"level":"info","ts":"2026-04-09T22:44:34.875769Z","caller":"traceutil/trace.go:171","msg":"trace[1670780398] transaction","detail":"{read_only:false; response_revision:8242; number_of_response:1; }","duration":"143.715164ms","start":"2026-04-09T22:44:34.732014Z","end":"2026-04-09T22:44:34.875729Z","steps":["trace[1670780398] 'process raft request' (duration: 68.64772ms)","trace[1670780398] 'compare' (duration: 20.324503ms)","trace[1670780398] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 54.551316ms)"],"step_count":3} {"level":"info","ts":"2026-04-09T22:44:34.87601Z","caller":"traceutil/trace.go:171","msg":"trace[564710235] linearizableReadLoop","detail":"{readStateIndex:8533; appliedIndex:8530; }","duration":"137.868724ms","start":"2026-04-09T22:44:34.738132Z","end":"2026-04-09T22:44:34.876Z","steps":["trace[564710235] 'read index received' (duration: 1.208473ms)","trace[564710235] 'applied index is now lower than readState.Index' (duration: 136.658941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-09T22:44:34.876194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.043469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5g4z5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T22:44:34.876245Z","caller":"traceutil/trace.go:171","msg":"trace[1129539754] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5g4z5; range_end:; response_count:1; response_revision:8243; }","duration":"138.122671ms","start":"2026-04-09T22:44:34.7381Z","end":"2026-04-09T22:44:34.876222Z","steps":["trace[1129539754] 'agreement among raft nodes before linearized reading' (duration: 137.944486ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:34.876325Z","caller":"traceutil/trace.go:171","msg":"trace[1359148291] transaction","detail":"{read_only:false; response_revision:8243; number_of_response:1; }","duration":"144.181856ms","start":"2026-04-09T22:44:34.732111Z","end":"2026-04-09T22:44:34.876293Z","steps":["trace[1359148291] 'process raft request' (duration: 143.556859ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:44:35.068721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6023,"took":"378.016173ms","hash":1180772969,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":23556096,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-09T22:44:35.068867Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1180772969,"revision":6023,"compact-revision":2969} {"level":"info","ts":"2026-04-09T22:45:02.934188Z","caller":"traceutil/trace.go:171","msg":"trace[363294247] transaction","detail":"{read_only:false; response_revision:8440; number_of_response:1; }","duration":"211.643733ms","start":"2026-04-09T22:45:02.722526Z","end":"2026-04-09T22:45:02.93417Z","steps":["trace[363294247] 'process raft request' (duration: 211.494439ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:03.101634Z","caller":"traceutil/trace.go:171","msg":"trace[1032564998] linearizableReadLoop","detail":"{readStateIndex:8739; appliedIndex:8736; }","duration":"209.543536ms","start":"2026-04-09T22:45:02.892067Z","end":"2026-04-09T22:45:03.10161Z","steps":["trace[1032564998] 'read index received' (duration: 42.020454ms)","trace[1032564998] 'applied index is now lower than readState.Index' (duration: 167.521392ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:45:03.101725Z","caller":"traceutil/trace.go:171","msg":"trace[1064678445] transaction","detail":"{read_only:false; response_revision:8441; number_of_response:1; }","duration":"371.641831ms","start":"2026-04-09T22:45:02.730036Z","end":"2026-04-09T22:45:03.101678Z","steps":["trace[1064678445] 'process raft request' (duration: 362.265425ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:03.10178Z","caller":"traceutil/trace.go:171","msg":"trace[1755933097] transaction","detail":"{read_only:false; response_revision:8442; number_of_response:1; }","duration":"361.811172ms","start":"2026-04-09T22:45:02.739928Z","end":"2026-04-09T22:45:03.10174Z","steps":["trace[1755933097] 'process raft request' (duration: 361.638748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:03.101881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.785112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T22:45:03.101916Z","caller":"traceutil/trace.go:171","msg":"trace[663654134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8442; }","duration":"209.871445ms","start":"2026-04-09T22:45:02.892037Z","end":"2026-04-09T22:45:03.101908Z","steps":["trace[663654134] 'agreement among raft nodes before linearized reading' (duration: 209.712511ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:03.101935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:02.730029Z","time spent":"371.786984ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:45:03.101999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:02.739919Z","time spent":"361.960866ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:45:03.102093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.849754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-09T22:45:03.102256Z","caller":"traceutil/trace.go:171","msg":"trace[1803283067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8442; }","duration":"210.029899ms","start":"2026-04-09T22:45:02.892165Z","end":"2026-04-09T22:45:03.102195Z","steps":["trace[1803283067] 'agreement among raft nodes before linearized reading' (duration: 209.69268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:03.102417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.999693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:45:03.102461Z","caller":"traceutil/trace.go:171","msg":"trace[75427247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8442; }","duration":"176.189228ms","start":"2026-04-09T22:45:02.926262Z","end":"2026-04-09T22:45:03.102452Z","steps":["trace[75427247] 'agreement among raft nodes before linearized reading' (duration: 176.095205ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:16.76796Z","caller":"traceutil/trace.go:171","msg":"trace[972083974] transaction","detail":"{read_only:false; response_revision:8561; number_of_response:1; }","duration":"121.579181ms","start":"2026-04-09T22:45:16.646356Z","end":"2026-04-09T22:45:16.767935Z","steps":["trace[972083974] 'process raft request' (duration: 121.345395ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:30.12528Z","caller":"traceutil/trace.go:171","msg":"trace[1038444240] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"105.085971ms","start":"2026-04-09T22:45:30.02017Z","end":"2026-04-09T22:45:30.125256Z","steps":["trace[1038444240] 'process raft request' (duration: 104.808124ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:35.458292Z","caller":"traceutil/trace.go:171","msg":"trace[2040541334] transaction","detail":"{read_only:false; response_revision:8778; number_of_response:1; }","duration":"401.539074ms","start":"2026-04-09T22:45:35.056727Z","end":"2026-04-09T22:45:35.458266Z","steps":["trace[2040541334] 'process raft request' (duration: 401.294548ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.458447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.056707Z","time spent":"401.655298ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-09T22:45:35.752966Z","caller":"traceutil/trace.go:171","msg":"trace[1936853346] linearizableReadLoop","detail":"{readStateIndex:9090; appliedIndex:9087; }","duration":"407.96072ms","start":"2026-04-09T22:45:35.344971Z","end":"2026-04-09T22:45:35.752931Z","steps":["trace[1936853346] 'read index received' (duration: 113.09288ms)","trace[1936853346] 'applied index is now lower than readState.Index' (duration: 294.8668ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:45:35.753283Z","caller":"traceutil/trace.go:171","msg":"trace[1499486789] transaction","detail":"{read_only:false; response_revision:8779; number_of_response:1; }","duration":"459.445282ms","start":"2026-04-09T22:45:35.293826Z","end":"2026-04-09T22:45:35.753272Z","steps":["trace[1499486789] 'process raft request' (duration: 458.898387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.293801Z","time spent":"459.517493ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:45:35.75362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.991806ms","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-04-09T22:45:35.753603Z","caller":"traceutil/trace.go:171","msg":"trace[438591690] transaction","detail":"{read_only:false; response_revision:8780; number_of_response:1; }","duration":"458.050964ms","start":"2026-04-09T22:45:35.295511Z","end":"2026-04-09T22:45:35.753562Z","steps":["trace[438591690] 'process raft request' (duration: 457.375686ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.658957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-04-09T22:45:35.75374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.953858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-5g4z5\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-04-09T22:45:35.753794Z","caller":"traceutil/trace.go:171","msg":"trace[2080451890] range","detail":"{range_begin:/registry/pods/openstack/keepalived-5g4z5; range_end:; response_count:1; response_revision:8780; }","duration":"376.0307ms","start":"2026-04-09T22:45:35.377755Z","end":"2026-04-09T22:45:35.753786Z","steps":["trace[2080451890] 'agreement among raft nodes before linearized reading' (duration: 375.928077ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:45:35.753807Z","caller":"traceutil/trace.go:171","msg":"trace[1206897331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8780; }","duration":"383.789511ms","start":"2026-04-09T22:45:35.369996Z","end":"2026-04-09T22:45:35.753786Z","steps":["trace[1206897331] 'agreement among raft nodes before linearized reading' (duration: 383.468212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.295488Z","time spent":"458.235508ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-09T22:45:35.753805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.748213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-04-09T22:45:35.753877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.369943Z","time spent":"383.919665ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-09T22:45:35.753661Z","caller":"traceutil/trace.go:171","msg":"trace[963801292] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8780; }","duration":"377.051408ms","start":"2026-04-09T22:45:35.376598Z","end":"2026-04-09T22:45:35.753649Z","steps":["trace[963801292] 'agreement among raft nodes before linearized reading' (duration: 376.929205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.753981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.376587Z","time spent":"377.363476ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-09T22:45:35.753827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.377738Z","time spent":"376.083382ms","remote":"127.0.0.1:58328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6546,"request content":"key:\"/registry/pods/openstack/keepalived-5g4z5\" "} {"level":"info","ts":"2026-04-09T22:45:35.753911Z","caller":"traceutil/trace.go:171","msg":"trace[1751785134] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8780; }","duration":"382.878527ms","start":"2026-04-09T22:45:35.371004Z","end":"2026-04-09T22:45:35.753883Z","steps":["trace[1751785134] 'agreement among raft nodes before linearized reading' (duration: 382.569618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.754154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.370991Z","time spent":"383.145003ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-09T22:45:35.754074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.113002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-09T22:45:35.754444Z","caller":"traceutil/trace.go:171","msg":"trace[20648956] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8780; }","duration":"376.585855ms","start":"2026-04-09T22:45:35.377823Z","end":"2026-04-09T22:45:35.754409Z","steps":["trace[20648956] 'agreement among raft nodes before linearized reading' (duration: 375.713681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.754772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.377803Z","time spent":"376.958615ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-09T22:45:35.754124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.133371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-09T22:45:35.755232Z","caller":"traceutil/trace.go:171","msg":"trace[1027825974] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8780; }","duration":"410.287563ms","start":"2026-04-09T22:45:35.344913Z","end":"2026-04-09T22:45:35.755201Z","steps":["trace[1027825974] 'agreement among raft nodes before linearized reading' (duration: 408.832844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-09T22:45:35.75532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-09T22:45:35.3449Z","time spent":"410.398156ms","remote":"127.0.0.1:58412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-09T22:45:38.072026Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023a0.wal"} {"level":"info","ts":"2026-04-09T22:46:32.110814Z","caller":"traceutil/trace.go:171","msg":"trace[1626312426] transaction","detail":"{read_only:false; response_revision:9300; number_of_response:1; }","duration":"136.416828ms","start":"2026-04-09T22:46:31.974352Z","end":"2026-04-09T22:46:32.110768Z","steps":["trace[1626312426] 'process raft request' (duration: 136.159681ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:46:32.222447Z","caller":"traceutil/trace.go:171","msg":"trace[1493424710] transaction","detail":"{read_only:false; response_revision:9302; number_of_response:1; }","duration":"105.705451ms","start":"2026-04-09T22:46:32.116732Z","end":"2026-04-09T22:46:32.222438Z","steps":["trace[1493424710] 'process raft request' (duration: 105.459174ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:46:32.222699Z","caller":"traceutil/trace.go:171","msg":"trace[897457270] transaction","detail":"{read_only:false; response_revision:9303; number_of_response:1; }","duration":"105.899396ms","start":"2026-04-09T22:46:32.116778Z","end":"2026-04-09T22:46:32.222677Z","steps":["trace[897457270] 'process raft request' (duration: 105.619239ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:46:32.222322Z","caller":"traceutil/trace.go:171","msg":"trace[313885896] transaction","detail":"{read_only:false; response_revision:9301; number_of_response:1; }","duration":"106.150523ms","start":"2026-04-09T22:46:32.116122Z","end":"2026-04-09T22:46:32.222272Z","steps":["trace[313885896] 'process raft request' (duration: 65.869876ms)","trace[313885896] 'compare' (duration: 39.980969ms)"],"step_count":2} {"level":"info","ts":"2026-04-09T22:46:32.222987Z","caller":"traceutil/trace.go:171","msg":"trace[1958223248] transaction","detail":"{read_only:false; response_revision:9305; number_of_response:1; }","duration":"105.354802ms","start":"2026-04-09T22:46:32.117599Z","end":"2026-04-09T22:46:32.222953Z","steps":["trace[1958223248] 'process raft request' (duration: 105.261959ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:46:32.222996Z","caller":"traceutil/trace.go:171","msg":"trace[163345371] transaction","detail":"{read_only:false; response_revision:9304; number_of_response:1; }","duration":"105.886186ms","start":"2026-04-09T22:46:32.117079Z","end":"2026-04-09T22:46:32.222965Z","steps":["trace[163345371] 'process raft request' (duration: 105.475075ms)"],"step_count":1} {"level":"info","ts":"2026-04-09T22:47:21.291197Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5ac240a6daec2148","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-09T22:47:21.296331Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-09T22:47:21.296458Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-09T22:49:34.696074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8238} {"level":"info","ts":"2026-04-09T22:49:34.776535Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8238,"took":"78.237906ms","hash":2354766784,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":24805376,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-09T22:49:34.776601Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2354766784,"revision":8238,"compact-revision":6023} {"level":"info","ts":"2026-04-09T22:54:34.701142Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10635} {"level":"info","ts":"2026-04-09T22:54:34.779719Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10635,"took":"76.328486ms","hash":34554073,"current-db-size-bytes":57065472,"current-db-size":"57 MB","current-db-size-in-use-bytes":20357120,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-04-09T22:54:34.779774Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":34554073,"revision":10635,"compact-revision":8238}