{"level":"warn","ts":"2026-05-06T05:04:28.635516Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:04:28.635642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.249: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.19.213.249:2380","--initial-cluster=instance=https://199.19.213.249:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.249:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.249: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-05-06T05:04:28.635755Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:04:28.635773Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-05-06T05:04:28.635795Z","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-05-06T05:04:28.636607Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"]} {"level":"info","ts":"2026-05-06T05:04:28.636734Z","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.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.249: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-05-06T05:04:28.640252Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.252132ms"} {"level":"info","ts":"2026-05-06T05:04:28.651995Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5835264b86215850","cluster-id":"4e10f3807bdbbd9b"} {"level":"info","ts":"2026-05-06T05:04:28.652199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T05:04:28.652249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 0"} {"level":"info","ts":"2026-05-06T05:04:28.652272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5835264b86215850 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T05:04:28.652317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became follower at term 1"} {"level":"info","ts":"2026-05-06T05:04:28.652391Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"warn","ts":"2026-05-06T05:04:28.657672Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T05:04:28.659417Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T05:04:28.660943Z","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-05-06T05:04:28.664072Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5835264b86215850","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T05:04:28.664293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5835264b86215850","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T05:04:28.66449Z","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-05-06T05:04:28.664616Z","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-05-06T05:04:28.664646Z","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-05-06T05:04:28.66596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 switched to configuration voters=(6356028554918058064)"} {"level":"info","ts":"2026-05-06T05:04:28.666256Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","added-peer-id":"5835264b86215850","added-peer-peer-urls":["https://199.19.213.249:2380"]} {"level":"info","ts":"2026-05-06T05:04:28.670166Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-05-06T05:04:28.670046Z","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-05-06T05:04:28.67021Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.249:2380"} {"level":"info","ts":"2026-05-06T05:04:28.67097Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5835264b86215850","initial-advertise-peer-urls":["https://199.19.213.249:2380"],"listen-peer-urls":["https://199.19.213.249:2380"],"advertise-client-urls":["https://199.19.213.249:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.249:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T05:04:28.671022Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T05:04:29.253225Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T05:04:29.253287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T05:04:29.253342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgPreVoteResp from 5835264b86215850 at term 1"} {"level":"info","ts":"2026-05-06T05:04:29.253372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became candidate at term 2"} {"level":"info","ts":"2026-05-06T05:04:29.253414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 received MsgVoteResp from 5835264b86215850 at term 2"} {"level":"info","ts":"2026-05-06T05:04:29.253458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5835264b86215850 became leader at term 2"} {"level":"info","ts":"2026-05-06T05:04:29.253483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5835264b86215850 elected leader 5835264b86215850 at term 2"} {"level":"info","ts":"2026-05-06T05:04:29.254618Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:29.255895Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:04:29.255965Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5835264b86215850","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.249:2379]}","request-path":"/0/members/5835264b86215850/attributes","cluster-id":"4e10f3807bdbbd9b","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T05:04:29.255957Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:04:29.256405Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T05:04:29.256491Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T05:04:29.258876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T05:04:29.26048Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4e10f3807bdbbd9b","local-member-id":"5835264b86215850","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:29.260497Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.249:2379"} {"level":"info","ts":"2026-05-06T05:04:29.262391Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:04:29.262468Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:05:04.864386Z","caller":"traceutil/trace.go:171","msg":"trace[1020475849] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"152.48127ms","start":"2026-05-06T05:05:04.71188Z","end":"2026-05-06T05:05:04.864361Z","steps":["trace[1020475849] 'process raft request' (duration: 152.064391ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.097667Z","caller":"traceutil/trace.go:171","msg":"trace[949569471] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"173.989798ms","start":"2026-05-06T05:05:04.923634Z","end":"2026-05-06T05:05:05.097624Z","steps":["trace[949569471] 'process raft request' (duration: 173.906027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.097721Z","caller":"traceutil/trace.go:171","msg":"trace[1200454461] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"200.647457ms","start":"2026-05-06T05:05:04.897002Z","end":"2026-05-06T05:05:05.097649Z","steps":["trace[1200454461] 'process raft request' (duration: 176.205256ms)","trace[1200454461] 'compare' (duration: 24.119274ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:05.097717Z","caller":"traceutil/trace.go:171","msg":"trace[1455082828] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"178.285072ms","start":"2026-05-06T05:05:04.919397Z","end":"2026-05-06T05:05:05.097682Z","steps":["trace[1455082828] 'read index received' (duration: 153.906652ms)","trace[1455082828] 'applied index is now lower than readState.Index' (duration: 24.374919ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:05.09798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.542787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-06T05:05:05.098098Z","caller":"traceutil/trace.go:171","msg":"trace[316313683] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"178.731111ms","start":"2026-05-06T05:05:04.91935Z","end":"2026-05-06T05:05:05.098081Z","steps":["trace[316313683] 'agreement among raft nodes before linearized reading' (duration: 178.412244ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:05.602169Z","caller":"traceutil/trace.go:171","msg":"trace[1101133804] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"401.828904ms","start":"2026-05-06T05:05:05.200307Z","end":"2026-05-06T05:05:05.602136Z","steps":["trace[1101133804] 'process raft request' (duration: 370.409151ms)","trace[1101133804] 'compare' (duration: 31.165228ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:05.60262Z","caller":"traceutil/trace.go:171","msg":"trace[764428774] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"227.429719ms","start":"2026-05-06T05:05:05.374641Z","end":"2026-05-06T05:05:05.602071Z","steps":["trace[764428774] 'read index received' (duration: 196.17498ms)","trace[764428774] 'applied index is now lower than readState.Index' (duration: 31.253679ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:05.602667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:05.200271Z","time spent":"401.970497ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:05:05.602701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.027571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:05:05.603106Z","caller":"traceutil/trace.go:171","msg":"trace[105031010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:575; }","duration":"228.4479ms","start":"2026-05-06T05:05:05.374601Z","end":"2026-05-06T05:05:05.603049Z","steps":["trace[105031010] 'agreement among raft nodes before linearized reading' (duration: 227.628882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:05.604171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.891773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-06T05:05:05.60432Z","caller":"traceutil/trace.go:171","msg":"trace[750277552] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"219.073836ms","start":"2026-05-06T05:05:05.385213Z","end":"2026-05-06T05:05:05.604287Z","steps":["trace[750277552] 'agreement among raft nodes before linearized reading' (duration: 218.708168ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:50.152668Z","caller":"traceutil/trace.go:171","msg":"trace[1794250068] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"293.227346ms","start":"2026-05-06T05:05:49.859415Z","end":"2026-05-06T05:05:50.152643Z","steps":["trace[1794250068] 'process raft request' (duration: 293.005902ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:50.192193Z","caller":"traceutil/trace.go:171","msg":"trace[1241374854] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"331.700251ms","start":"2026-05-06T05:05:49.860448Z","end":"2026-05-06T05:05:50.192148Z","steps":["trace[1241374854] 'process raft request' (duration: 331.445745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:50.192486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:49.860429Z","time spent":"331.906026ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:36.413919Z","caller":"traceutil/trace.go:171","msg":"trace[1748989433] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"137.719577ms","start":"2026-05-06T05:06:36.27614Z","end":"2026-05-06T05:06:36.413859Z","steps":["trace[1748989433] 'process raft request' (duration: 137.457861ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:36.836327Z","caller":"traceutil/trace.go:171","msg":"trace[363396775] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"136.064819ms","start":"2026-05-06T05:06:36.700233Z","end":"2026-05-06T05:06:36.836298Z","steps":["trace[363396775] 'process raft request' (duration: 135.869994ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:36.869207Z","caller":"traceutil/trace.go:171","msg":"trace[83454911] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"164.939073ms","start":"2026-05-06T05:06:36.70423Z","end":"2026-05-06T05:06:36.869169Z","steps":["trace[83454911] 'process raft request' (duration: 164.674917ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:45.607049Z","caller":"traceutil/trace.go:171","msg":"trace[1576314765] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"121.522171ms","start":"2026-05-06T05:06:45.485489Z","end":"2026-05-06T05:06:45.607011Z","steps":["trace[1576314765] 'process raft request' (duration: 96.799859ms)","trace[1576314765] 'compare' (duration: 24.574799ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:22.268454Z","caller":"traceutil/trace.go:171","msg":"trace[663134818] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"202.017009ms","start":"2026-05-06T05:07:22.066408Z","end":"2026-05-06T05:07:22.268425Z","steps":["trace[663134818] 'process raft request' (duration: 115.231107ms)","trace[663134818] 'compare' (duration: 86.65364ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:22.4606Z","caller":"traceutil/trace.go:171","msg":"trace[1300434918] transaction","detail":"{read_only:false; response_revision:2100; number_of_response:1; }","duration":"160.925693ms","start":"2026-05-06T05:07:22.299603Z","end":"2026-05-06T05:07:22.460529Z","steps":["trace[1300434918] 'process raft request' (duration: 69.03002ms)","trace[1300434918] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/ingress-nginx/ingress-nginx-leader; req_size:472; } (duration: 91.593107ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:08:44.327331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.174682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:08:44.327421Z","caller":"traceutil/trace.go:171","msg":"trace[71703625] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:2736; }","duration":"151.306586ms","start":"2026-05-06T05:08:44.176095Z","end":"2026-05-06T05:08:44.327402Z","steps":["trace[71703625] 'count revisions from in-memory index tree' (duration: 151.098701ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:08:44.327827Z","caller":"traceutil/trace.go:171","msg":"trace[486821897] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"129.823985ms","start":"2026-05-06T05:08:44.197969Z","end":"2026-05-06T05:08:44.327793Z","steps":["trace[486821897] 'process raft request' (duration: 80.255923ms)","trace[486821897] 'compare' (duration: 49.0198ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:12:46.436605Z","caller":"traceutil/trace.go:171","msg":"trace[1310570866] transaction","detail":"{read_only:false; response_revision:4639; number_of_response:1; }","duration":"113.508707ms","start":"2026-05-06T05:12:46.323064Z","end":"2026-05-06T05:12:46.436573Z","steps":["trace[1310570866] 'process raft request' (duration: 113.453466ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:46.436648Z","caller":"traceutil/trace.go:171","msg":"trace[180580414] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"165.736161ms","start":"2026-05-06T05:12:46.270877Z","end":"2026-05-06T05:12:46.436613Z","steps":["trace[180580414] 'process raft request' (duration: 165.388323ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:46.436716Z","caller":"traceutil/trace.go:171","msg":"trace[108129351] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"162.239696ms","start":"2026-05-06T05:12:46.274418Z","end":"2026-05-06T05:12:46.436658Z","steps":["trace[108129351] 'process raft request' (duration: 162.029111ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:54.189498Z","caller":"traceutil/trace.go:171","msg":"trace[1481244775] transaction","detail":"{read_only:false; response_revision:4890; number_of_response:1; }","duration":"109.466078ms","start":"2026-05-06T05:12:54.080007Z","end":"2026-05-06T05:12:54.189473Z","steps":["trace[1481244775] 'process raft request' (duration: 109.316405ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.680736Z","caller":"traceutil/trace.go:171","msg":"trace[530829001] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5029; }","duration":"167.151092ms","start":"2026-05-06T05:12:57.51356Z","end":"2026-05-06T05:12:57.680711Z","steps":["trace[530829001] 'process raft request' (duration: 167.06569ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.718531Z","caller":"traceutil/trace.go:171","msg":"trace[530423903] linearizableReadLoop","detail":"{readStateIndex:5222; appliedIndex:5221; }","duration":"123.370711ms","start":"2026-05-06T05:12:57.595138Z","end":"2026-05-06T05:12:57.718509Z","steps":["trace[530423903] 'read index received' (duration: 85.527519ms)","trace[530423903] 'applied index is now lower than readState.Index' (duration: 37.841952ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:12:57.718715Z","caller":"traceutil/trace.go:171","msg":"trace[1175485547] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"154.096428ms","start":"2026-05-06T05:12:57.564559Z","end":"2026-05-06T05:12:57.718656Z","steps":["trace[1175485547] 'process raft request' (duration: 153.772331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:12:57.718769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.613366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-4t94h\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-05-06T05:12:57.718815Z","caller":"traceutil/trace.go:171","msg":"trace[1671377616] range","detail":"{range_begin:/registry/pods/openstack/keepalived-4t94h; range_end:; response_count:1; response_revision:5030; }","duration":"123.709348ms","start":"2026-05-06T05:12:57.595093Z","end":"2026-05-06T05:12:57.718802Z","steps":["trace[1671377616] 'agreement among raft nodes before linearized reading' (duration: 123.550655ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.890319Z","caller":"traceutil/trace.go:171","msg":"trace[771532494] linearizableReadLoop","detail":"{readStateIndex:5223; appliedIndex:5222; }","duration":"165.724862ms","start":"2026-05-06T05:12:57.724567Z","end":"2026-05-06T05:12:57.890292Z","steps":["trace[771532494] 'read index received' (duration: 94.2891ms)","trace[771532494] 'applied index is now lower than readState.Index' (duration: 71.434272ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:12:57.890413Z","caller":"traceutil/trace.go:171","msg":"trace[436663218] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"166.579061ms","start":"2026-05-06T05:12:57.723812Z","end":"2026-05-06T05:12:57.890391Z","steps":["trace[436663218] 'process raft request' (duration: 94.904233ms)","trace[436663218] 'compare' (duration: 71.172706ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:12:57.890657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.06543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16179"} {"level":"info","ts":"2026-05-06T05:12:57.890735Z","caller":"traceutil/trace.go:171","msg":"trace[722153150] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:5031; }","duration":"166.189982ms","start":"2026-05-06T05:12:57.724527Z","end":"2026-05-06T05:12:57.890717Z","steps":["trace[722153150] 'agreement among raft nodes before linearized reading' (duration: 165.938846ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.917659Z","caller":"traceutil/trace.go:171","msg":"trace[1802949864] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"191.662094ms","start":"2026-05-06T05:12:57.725943Z","end":"2026-05-06T05:12:57.917605Z","steps":["trace[1802949864] 'process raft request' (duration: 191.45805ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.917999Z","caller":"traceutil/trace.go:171","msg":"trace[811935602] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"189.960567ms","start":"2026-05-06T05:12:57.728012Z","end":"2026-05-06T05:12:57.917972Z","steps":["trace[811935602] 'process raft request' (duration: 189.534708ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:57.918323Z","caller":"traceutil/trace.go:171","msg":"trace[766785560] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"178.773404ms","start":"2026-05-06T05:12:57.739535Z","end":"2026-05-06T05:12:57.918309Z","steps":["trace[766785560] 'process raft request' (duration: 178.238553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:12:57.932249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.497848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:62865"} {"level":"info","ts":"2026-05-06T05:12:57.932312Z","caller":"traceutil/trace.go:171","msg":"trace[1335070327] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:5034; }","duration":"207.612351ms","start":"2026-05-06T05:12:57.724686Z","end":"2026-05-06T05:12:57.932298Z","steps":["trace[1335070327] 'agreement among raft nodes before linearized reading' (duration: 207.389546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:12:57.932588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.540836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77a6c126-0466-470d-acd0-f65462adbbea\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-05-06T05:12:57.932625Z","caller":"traceutil/trace.go:171","msg":"trace[1163018126] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77a6c126-0466-470d-acd0-f65462adbbea; range_end:; response_count:1; response_revision:5034; }","duration":"141.617438ms","start":"2026-05-06T05:12:57.790998Z","end":"2026-05-06T05:12:57.932615Z","steps":["trace[1163018126] 'agreement among raft nodes before linearized reading' (duration: 141.531796ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:58.207831Z","caller":"traceutil/trace.go:171","msg":"trace[1308229367] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5236; }","duration":"133.164024ms","start":"2026-05-06T05:12:58.074647Z","end":"2026-05-06T05:12:58.207811Z","steps":["trace[1308229367] 'read index received' (duration: 7.191376ms)","trace[1308229367] 'applied index is now lower than readState.Index' (duration: 125.972048ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:12:58.207897Z","caller":"traceutil/trace.go:171","msg":"trace[979840453] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"144.525061ms","start":"2026-05-06T05:12:58.063338Z","end":"2026-05-06T05:12:58.207863Z","steps":["trace[979840453] 'process raft request' (duration: 144.381458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:12:58.20794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.293447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:12:58.20797Z","caller":"traceutil/trace.go:171","msg":"trace[86282458] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5045; }","duration":"133.380869ms","start":"2026-05-06T05:12:58.074582Z","end":"2026-05-06T05:12:58.207963Z","steps":["trace[86282458] 'agreement among raft nodes before linearized reading' (duration: 133.324188ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:12:58.207963Z","caller":"traceutil/trace.go:171","msg":"trace[491327127] transaction","detail":"{read_only:false; response_revision:5044; number_of_response:1; }","duration":"151.677706ms","start":"2026-05-06T05:12:58.056248Z","end":"2026-05-06T05:12:58.207926Z","steps":["trace[491327127] 'process raft request' (duration: 151.348719ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:00.035891Z","caller":"traceutil/trace.go:171","msg":"trace[103960150] transaction","detail":"{read_only:false; response_revision:5071; number_of_response:1; }","duration":"166.949787ms","start":"2026-05-06T05:12:59.868918Z","end":"2026-05-06T05:13:00.035867Z","steps":["trace[103960150] 'process raft request' (duration: 156.882999ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:00.035923Z","caller":"traceutil/trace.go:171","msg":"trace[1900590497] linearizableReadLoop","detail":"{readStateIndex:5265; appliedIndex:5264; }","duration":"156.615494ms","start":"2026-05-06T05:12:59.879231Z","end":"2026-05-06T05:13:00.035846Z","steps":["trace[1900590497] 'read index received' (duration: 146.621997ms)","trace[1900590497] 'applied index is now lower than readState.Index' (duration: 9.992427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:13:00.036007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.739766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:13:00.036065Z","caller":"traceutil/trace.go:171","msg":"trace[968743811] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:5071; }","duration":"156.839188ms","start":"2026-05-06T05:12:59.879213Z","end":"2026-05-06T05:13:00.036052Z","steps":["trace[968743811] 'agreement among raft nodes before linearized reading' (duration: 156.650554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:00.036253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.507803ms","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-05-06T05:13:00.036303Z","caller":"traceutil/trace.go:171","msg":"trace[1195554336] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5071; }","duration":"140.650417ms","start":"2026-05-06T05:12:59.895641Z","end":"2026-05-06T05:13:00.036291Z","steps":["trace[1195554336] 'agreement among raft nodes before linearized reading' (duration: 140.442622ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:00.03651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.937301ms","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-05-06T05:13:00.036554Z","caller":"traceutil/trace.go:171","msg":"trace[246877109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5071; }","duration":"111.004332ms","start":"2026-05-06T05:12:59.925541Z","end":"2026-05-06T05:13:00.036545Z","steps":["trace[246877109] 'agreement among raft nodes before linearized reading' (duration: 110.87824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:00.036578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.92428ms","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-05-06T05:13:00.036673Z","caller":"traceutil/trace.go:171","msg":"trace[922549056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5071; }","duration":"111.057154ms","start":"2026-05-06T05:12:59.925596Z","end":"2026-05-06T05:13:00.036653Z","steps":["trace[922549056] 'agreement among raft nodes before linearized reading' (duration: 110.766208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:08.549218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.133064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:13:08.549315Z","caller":"traceutil/trace.go:171","msg":"trace[277986512] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5256; }","duration":"128.283657ms","start":"2026-05-06T05:13:08.421012Z","end":"2026-05-06T05:13:08.549296Z","steps":["trace[277986512] 'range keys from in-memory index tree' (duration: 127.958009ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.197687Z","caller":"traceutil/trace.go:171","msg":"trace[711455310] linearizableReadLoop","detail":"{readStateIndex:5642; appliedIndex:5641; }","duration":"157.511304ms","start":"2026-05-06T05:13:17.040143Z","end":"2026-05-06T05:13:17.197655Z","steps":["trace[711455310] 'read index received' (duration: 157.222226ms)","trace[711455310] 'applied index is now lower than readState.Index' (duration: 288.068µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:13:17.197726Z","caller":"traceutil/trace.go:171","msg":"trace[1240164374] transaction","detail":"{read_only:false; response_revision:5439; number_of_response:1; }","duration":"165.033147ms","start":"2026-05-06T05:13:17.032669Z","end":"2026-05-06T05:13:17.197702Z","steps":["trace[1240164374] 'process raft request' (duration: 164.798812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:17.197914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.748699ms","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-05-06T05:13:17.197961Z","caller":"traceutil/trace.go:171","msg":"trace[618077147] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5439; }","duration":"157.84024ms","start":"2026-05-06T05:13:17.040108Z","end":"2026-05-06T05:13:17.197949Z","steps":["trace[618077147] 'agreement among raft nodes before linearized reading' (duration: 157.671246ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.374402Z","caller":"traceutil/trace.go:171","msg":"trace[1179256308] linearizableReadLoop","detail":"{readStateIndex:5643; appliedIndex:5642; }","duration":"119.447116ms","start":"2026-05-06T05:13:17.254927Z","end":"2026-05-06T05:13:17.374374Z","steps":["trace[1179256308] 'read index received' (duration: 70.740427ms)","trace[1179256308] 'applied index is now lower than readState.Index' (duration: 48.705149ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:13:17.374683Z","caller":"traceutil/trace.go:171","msg":"trace[2118283961] transaction","detail":"{read_only:false; response_revision:5440; number_of_response:1; }","duration":"161.821756ms","start":"2026-05-06T05:13:17.212823Z","end":"2026-05-06T05:13:17.374644Z","steps":["trace[2118283961] 'process raft request' (duration: 112.738029ms)","trace[2118283961] 'compare' (duration: 48.658318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:13:17.374785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.788403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:13:17.374834Z","caller":"traceutil/trace.go:171","msg":"trace[1329523955] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:5440; }","duration":"119.971237ms","start":"2026-05-06T05:13:17.254849Z","end":"2026-05-06T05:13:17.37482Z","steps":["trace[1329523955] 'agreement among raft nodes before linearized reading' (duration: 119.65374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:17.612425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.933359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:13:17.613152Z","caller":"traceutil/trace.go:171","msg":"trace[1212478234] transaction","detail":"{read_only:false; response_revision:5442; number_of_response:1; }","duration":"248.353657ms","start":"2026-05-06T05:13:17.364751Z","end":"2026-05-06T05:13:17.613105Z","steps":["trace[1212478234] 'process raft request' (duration: 247.933618ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.613187Z","caller":"traceutil/trace.go:171","msg":"trace[1719425257] transaction","detail":"{read_only:false; response_revision:5441; number_of_response:1; }","duration":"325.224307ms","start":"2026-05-06T05:13:17.287924Z","end":"2026-05-06T05:13:17.613148Z","steps":["trace[1719425257] 'process raft request' (duration: 197.228995ms)","trace[1719425257] 'compare' (duration: 126.746404ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:13:17.613248Z","caller":"traceutil/trace.go:171","msg":"trace[63383509] transaction","detail":"{read_only:false; response_revision:5443; number_of_response:1; }","duration":"231.729655ms","start":"2026-05-06T05:13:17.381505Z","end":"2026-05-06T05:13:17.613234Z","steps":["trace[63383509] 'process raft request' (duration: 231.270916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:17.613366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:13:17.287878Z","time spent":"325.39038ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:13:17.613154Z","caller":"traceutil/trace.go:171","msg":"trace[439759945] transaction","detail":"{read_only:false; response_revision:5444; number_of_response:1; }","duration":"231.418429ms","start":"2026-05-06T05:13:17.381696Z","end":"2026-05-06T05:13:17.613115Z","steps":["trace[439759945] 'process raft request' (duration: 231.148923ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.613668Z","caller":"traceutil/trace.go:171","msg":"trace[1598353315] linearizableReadLoop","detail":"{readStateIndex:5645; appliedIndex:5643; }","duration":"237.568822ms","start":"2026-05-06T05:13:17.376069Z","end":"2026-05-06T05:13:17.613638Z","steps":["trace[1598353315] 'read index received' (duration: 109.09718ms)","trace[1598353315] 'applied index is now lower than readState.Index' (duration: 128.467502ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:13:17.613808Z","caller":"traceutil/trace.go:171","msg":"trace[292857045] transaction","detail":"{read_only:false; response_revision:5447; number_of_response:1; }","duration":"230.754913ms","start":"2026-05-06T05:13:17.383041Z","end":"2026-05-06T05:13:17.613796Z","steps":["trace[292857045] 'process raft request' (duration: 230.353385ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.613849Z","caller":"traceutil/trace.go:171","msg":"trace[2122002457] transaction","detail":"{read_only:false; response_revision:5448; number_of_response:1; }","duration":"230.774484ms","start":"2026-05-06T05:13:17.38306Z","end":"2026-05-06T05:13:17.613834Z","steps":["trace[2122002457] 'process raft request' (duration: 230.520949ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.613176Z","caller":"traceutil/trace.go:171","msg":"trace[393870509] transaction","detail":"{read_only:false; response_revision:5445; number_of_response:1; }","duration":"231.413729ms","start":"2026-05-06T05:13:17.381705Z","end":"2026-05-06T05:13:17.613118Z","steps":["trace[393870509] 'process raft request' (duration: 231.193154ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:17.61401Z","caller":"traceutil/trace.go:171","msg":"trace[202711649] transaction","detail":"{read_only:false; response_revision:5446; number_of_response:1; }","duration":"231.168783ms","start":"2026-05-06T05:13:17.382832Z","end":"2026-05-06T05:13:17.614001Z","steps":["trace[202711649] 'process raft request' (duration: 230.141091ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:13:17.613991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.891689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:13:17.614065Z","caller":"traceutil/trace.go:171","msg":"trace[453623869] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5448; }","duration":"238.038652ms","start":"2026-05-06T05:13:17.376009Z","end":"2026-05-06T05:13:17.614047Z","steps":["trace[453623869] 'agreement among raft nodes before linearized reading' (duration: 237.884009ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:14:29.284951Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2983} {"level":"info","ts":"2026-05-06T05:14:29.36325Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2983,"took":"77.100007ms","hash":3769098859,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":33931264,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T05:14:29.363337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3769098859,"revision":2983,"compact-revision":-1} {"level":"info","ts":"2026-05-06T05:19:22.184513Z","caller":"traceutil/trace.go:171","msg":"trace[1929945051] transaction","detail":"{read_only:false; response_revision:8261; number_of_response:1; }","duration":"106.799375ms","start":"2026-05-06T05:19:22.077698Z","end":"2026-05-06T05:19:22.184497Z","steps":["trace[1929945051] 'process raft request' (duration: 106.702593ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:19:22.186111Z","caller":"traceutil/trace.go:171","msg":"trace[1264681375] transaction","detail":"{read_only:false; response_revision:8262; number_of_response:1; }","duration":"106.195092ms","start":"2026-05-06T05:19:22.079877Z","end":"2026-05-06T05:19:22.186072Z","steps":["trace[1264681375] 'process raft request' (duration: 106.12072ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:19:29.29264Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6106} {"level":"info","ts":"2026-05-06T05:19:29.396965Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6106,"took":"101.354829ms","hash":4186729240,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":23810048,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-05-06T05:19:29.39706Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4186729240,"revision":6106,"compact-revision":2983} {"level":"info","ts":"2026-05-06T05:19:34.820672Z","caller":"traceutil/trace.go:171","msg":"trace[590615092] transaction","detail":"{read_only:false; response_revision:8330; number_of_response:1; }","duration":"286.222387ms","start":"2026-05-06T05:19:34.534406Z","end":"2026-05-06T05:19:34.820628Z","steps":["trace[590615092] 'process raft request' (duration: 285.754428ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:19:34.821082Z","caller":"traceutil/trace.go:171","msg":"trace[531679139] transaction","detail":"{read_only:false; response_revision:8329; number_of_response:1; }","duration":"287.289911ms","start":"2026-05-06T05:19:34.532927Z","end":"2026-05-06T05:19:34.820217Z","steps":["trace[531679139] 'process raft request' (duration: 221.602281ms)","trace[531679139] 'compare' (duration: 65.437915ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:20:03.552055Z","caller":"traceutil/trace.go:171","msg":"trace[1013268962] transaction","detail":"{read_only:false; response_revision:8538; number_of_response:1; }","duration":"317.543105ms","start":"2026-05-06T05:20:03.234493Z","end":"2026-05-06T05:20:03.552036Z","steps":["trace[1013268962] 'process raft request' (duration: 317.397742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:20:03.552192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:20:03.234468Z","time spent":"317.655647ms","remote":"127.0.0.1:52418","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-05-06T05:20:03.59747Z","caller":"traceutil/trace.go:171","msg":"trace[755053209] linearizableReadLoop","detail":"{readStateIndex:8843; appliedIndex:8840; }","duration":"119.602167ms","start":"2026-05-06T05:20:03.477846Z","end":"2026-05-06T05:20:03.597448Z","steps":["trace[755053209] 'read index received' (duration: 74.077408ms)","trace[755053209] 'applied index is now lower than readState.Index' (duration: 45.523779ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:20:03.597623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.76384ms","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-05-06T05:20:03.597661Z","caller":"traceutil/trace.go:171","msg":"trace[1682224177] transaction","detail":"{read_only:false; response_revision:8540; number_of_response:1; }","duration":"234.583377ms","start":"2026-05-06T05:20:03.363063Z","end":"2026-05-06T05:20:03.597646Z","steps":["trace[1682224177] 'process raft request' (duration: 234.343591ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:03.597666Z","caller":"traceutil/trace.go:171","msg":"trace[888053161] range","detail":"{range_begin:/registry/monitoring.coreos.com/podmonitors/; range_end:/registry/monitoring.coreos.com/podmonitors0; response_count:0; response_revision:8540; }","duration":"119.865954ms","start":"2026-05-06T05:20:03.47779Z","end":"2026-05-06T05:20:03.597656Z","steps":["trace[888053161] 'agreement among raft nodes before linearized reading' (duration: 119.74937ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:03.597963Z","caller":"traceutil/trace.go:171","msg":"trace[1458376104] transaction","detail":"{read_only:false; response_revision:8539; number_of_response:1; }","duration":"361.219176ms","start":"2026-05-06T05:20:03.236391Z","end":"2026-05-06T05:20:03.59761Z","steps":["trace[1458376104] 'process raft request' (duration: 360.901848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:20:03.598051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:20:03.236376Z","time spent":"361.613484ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:20:05.770326Z","caller":"traceutil/trace.go:171","msg":"trace[1466865442] transaction","detail":"{read_only:false; response_revision:8547; number_of_response:1; }","duration":"213.681522ms","start":"2026-05-06T05:20:05.556616Z","end":"2026-05-06T05:20:05.770298Z","steps":["trace[1466865442] 'process raft request' (duration: 213.484468ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:05.779053Z","caller":"traceutil/trace.go:171","msg":"trace[311849784] transaction","detail":"{read_only:false; response_revision:8548; number_of_response:1; }","duration":"177.121293ms","start":"2026-05-06T05:20:05.60184Z","end":"2026-05-06T05:20:05.778961Z","steps":["trace[311849784] 'process raft request' (duration: 176.925939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:05.779194Z","caller":"traceutil/trace.go:171","msg":"trace[2108210430] transaction","detail":"{read_only:false; response_revision:8549; number_of_response:1; }","duration":"176.234435ms","start":"2026-05-06T05:20:05.602943Z","end":"2026-05-06T05:20:05.779177Z","steps":["trace[2108210430] 'process raft request' (duration: 175.961529ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:17.168559Z","caller":"traceutil/trace.go:171","msg":"trace[1542907059] transaction","detail":"{read_only:false; response_revision:8656; number_of_response:1; }","duration":"102.072226ms","start":"2026-05-06T05:20:17.066461Z","end":"2026-05-06T05:20:17.168533Z","steps":["trace[1542907059] 'process raft request' (duration: 84.248356ms)","trace[1542907059] 'compare' (duration: 17.660186ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:20:17.783525Z","caller":"traceutil/trace.go:171","msg":"trace[32908990] transaction","detail":"{read_only:false; response_revision:8660; number_of_response:1; }","duration":"168.268265ms","start":"2026-05-06T05:20:17.615236Z","end":"2026-05-06T05:20:17.783504Z","steps":["trace[32908990] 'process raft request' (duration: 168.120362ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:17.92215Z","caller":"traceutil/trace.go:171","msg":"trace[427188529] transaction","detail":"{read_only:false; response_revision:8661; number_of_response:1; }","duration":"113.456618ms","start":"2026-05-06T05:20:17.808665Z","end":"2026-05-06T05:20:17.922122Z","steps":["trace[427188529] 'process raft request' (duration: 112.985338ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:17.922204Z","caller":"traceutil/trace.go:171","msg":"trace[60116433] transaction","detail":"{read_only:false; response_revision:8662; number_of_response:1; }","duration":"112.511637ms","start":"2026-05-06T05:20:17.809662Z","end":"2026-05-06T05:20:17.922174Z","steps":["trace[60116433] 'process raft request' (duration: 112.375974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:17.922409Z","caller":"traceutil/trace.go:171","msg":"trace[156347649] transaction","detail":"{read_only:false; response_revision:8663; number_of_response:1; }","duration":"103.119607ms","start":"2026-05-06T05:20:17.819258Z","end":"2026-05-06T05:20:17.922377Z","steps":["trace[156347649] 'process raft request' (duration: 102.864162ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:20:32.33004Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023c2.wal"} {"level":"info","ts":"2026-05-06T05:22:08.640292Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:22:08.646909Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-06T05:22:08.647069Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-06T05:24:29.29976Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8302} {"level":"info","ts":"2026-05-06T05:24:29.378383Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8302,"took":"76.417403ms","hash":75571839,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":25702400,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-06T05:24:29.378449Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":75571839,"revision":8302,"compact-revision":6106} {"level":"info","ts":"2026-05-06T05:25:14.471345Z","caller":"traceutil/trace.go:171","msg":"trace[955002143] linearizableReadLoop","detail":"{readStateIndex:11618; appliedIndex:11617; }","duration":"250.548037ms","start":"2026-05-06T05:25:14.220769Z","end":"2026-05-06T05:25:14.471317Z","steps":["trace[955002143] 'read index received' (duration: 250.395304ms)","trace[955002143] 'applied index is now lower than readState.Index' (duration: 151.503µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:25:14.471408Z","caller":"traceutil/trace.go:171","msg":"trace[913078267] transaction","detail":"{read_only:false; response_revision:11237; number_of_response:1; }","duration":"262.126894ms","start":"2026-05-06T05:25:14.209245Z","end":"2026-05-06T05:25:14.471372Z","steps":["trace[913078267] 'process raft request' (duration: 261.93683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:25:14.471589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.807252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-06T05:25:14.471625Z","caller":"traceutil/trace.go:171","msg":"trace[655610541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11237; }","duration":"250.903874ms","start":"2026-05-06T05:25:14.220712Z","end":"2026-05-06T05:25:14.471616Z","steps":["trace[655610541] 'agreement among raft nodes before linearized reading' (duration: 250.763771ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:25:14.471805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.999612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-06T05:25:14.471852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.713565ms","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-05-06T05:25:14.471957Z","caller":"traceutil/trace.go:171","msg":"trace[1916364414] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:11237; }","duration":"240.831148ms","start":"2026-05-06T05:25:14.231103Z","end":"2026-05-06T05:25:14.471934Z","steps":["trace[1916364414] 'agreement among raft nodes before linearized reading' (duration: 240.567642ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:25:14.472025Z","caller":"traceutil/trace.go:171","msg":"trace[1828259058] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11237; }","duration":"248.157065ms","start":"2026-05-06T05:25:14.223764Z","end":"2026-05-06T05:25:14.471921Z","steps":["trace[1828259058] 'agreement among raft nodes before linearized reading' (duration: 247.894979ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:25:14.588721Z","caller":"traceutil/trace.go:171","msg":"trace[1468680120] transaction","detail":"{read_only:false; response_revision:11238; number_of_response:1; }","duration":"112.437613ms","start":"2026-05-06T05:25:14.476257Z","end":"2026-05-06T05:25:14.588695Z","steps":["trace[1468680120] 'process raft request' (duration: 32.310081ms)","trace[1468680120] 'compare' (duration: 79.767115ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:25:14.58892Z","caller":"traceutil/trace.go:171","msg":"trace[2120652360] transaction","detail":"{read_only:false; response_revision:11239; number_of_response:1; }","duration":"112.050356ms","start":"2026-05-06T05:25:14.476849Z","end":"2026-05-06T05:25:14.5889Z","steps":["trace[2120652360] 'process raft request' (duration: 111.654636ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:25:14.588934Z","caller":"traceutil/trace.go:171","msg":"trace[1733294015] transaction","detail":"{read_only:false; response_revision:11240; number_of_response:1; }","duration":"110.393031ms","start":"2026-05-06T05:25:14.478524Z","end":"2026-05-06T05:25:14.588917Z","steps":["trace[1733294015] 'process raft request' (duration: 110.055003ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:29:29.307273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10792} {"level":"info","ts":"2026-05-06T05:29:29.386644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10792,"took":"76.523213ms","hash":4063710968,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":27848704,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-06T05:29:29.386712Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4063710968,"revision":10792,"compact-revision":8302} {"level":"info","ts":"2026-05-06T05:31:45.560366Z","caller":"traceutil/trace.go:171","msg":"trace[272488086] linearizableReadLoop","detail":"{readStateIndex:14458; appliedIndex:14457; }","duration":"149.760585ms","start":"2026-05-06T05:31:45.410577Z","end":"2026-05-06T05:31:45.560337Z","steps":["trace[272488086] 'read index received' (duration: 149.615252ms)","trace[272488086] 'applied index is now lower than readState.Index' (duration: 143.913µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:31:45.560407Z","caller":"traceutil/trace.go:171","msg":"trace[1848513948] transaction","detail":"{read_only:false; response_revision:13984; number_of_response:1; }","duration":"302.427652ms","start":"2026-05-06T05:31:45.257938Z","end":"2026-05-06T05:31:45.560366Z","steps":["trace[1848513948] 'process raft request' (duration: 302.249038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:31:45.560623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.506413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:31:45.560647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:31:45.25791Z","time spent":"302.584025ms","remote":"127.0.0.1:52260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:31:45.560672Z","caller":"traceutil/trace.go:171","msg":"trace[592870093] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:13984; }","duration":"138.625946ms","start":"2026-05-06T05:31:45.422032Z","end":"2026-05-06T05:31:45.560658Z","steps":["trace[592870093] 'agreement among raft nodes before linearized reading' (duration: 138.489823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:31:45.560925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.276867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7fe331d9-0cf1-40ae-ab0c-d9ec8addaedb\" ","response":"range_response_count:1 size:3607"} {"level":"info","ts":"2026-05-06T05:31:45.561083Z","caller":"traceutil/trace.go:171","msg":"trace[2099638639] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7fe331d9-0cf1-40ae-ab0c-d9ec8addaedb; range_end:; response_count:1; response_revision:13984; }","duration":"150.525482ms","start":"2026-05-06T05:31:45.410523Z","end":"2026-05-06T05:31:45.561048Z","steps":["trace[2099638639] 'agreement among raft nodes before linearized reading' (duration: 149.93526ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:34:29.320099Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12968} {"level":"info","ts":"2026-05-06T05:34:29.404942Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12968,"took":"80.838451ms","hash":2816539468,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31195136,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T05:34:29.405028Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2816539468,"revision":12968,"compact-revision":10792} {"level":"info","ts":"2026-05-06T05:39:29.326219Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15497} {"level":"info","ts":"2026-05-06T05:39:29.423456Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15497,"took":"94.043654ms","hash":1916392012,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":33714176,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T05:39:29.423523Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1916392012,"revision":15497,"compact-revision":12968} {"level":"info","ts":"2026-05-06T05:42:21.727047Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:42:21.731366Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-06T05:42:21.731461Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-05-06T05:43:53.410542Z","caller":"traceutil/trace.go:171","msg":"trace[1059956520] transaction","detail":"{read_only:false; response_revision:19953; number_of_response:1; }","duration":"144.069376ms","start":"2026-05-06T05:43:53.266439Z","end":"2026-05-06T05:43:53.410509Z","steps":["trace[1059956520] 'process raft request' (duration: 143.849441ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:43:53.57798Z","caller":"traceutil/trace.go:171","msg":"trace[74056785] transaction","detail":"{read_only:false; response_revision:19954; number_of_response:1; }","duration":"309.516329ms","start":"2026-05-06T05:43:53.268418Z","end":"2026-05-06T05:43:53.577935Z","steps":["trace[74056785] 'process raft request' (duration: 279.436565ms)","trace[74056785] 'compare' (duration: 29.932671ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:43:53.578224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:43:53.268406Z","time spent":"309.700214ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:43:54.343282Z","caller":"traceutil/trace.go:171","msg":"trace[1923710730] linearizableReadLoop","detail":"{readStateIndex:20623; appliedIndex:20622; }","duration":"141.202595ms","start":"2026-05-06T05:43:54.202047Z","end":"2026-05-06T05:43:54.34325Z","steps":["trace[1923710730] 'read index received' (duration: 141.057912ms)","trace[1923710730] 'applied index is now lower than readState.Index' (duration: 144.133µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:43:54.343579Z","caller":"traceutil/trace.go:171","msg":"trace[822398286] transaction","detail":"{read_only:false; response_revision:19955; number_of_response:1; }","duration":"142.366469ms","start":"2026-05-06T05:43:54.201204Z","end":"2026-05-06T05:43:54.343571Z","steps":["trace[822398286] 'process raft request' (duration: 141.839178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.343818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.752168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-06T05:43:54.343848Z","caller":"traceutil/trace.go:171","msg":"trace[680262561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:19955; }","duration":"141.808018ms","start":"2026-05-06T05:43:54.202031Z","end":"2026-05-06T05:43:54.343839Z","steps":["trace[680262561] 'agreement among raft nodes before linearized reading' (duration: 141.673796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.344101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.122921ms","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-05-06T05:43:54.344184Z","caller":"traceutil/trace.go:171","msg":"trace[329129278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19955; }","duration":"140.230664ms","start":"2026-05-06T05:43:54.203935Z","end":"2026-05-06T05:43:54.344166Z","steps":["trace[329129278] 'agreement among raft nodes before linearized reading' (duration: 140.000369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.344201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.228798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-06T05:43:54.344309Z","caller":"traceutil/trace.go:171","msg":"trace[1407457514] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19955; }","duration":"135.358481ms","start":"2026-05-06T05:43:54.208923Z","end":"2026-05-06T05:43:54.344281Z","steps":["trace[1407457514] 'agreement among raft nodes before linearized reading' (duration: 135.030434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.344141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.143009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-06T05:43:54.344461Z","caller":"traceutil/trace.go:171","msg":"trace[1842560094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19955; }","duration":"137.473255ms","start":"2026-05-06T05:43:54.206978Z","end":"2026-05-06T05:43:54.344452Z","steps":["trace[1842560094] 'agreement among raft nodes before linearized reading' (duration: 137.110598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.344426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.575341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-06T05:43:54.344573Z","caller":"traceutil/trace.go:171","msg":"trace[12184152] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:19955; }","duration":"125.738564ms","start":"2026-05-06T05:43:54.218825Z","end":"2026-05-06T05:43:54.344564Z","steps":["trace[12184152] 'agreement among raft nodes before linearized reading' (duration: 125.55508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.344549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.4952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-05-06T05:43:54.345546Z","caller":"traceutil/trace.go:171","msg":"trace[1850983016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:19955; }","duration":"141.519061ms","start":"2026-05-06T05:43:54.203987Z","end":"2026-05-06T05:43:54.345507Z","steps":["trace[1850983016] 'agreement among raft nodes before linearized reading' (duration: 140.346347ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:43:54.574776Z","caller":"traceutil/trace.go:171","msg":"trace[752917639] transaction","detail":"{read_only:false; response_revision:19957; number_of_response:1; }","duration":"223.863005ms","start":"2026-05-06T05:43:54.350885Z","end":"2026-05-06T05:43:54.574748Z","steps":["trace[752917639] 'process raft request' (duration: 223.780214ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:43:54.57477Z","caller":"traceutil/trace.go:171","msg":"trace[555822817] transaction","detail":"{read_only:false; response_revision:19956; number_of_response:1; }","duration":"223.978728ms","start":"2026-05-06T05:43:54.350748Z","end":"2026-05-06T05:43:54.574727Z","steps":["trace[555822817] 'process raft request' (duration: 177.286597ms)","trace[555822817] 'compare' (duration: 46.480427ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:43:54.575188Z","caller":"traceutil/trace.go:171","msg":"trace[2118415335] transaction","detail":"{read_only:false; response_revision:19960; number_of_response:1; }","duration":"224.160372ms","start":"2026-05-06T05:43:54.351016Z","end":"2026-05-06T05:43:54.575177Z","steps":["trace[2118415335] 'process raft request' (duration: 224.05501ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:43:54.575192Z","caller":"traceutil/trace.go:171","msg":"trace[1488424034] transaction","detail":"{read_only:false; response_revision:19959; number_of_response:1; }","duration":"224.197693ms","start":"2026-05-06T05:43:54.350972Z","end":"2026-05-06T05:43:54.575169Z","steps":["trace[1488424034] 'process raft request' (duration: 223.900157ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:43:54.57515Z","caller":"traceutil/trace.go:171","msg":"trace[282033869] linearizableReadLoop","detail":"{readStateIndex:20628; appliedIndex:20623; }","duration":"223.668531ms","start":"2026-05-06T05:43:54.351456Z","end":"2026-05-06T05:43:54.575124Z","steps":["trace[282033869] 'read index received' (duration: 176.557372ms)","trace[282033869] 'applied index is now lower than readState.Index' (duration: 47.110519ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:43:54.575277Z","caller":"traceutil/trace.go:171","msg":"trace[1751415653] transaction","detail":"{read_only:false; response_revision:19958; number_of_response:1; }","duration":"224.304575ms","start":"2026-05-06T05:43:54.350964Z","end":"2026-05-06T05:43:54.575269Z","steps":["trace[1751415653] 'process raft request' (duration: 223.743784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.575355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.886407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-06T05:43:54.575396Z","caller":"traceutil/trace.go:171","msg":"trace[1273425735] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:19960; }","duration":"223.955008ms","start":"2026-05-06T05:43:54.351431Z","end":"2026-05-06T05:43:54.575386Z","steps":["trace[1273425735] 'agreement among raft nodes before linearized reading' (duration: 223.787674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:43:54.575453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.722263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18464"} {"level":"info","ts":"2026-05-06T05:43:54.575521Z","caller":"traceutil/trace.go:171","msg":"trace[1908058101] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19960; }","duration":"223.814864ms","start":"2026-05-06T05:43:54.351689Z","end":"2026-05-06T05:43:54.575503Z","steps":["trace[1908058101] 'agreement among raft nodes before linearized reading' (duration: 223.61019ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:44:29.333127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18086} {"level":"info","ts":"2026-05-06T05:44:29.459721Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18086,"took":"122.695788ms","hash":208488009,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30412800,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T05:44:29.459912Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":208488009,"revision":18086,"compact-revision":15497} {"level":"warn","ts":"2026-05-06T05:47:36.366804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.527895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:47:36.366973Z","caller":"traceutil/trace.go:171","msg":"trace[1205257958] transaction","detail":"{read_only:false; response_revision:22334; number_of_response:1; }","duration":"435.427279ms","start":"2026-05-06T05:47:35.931512Z","end":"2026-05-06T05:47:36.366939Z","steps":["trace[1205257958] 'process raft request' (duration: 228.619258ms)","trace[1205257958] 'compare' (duration: 206.234129ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:47:36.367045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:47:35.931489Z","time spent":"435.52301ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:47:36.400251Z","caller":"traceutil/trace.go:171","msg":"trace[1643143473] transaction","detail":"{read_only:false; response_revision:22335; number_of_response:1; }","duration":"451.466292ms","start":"2026-05-06T05:47:35.948755Z","end":"2026-05-06T05:47:36.400221Z","steps":["trace[1643143473] 'process raft request' (duration: 451.315408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:47:36.400435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:47:35.948735Z","time spent":"451.594295ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:47:36.424942Z","caller":"traceutil/trace.go:171","msg":"trace[2126821695] transaction","detail":"{read_only:false; response_revision:22336; number_of_response:1; }","duration":"201.296231ms","start":"2026-05-06T05:47:36.223632Z","end":"2026-05-06T05:47:36.424928Z","steps":["trace[2126821695] 'process raft request' (duration: 201.204939ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:29.33886Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20371} {"level":"info","ts":"2026-05-06T05:49:29.444557Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20371,"took":"102.292092ms","hash":716682801,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":42258432,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-06T05:49:29.444636Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":716682801,"revision":20371,"compact-revision":18086} {"level":"info","ts":"2026-05-06T05:50:45.696092Z","caller":"traceutil/trace.go:171","msg":"trace[1079260603] transaction","detail":"{read_only:false; response_revision:24304; number_of_response:1; }","duration":"123.73794ms","start":"2026-05-06T05:50:45.572326Z","end":"2026-05-06T05:50:45.696064Z","steps":["trace[1079260603] 'process raft request' (duration: 123.565346ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:50.551211Z","caller":"traceutil/trace.go:171","msg":"trace[1885844433] transaction","detail":"{read_only:false; response_revision:24337; number_of_response:1; }","duration":"149.537153ms","start":"2026-05-06T05:50:50.401647Z","end":"2026-05-06T05:50:50.551184Z","steps":["trace[1885844433] 'process raft request' (duration: 149.39859ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:50.702438Z","caller":"traceutil/trace.go:171","msg":"trace[1793912974] transaction","detail":"{read_only:false; response_revision:24338; number_of_response:1; }","duration":"128.444961ms","start":"2026-05-06T05:50:50.573971Z","end":"2026-05-06T05:50:50.702416Z","steps":["trace[1793912974] 'process raft request' (duration: 111.662001ms)","trace[1793912974] 'compare' (duration: 16.551325ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:50.887851Z","caller":"traceutil/trace.go:171","msg":"trace[594021897] linearizableReadLoop","detail":"{readStateIndex:25131; appliedIndex:25129; }","duration":"111.970968ms","start":"2026-05-06T05:50:50.775859Z","end":"2026-05-06T05:50:50.88783Z","steps":["trace[594021897] 'read index received' (duration: 98.355867ms)","trace[594021897] 'applied index is now lower than readState.Index' (duration: 13.614201ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:50.887941Z","caller":"traceutil/trace.go:171","msg":"trace[2062712353] transaction","detail":"{read_only:false; response_revision:24340; number_of_response:1; }","duration":"121.145356ms","start":"2026-05-06T05:50:50.766787Z","end":"2026-05-06T05:50:50.887933Z","steps":["trace[2062712353] 'process raft request' (duration: 120.972962ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:50.887936Z","caller":"traceutil/trace.go:171","msg":"trace[1955873774] transaction","detail":"{read_only:false; response_revision:24339; number_of_response:1; }","duration":"121.210847ms","start":"2026-05-06T05:50:50.766691Z","end":"2026-05-06T05:50:50.887902Z","steps":["trace[1955873774] 'process raft request' (duration: 107.393101ms)","trace[1955873774] 'compare' (duration: 13.426327ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:50.888188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.733093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T05:50:50.888297Z","caller":"traceutil/trace.go:171","msg":"trace[484401963] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24340; }","duration":"111.887397ms","start":"2026-05-06T05:50:50.776383Z","end":"2026-05-06T05:50:50.888271Z","steps":["trace[484401963] 'agreement among raft nodes before linearized reading' (duration: 111.657932ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:50.888322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.713845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-06T05:50:50.888331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.459049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T05:50:50.888358Z","caller":"traceutil/trace.go:171","msg":"trace[1521168162] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24340; }","duration":"106.775467ms","start":"2026-05-06T05:50:50.781573Z","end":"2026-05-06T05:50:50.888348Z","steps":["trace[1521168162] 'agreement among raft nodes before linearized reading' (duration: 106.660715ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:50.888361Z","caller":"traceutil/trace.go:171","msg":"trace[700876587] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24340; }","duration":"112.525749ms","start":"2026-05-06T05:50:50.775826Z","end":"2026-05-06T05:50:50.888352Z","steps":["trace[700876587] 'agreement among raft nodes before linearized reading' (duration: 112.414567ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:51.211792Z","caller":"traceutil/trace.go:171","msg":"trace[1327192970] transaction","detail":"{read_only:false; response_revision:24341; number_of_response:1; }","duration":"318.080223ms","start":"2026-05-06T05:50:50.893678Z","end":"2026-05-06T05:50:51.211758Z","steps":["trace[1327192970] 'process raft request' (duration: 253.397138ms)","trace[1327192970] 'compare' (duration: 64.156264ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:51.211908Z","caller":"traceutil/trace.go:171","msg":"trace[470222948] linearizableReadLoop","detail":"{readStateIndex:25134; appliedIndex:25131; }","duration":"127.971021ms","start":"2026-05-06T05:50:51.083914Z","end":"2026-05-06T05:50:51.211885Z","steps":["trace[470222948] 'read index received' (duration: 63.279315ms)","trace[470222948] 'applied index is now lower than readState.Index' (duration: 64.690466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:51.211943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:50.893663Z","time spent":"318.202396ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:51.212028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.114804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:51.212007Z","caller":"traceutil/trace.go:171","msg":"trace[1870833035] transaction","detail":"{read_only:false; response_revision:24343; number_of_response:1; }","duration":"315.881646ms","start":"2026-05-06T05:50:50.896104Z","end":"2026-05-06T05:50:51.211986Z","steps":["trace[1870833035] 'process raft request' (duration: 315.724353ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:51.212014Z","caller":"traceutil/trace.go:171","msg":"trace[472725296] transaction","detail":"{read_only:false; response_revision:24342; number_of_response:1; }","duration":"318.003932ms","start":"2026-05-06T05:50:50.893984Z","end":"2026-05-06T05:50:51.211987Z","steps":["trace[472725296] 'process raft request' (duration: 317.719206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:51.212129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:50.896097Z","time spent":"315.963138ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:51.212174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:50.893972Z","time spent":"318.118494ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:51.212057Z","caller":"traceutil/trace.go:171","msg":"trace[202471029] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24343; }","duration":"128.175915ms","start":"2026-05-06T05:50:51.083871Z","end":"2026-05-06T05:50:51.212047Z","steps":["trace[202471029] 'agreement among raft nodes before linearized reading' (duration: 128.109664ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:51.296173Z","caller":"traceutil/trace.go:171","msg":"trace[278111705] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"115.492654ms","start":"2026-05-06T05:50:51.180654Z","end":"2026-05-06T05:50:51.296146Z","steps":["trace[278111705] 'process raft request' (duration: 115.366141ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:51.548623Z","caller":"traceutil/trace.go:171","msg":"trace[1459883048] transaction","detail":"{read_only:false; response_revision:24345; number_of_response:1; }","duration":"359.704336ms","start":"2026-05-06T05:50:51.188878Z","end":"2026-05-06T05:50:51.548583Z","steps":["trace[1459883048] 'process raft request' (duration: 359.396649ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:51.548627Z","caller":"traceutil/trace.go:171","msg":"trace[1662509944] transaction","detail":"{read_only:false; response_revision:24346; number_of_response:1; }","duration":"351.067671ms","start":"2026-05-06T05:50:51.197523Z","end":"2026-05-06T05:50:51.548591Z","steps":["trace[1662509944] 'process raft request' (duration: 350.965249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:51.548763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:51.188859Z","time spent":"359.827528ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:51.54883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:51.197506Z","time spent":"351.210563ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:51.548887Z","caller":"traceutil/trace.go:171","msg":"trace[938008649] linearizableReadLoop","detail":"{readStateIndex:25138; appliedIndex:25135; }","duration":"336.877097ms","start":"2026-05-06T05:50:51.211976Z","end":"2026-05-06T05:50:51.548853Z","steps":["trace[938008649] 'read index received' (duration: 84.296696ms)","trace[938008649] 'applied index is now lower than readState.Index' (duration: 252.579031ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:51.549038Z","caller":"traceutil/trace.go:171","msg":"trace[1465461127] transaction","detail":"{read_only:false; response_revision:24347; number_of_response:1; }","duration":"345.125855ms","start":"2026-05-06T05:50:51.203901Z","end":"2026-05-06T05:50:51.549026Z","steps":["trace[1465461127] 'process raft request' (duration: 344.638904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:51.54917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:51.203882Z","time spent":"345.185616ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:51.549261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.047339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:51.549295Z","caller":"traceutil/trace.go:171","msg":"trace[1065913164] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24347; }","duration":"448.125199ms","start":"2026-05-06T05:50:51.101162Z","end":"2026-05-06T05:50:51.549287Z","steps":["trace[1065913164] 'agreement among raft nodes before linearized reading' (duration: 448.068098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:51.549316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:51.101137Z","time spent":"448.174741ms","remote":"127.0.0.1:52554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:50:52.062686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.291667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:52.062755Z","caller":"traceutil/trace.go:171","msg":"trace[1706718853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24348; }","duration":"198.38993ms","start":"2026-05-06T05:50:51.864348Z","end":"2026-05-06T05:50:52.062738Z","steps":["trace[1706718853] 'range keys from in-memory index tree' (duration: 198.221456ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:52.802943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.149026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:52.803095Z","caller":"traceutil/trace.go:171","msg":"trace[537736702] transaction","detail":"{read_only:false; response_revision:24351; number_of_response:1; }","duration":"235.469004ms","start":"2026-05-06T05:50:52.567603Z","end":"2026-05-06T05:50:52.803072Z","steps":["trace[537736702] 'process raft request' (duration: 135.077914ms)","trace[537736702] 'compare' (duration: 99.618314ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:53.116363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.066411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:53.116734Z","caller":"traceutil/trace.go:171","msg":"trace[597707] transaction","detail":"{read_only:false; response_revision:24352; number_of_response:1; }","duration":"544.891962ms","start":"2026-05-06T05:50:52.57181Z","end":"2026-05-06T05:50:53.116702Z","steps":["trace[597707] 'process raft request' (duration: 411.390403ms)","trace[597707] 'compare' (duration: 132.669211ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:53.116825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:52.571792Z","time spent":"544.991315ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:53.117266Z","caller":"traceutil/trace.go:171","msg":"trace[919683621] linearizableReadLoop","detail":"{readStateIndex:25145; appliedIndex:25143; }","duration":"375.947813ms","start":"2026-05-06T05:50:52.741306Z","end":"2026-05-06T05:50:53.117254Z","steps":["trace[919683621] 'read index received' (duration: 241.989705ms)","trace[919683621] 'applied index is now lower than readState.Index' (duration: 133.957238ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:53.117592Z","caller":"traceutil/trace.go:171","msg":"trace[180553979] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"409.593995ms","start":"2026-05-06T05:50:52.70796Z","end":"2026-05-06T05:50:53.117554Z","steps":["trace[180553979] 'process raft request' (duration: 408.508121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.11767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.624484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-06T05:50:53.11771Z","caller":"traceutil/trace.go:171","msg":"trace[1812974178] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24353; }","duration":"183.716956ms","start":"2026-05-06T05:50:52.933982Z","end":"2026-05-06T05:50:53.117699Z","steps":["trace[1812974178] 'agreement among raft nodes before linearized reading' (duration: 183.539751ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.117795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:52.707927Z","time spent":"409.744758ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:53.117794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.480735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-05-06T05:50:53.117887Z","caller":"traceutil/trace.go:171","msg":"trace[89346792] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24353; }","duration":"312.639778ms","start":"2026-05-06T05:50:52.805224Z","end":"2026-05-06T05:50:53.117864Z","steps":["trace[89346792] 'agreement among raft nodes before linearized reading' (duration: 312.27055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.117969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.661119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-69579d84db-gfhkt\" ","response":"range_response_count:1 size:8652"} {"level":"warn","ts":"2026-05-06T05:50:53.117972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:52.805206Z","time spent":"312.75169ms","remote":"127.0.0.1:52322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-06T05:50:53.118007Z","caller":"traceutil/trace.go:171","msg":"trace[452254533] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-69579d84db-gfhkt; range_end:; response_count:1; response_revision:24353; }","duration":"376.747471ms","start":"2026-05-06T05:50:52.74125Z","end":"2026-05-06T05:50:53.117998Z","steps":["trace[452254533] 'agreement among raft nodes before linearized reading' (duration: 376.641619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.118035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:52.74122Z","time spent":"376.808433ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":8674,"request content":"key:\"/registry/pods/openstack/magnum-api-69579d84db-gfhkt\" "} {"level":"warn","ts":"2026-05-06T05:50:53.118035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.393577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:53.118075Z","caller":"traceutil/trace.go:171","msg":"trace[1642268850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24353; }","duration":"252.479788ms","start":"2026-05-06T05:50:52.865584Z","end":"2026-05-06T05:50:53.118064Z","steps":["trace[1642268850] 'agreement among raft nodes before linearized reading' (duration: 252.388597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.118219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.949351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-05-06T05:50:53.118253Z","caller":"traceutil/trace.go:171","msg":"trace[1937861056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24353; }","duration":"226.019292ms","start":"2026-05-06T05:50:52.892226Z","end":"2026-05-06T05:50:53.118245Z","steps":["trace[1937861056] 'agreement among raft nodes before linearized reading' (duration: 225.92441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.118235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.83111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-06T05:50:53.118375Z","caller":"traceutil/trace.go:171","msg":"trace[558157608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24353; }","duration":"227.042213ms","start":"2026-05-06T05:50:52.891304Z","end":"2026-05-06T05:50:53.118346Z","steps":["trace[558157608] 'agreement among raft nodes before linearized reading' (duration: 226.580974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.325156Z","caller":"traceutil/trace.go:171","msg":"trace[633910361] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"193.121417ms","start":"2026-05-06T05:50:53.132009Z","end":"2026-05-06T05:50:53.325131Z","steps":["trace[633910361] 'process raft request' (duration: 192.79978ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.325211Z","caller":"traceutil/trace.go:171","msg":"trace[25178697] linearizableReadLoop","detail":"{readStateIndex:25148; appliedIndex:25146; }","duration":"185.047464ms","start":"2026-05-06T05:50:53.140143Z","end":"2026-05-06T05:50:53.325191Z","steps":["trace[25178697] 'read index received' (duration: 153.402235ms)","trace[25178697] 'applied index is now lower than readState.Index' (duration: 31.641809ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:53.32535Z","caller":"traceutil/trace.go:171","msg":"trace[2130408390] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"200.624869ms","start":"2026-05-06T05:50:53.124699Z","end":"2026-05-06T05:50:53.325324Z","steps":["trace[2130408390] 'process raft request' (duration: 168.837257ms)","trace[2130408390] 'compare' (duration: 31.090716ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:53.32547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.786058ms","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:2083"} {"level":"warn","ts":"2026-05-06T05:50:53.325471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.331392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-06T05:50:53.325472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.305149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-06T05:50:53.325525Z","caller":"traceutil/trace.go:171","msg":"trace[1321550793] 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:24357; }","duration":"155.890639ms","start":"2026-05-06T05:50:53.169615Z","end":"2026-05-06T05:50:53.325506Z","steps":["trace[1321550793] 'agreement among raft nodes before linearized reading' (duration: 155.711506ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.325528Z","caller":"traceutil/trace.go:171","msg":"trace[1021223464] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24357; }","duration":"109.424404ms","start":"2026-05-06T05:50:53.216088Z","end":"2026-05-06T05:50:53.325512Z","steps":["trace[1021223464] 'agreement among raft nodes before linearized reading' (duration: 109.272711ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.325556Z","caller":"traceutil/trace.go:171","msg":"trace[818153148] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24357; }","duration":"185.425542ms","start":"2026-05-06T05:50:53.140112Z","end":"2026-05-06T05:50:53.325537Z","steps":["trace[818153148] 'agreement among raft nodes before linearized reading' (duration: 185.185247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.325574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.749871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T05:50:53.325654Z","caller":"traceutil/trace.go:171","msg":"trace[321721416] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24357; }","duration":"109.864294ms","start":"2026-05-06T05:50:53.215769Z","end":"2026-05-06T05:50:53.325633Z","steps":["trace[321721416] 'agreement among raft nodes before linearized reading' (duration: 109.68022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.325755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.880443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T05:50:53.325938Z","caller":"traceutil/trace.go:171","msg":"trace[72963792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24357; }","duration":"110.114189ms","start":"2026-05-06T05:50:53.215792Z","end":"2026-05-06T05:50:53.325906Z","steps":["trace[72963792] 'agreement among raft nodes before linearized reading' (duration: 109.648369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.622571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.787296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:53.622722Z","caller":"traceutil/trace.go:171","msg":"trace[268585314] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"292.282033ms","start":"2026-05-06T05:50:53.330408Z","end":"2026-05-06T05:50:53.62269Z","steps":["trace[268585314] 'process raft request' (duration: 102.255202ms)","trace[268585314] 'compare' (duration: 188.904336ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:53.62343Z","caller":"traceutil/trace.go:171","msg":"trace[19643229] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"292.756581ms","start":"2026-05-06T05:50:53.330631Z","end":"2026-05-06T05:50:53.623388Z","steps":["trace[19643229] 'process raft request' (duration: 292.495466ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.623484Z","caller":"traceutil/trace.go:171","msg":"trace[961224823] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"291.842712ms","start":"2026-05-06T05:50:53.331614Z","end":"2026-05-06T05:50:53.623456Z","steps":["trace[961224823] 'process raft request' (duration: 291.727449ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.623503Z","caller":"traceutil/trace.go:171","msg":"trace[1025629796] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"292.039805ms","start":"2026-05-06T05:50:53.331406Z","end":"2026-05-06T05:50:53.623446Z","steps":["trace[1025629796] 'process raft request' (duration: 291.875482ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.623682Z","caller":"traceutil/trace.go:171","msg":"trace[1048547881] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"290.352909ms","start":"2026-05-06T05:50:53.333318Z","end":"2026-05-06T05:50:53.623671Z","steps":["trace[1048547881] 'process raft request' (duration: 290.073923ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.62381Z","caller":"traceutil/trace.go:171","msg":"trace[417261051] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"289.568103ms","start":"2026-05-06T05:50:53.334215Z","end":"2026-05-06T05:50:53.623784Z","steps":["trace[417261051] 'process raft request' (duration: 289.348608ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.624037Z","caller":"traceutil/trace.go:171","msg":"trace[74765126] linearizableReadLoop","detail":"{readStateIndex:25151; appliedIndex:25149; }","duration":"293.332923ms","start":"2026-05-06T05:50:53.330679Z","end":"2026-05-06T05:50:53.624012Z","steps":["trace[74765126] 'read index received' (duration: 101.963954ms)","trace[74765126] 'applied index is now lower than readState.Index' (duration: 191.367859ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:53.624239Z","caller":"traceutil/trace.go:171","msg":"trace[2006598369] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"288.317457ms","start":"2026-05-06T05:50:53.335898Z","end":"2026-05-06T05:50:53.624215Z","steps":["trace[2006598369] 'process raft request' (duration: 287.779455ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.624498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.807483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-a69ec812-b03e-4104-9f9d-0c4afde09b2a\" ","response":"range_response_count:1 size:1436"} {"level":"info","ts":"2026-05-06T05:50:53.624546Z","caller":"traceutil/trace.go:171","msg":"trace[1612642846] range","detail":"{range_begin:/registry/persistentvolumes/pvc-a69ec812-b03e-4104-9f9d-0c4afde09b2a; range_end:; response_count:1; response_revision:24364; }","duration":"293.866984ms","start":"2026-05-06T05:50:53.330662Z","end":"2026-05-06T05:50:53.624529Z","steps":["trace[1612642846] 'agreement among raft nodes before linearized reading' (duration: 293.711061ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.640344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.453595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:53.640445Z","caller":"traceutil/trace.go:171","msg":"trace[1695985574] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24367; }","duration":"250.608278ms","start":"2026-05-06T05:50:53.389817Z","end":"2026-05-06T05:50:53.640425Z","steps":["trace[1695985574] 'agreement among raft nodes before linearized reading' (duration: 250.422244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.640748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.231915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:53.640852Z","caller":"traceutil/trace.go:171","msg":"trace[1700199148] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24367; }","duration":"183.371508ms","start":"2026-05-06T05:50:53.45743Z","end":"2026-05-06T05:50:53.640802Z","steps":["trace[1700199148] 'agreement among raft nodes before linearized reading' (duration: 183.243735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:53.939864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.177417ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:53.940019Z","caller":"traceutil/trace.go:171","msg":"trace[1684644561] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"304.901141ms","start":"2026-05-06T05:50:53.63509Z","end":"2026-05-06T05:50:53.939992Z","steps":["trace[1684644561] 'process raft request' (duration: 119.434759ms)","trace[1684644561] 'compare' (duration: 184.904571ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:53.940137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:53.635071Z","time spent":"305.003854ms","remote":"127.0.0.1:52608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7836,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:50:53.940129Z","caller":"traceutil/trace.go:171","msg":"trace[1028860234] linearizableReadLoop","detail":"{readStateIndex:25160; appliedIndex:25159; }","duration":"295.48053ms","start":"2026-05-06T05:50:53.644617Z","end":"2026-05-06T05:50:53.940097Z","steps":["trace[1028860234] 'read index received' (duration: 109.881654ms)","trace[1028860234] 'applied index is now lower than readState.Index' (duration: 185.595076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:53.940261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.629223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:53.940331Z","caller":"traceutil/trace.go:171","msg":"trace[1532918343] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24368; }","duration":"295.744125ms","start":"2026-05-06T05:50:53.644568Z","end":"2026-05-06T05:50:53.940312Z","steps":["trace[1532918343] 'agreement among raft nodes before linearized reading' (duration: 295.615093ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:53.940349Z","caller":"traceutil/trace.go:171","msg":"trace[24673613] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"199.127996ms","start":"2026-05-06T05:50:53.741213Z","end":"2026-05-06T05:50:53.940341Z","steps":["trace[24673613] 'process raft request' (duration: 198.8382ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:54.41577Z","caller":"traceutil/trace.go:171","msg":"trace[1151692569] linearizableReadLoop","detail":"{readStateIndex:25162; appliedIndex:25161; }","duration":"118.962088ms","start":"2026-05-06T05:50:54.296751Z","end":"2026-05-06T05:50:54.415714Z","steps":["trace[1151692569] 'read index received' (duration: 118.772554ms)","trace[1151692569] 'applied index is now lower than readState.Index' (duration: 188.804µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:54.415985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.216145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-05-06T05:50:54.416084Z","caller":"traceutil/trace.go:171","msg":"trace[1780761815] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24370; }","duration":"119.362777ms","start":"2026-05-06T05:50:54.296711Z","end":"2026-05-06T05:50:54.416074Z","steps":["trace[1780761815] 'agreement among raft nodes before linearized reading' (duration: 119.129202ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:54.416358Z","caller":"traceutil/trace.go:171","msg":"trace[752351678] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"161.147942ms","start":"2026-05-06T05:50:54.255203Z","end":"2026-05-06T05:50:54.416351Z","steps":["trace[752351678] 'process raft request' (duration: 160.370285ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:06.006548Z","caller":"traceutil/trace.go:171","msg":"trace[778469859] transaction","detail":"{read_only:false; response_revision:24452; number_of_response:1; }","duration":"328.264222ms","start":"2026-05-06T05:51:05.678256Z","end":"2026-05-06T05:51:06.00652Z","steps":["trace[778469859] 'process raft request' (duration: 328.097218ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.006693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.678244Z","time spent":"328.378104ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:06.095635Z","caller":"traceutil/trace.go:171","msg":"trace[1806235629] transaction","detail":"{read_only:false; response_revision:24453; number_of_response:1; }","duration":"417.008193ms","start":"2026-05-06T05:51:05.67861Z","end":"2026-05-06T05:51:06.095618Z","steps":["trace[1806235629] 'process raft request' (duration: 416.785279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.09579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.678601Z","time spent":"417.096465ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:06.096004Z","caller":"traceutil/trace.go:171","msg":"trace[1476683126] transaction","detail":"{read_only:false; response_revision:24454; number_of_response:1; }","duration":"412.607369ms","start":"2026-05-06T05:51:05.683368Z","end":"2026-05-06T05:51:06.095975Z","steps":["trace[1476683126] 'process raft request' (duration: 412.17813ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:06.096174Z","caller":"traceutil/trace.go:171","msg":"trace[869191809] transaction","detail":"{read_only:false; response_revision:24455; number_of_response:1; }","duration":"408.835277ms","start":"2026-05-06T05:51:05.687327Z","end":"2026-05-06T05:51:06.096163Z","steps":["trace[869191809] 'process raft request' (duration: 408.561731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.096181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.683358Z","time spent":"412.714281ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:06.096232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.687283Z","time spent":"408.922749ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:06.096311Z","caller":"traceutil/trace.go:171","msg":"trace[1385149465] transaction","detail":"{read_only:false; response_revision:24458; number_of_response:1; }","duration":"101.775931ms","start":"2026-05-06T05:51:05.994527Z","end":"2026-05-06T05:51:06.096303Z","steps":["trace[1385149465] 'process raft request' (duration: 101.676058ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:06.096293Z","caller":"traceutil/trace.go:171","msg":"trace[1984543549] linearizableReadLoop","detail":"{readStateIndex:25247; appliedIndex:25245; }","duration":"416.008811ms","start":"2026-05-06T05:51:05.680254Z","end":"2026-05-06T05:51:06.096262Z","steps":["trace[1984543549] 'read index received' (duration: 326.216758ms)","trace[1984543549] 'applied index is now lower than readState.Index' (duration: 89.790513ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:06.096305Z","caller":"traceutil/trace.go:171","msg":"trace[1796909437] transaction","detail":"{read_only:false; response_revision:24457; number_of_response:1; }","duration":"150.364841ms","start":"2026-05-06T05:51:05.945928Z","end":"2026-05-06T05:51:06.096292Z","steps":["trace[1796909437] 'process raft request' (duration: 150.165366ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:06.096362Z","caller":"traceutil/trace.go:171","msg":"trace[96928093] transaction","detail":"{read_only:false; response_revision:24456; number_of_response:1; }","duration":"406.852966ms","start":"2026-05-06T05:51:05.689499Z","end":"2026-05-06T05:51:06.096352Z","steps":["trace[96928093] 'process raft request' (duration: 406.508587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.09642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.68949Z","time spent":"406.900468ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:06.096449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.521192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:06.096475Z","caller":"traceutil/trace.go:171","msg":"trace[412790759] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24458; }","duration":"169.592623ms","start":"2026-05-06T05:51:05.926875Z","end":"2026-05-06T05:51:06.096468Z","steps":["trace[412790759] 'agreement among raft nodes before linearized reading' (duration: 169.546462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.096527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.258067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T05:51:06.096607Z","caller":"traceutil/trace.go:171","msg":"trace[368219290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24458; }","duration":"416.35769ms","start":"2026-05-06T05:51:05.680232Z","end":"2026-05-06T05:51:06.096589Z","steps":["trace[368219290] 'agreement among raft nodes before linearized reading' (duration: 416.169506ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:06.096651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:05.680224Z","time spent":"416.418541ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:51:10.384469Z","caller":"traceutil/trace.go:171","msg":"trace[263884220] linearizableReadLoop","detail":"{readStateIndex:25329; appliedIndex:25328; }","duration":"340.275029ms","start":"2026-05-06T05:51:10.044171Z","end":"2026-05-06T05:51:10.384446Z","steps":["trace[263884220] 'read index received' (duration: 340.079744ms)","trace[263884220] 'applied index is now lower than readState.Index' (duration: 193.925µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:10.384526Z","caller":"traceutil/trace.go:171","msg":"trace[1090377362] transaction","detail":"{read_only:false; response_revision:24534; number_of_response:1; }","duration":"359.908609ms","start":"2026-05-06T05:51:10.024581Z","end":"2026-05-06T05:51:10.38449Z","steps":["trace[1090377362] 'process raft request' (duration: 359.696715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:10.384766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.547324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T05:51:10.38482Z","caller":"traceutil/trace.go:171","msg":"trace[645853260] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00; range_end:; response_count:1; response_revision:24534; }","duration":"340.637166ms","start":"2026-05-06T05:51:10.044166Z","end":"2026-05-06T05:51:10.384803Z","steps":["trace[645853260] 'agreement among raft nodes before linearized reading' (duration: 340.394741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:10.384861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.04413Z","time spent":"340.721288ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00\" "} {"level":"warn","ts":"2026-05-06T05:51:10.384819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.024556Z","time spent":"360.179796ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:10.885162Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6363759977728243524,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-06T05:51:11.161972Z","caller":"traceutil/trace.go:171","msg":"trace[157323303] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"1.049563784s","start":"2026-05-06T05:51:10.112378Z","end":"2026-05-06T05:51:11.161942Z","steps":["trace[157323303] 'process raft request' (duration: 1.049314018s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.162122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.112305Z","time spent":"1.049741708s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.162374Z","caller":"traceutil/trace.go:171","msg":"trace[1309188883] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"1.048045331s","start":"2026-05-06T05:51:10.1143Z","end":"2026-05-06T05:51:11.162345Z","steps":["trace[1309188883] 'process raft request' (duration: 1.047560601s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.162515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.114287Z","time spent":"1.048151463s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.162689Z","caller":"traceutil/trace.go:171","msg":"trace[1870366380] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"1.048226085s","start":"2026-05-06T05:51:10.114452Z","end":"2026-05-06T05:51:11.162678Z","steps":["trace[1870366380] 'process raft request' (duration: 1.047808976s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.162755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.1144Z","time spent":"1.048326097s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.163093Z","caller":"traceutil/trace.go:171","msg":"trace[544633182] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"1.048213345s","start":"2026-05-06T05:51:10.114868Z","end":"2026-05-06T05:51:11.163082Z","steps":["trace[544633182] 'process raft request' (duration: 1.047769045s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.163166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.114847Z","time spent":"1.048283296s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.163242Z","caller":"traceutil/trace.go:171","msg":"trace[1586973507] transaction","detail":"{read_only:false; response_revision:24540; number_of_response:1; }","duration":"876.421156ms","start":"2026-05-06T05:51:10.286794Z","end":"2026-05-06T05:51:11.163215Z","steps":["trace[1586973507] 'process raft request' (duration: 876.370174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.1634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.286772Z","time spent":"876.553048ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.163549Z","caller":"traceutil/trace.go:171","msg":"trace[266813231] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"891.058308ms","start":"2026-05-06T05:51:10.272479Z","end":"2026-05-06T05:51:11.163538Z","steps":["trace[266813231] 'process raft request' (duration: 890.558198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.163618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.272453Z","time spent":"891.13453ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:11.421873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.165188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:11.421984Z","caller":"traceutil/trace.go:171","msg":"trace[313091626] linearizableReadLoop","detail":"{readStateIndex:25336; appliedIndex:25329; }","duration":"1.037433705s","start":"2026-05-06T05:51:10.384534Z","end":"2026-05-06T05:51:11.421967Z","steps":["trace[313091626] 'read index received' (duration: 777.210811ms)","trace[313091626] 'applied index is now lower than readState.Index' (duration: 260.221244ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:11.422343Z","caller":"traceutil/trace.go:171","msg":"trace[966770946] transaction","detail":"{read_only:false; response_revision:24541; number_of_response:1; }","duration":"927.234254ms","start":"2026-05-06T05:51:10.49509Z","end":"2026-05-06T05:51:11.422324Z","steps":["trace[966770946] 'process raft request' (duration: 719.488713ms)","trace[966770946] 'compare' (duration: 206.900232ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:11.422226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.304607778s","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-05-06T05:51:11.422422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.495063Z","time spent":"927.322775ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:11.422421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307375027s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-06T05:51:11.422459Z","caller":"traceutil/trace.go:171","msg":"trace[1853180080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24541; }","duration":"1.304868853s","start":"2026-05-06T05:51:10.117577Z","end":"2026-05-06T05:51:11.422446Z","steps":["trace[1853180080] 'agreement among raft nodes before linearized reading' (duration: 1.304548686s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.422488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.117569Z","time spent":"1.304913084s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:51:11.422525Z","caller":"traceutil/trace.go:171","msg":"trace[1140743738] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24541; }","duration":"1.307470659s","start":"2026-05-06T05:51:10.114992Z","end":"2026-05-06T05:51:11.422463Z","steps":["trace[1140743738] 'agreement among raft nodes before linearized reading' (duration: 1.307144742s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.422568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.114983Z","time spent":"1.307577141s","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-05-06T05:51:11.573777Z","caller":"traceutil/trace.go:171","msg":"trace[509390466] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"332.443212ms","start":"2026-05-06T05:51:11.241294Z","end":"2026-05-06T05:51:11.573737Z","steps":["trace[509390466] 'process raft request' (duration: 286.963678ms)","trace[509390466] 'compare' (duration: 45.30553ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:11.573975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.241267Z","time spent":"332.611805ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.761526Z","caller":"traceutil/trace.go:171","msg":"trace[323778665] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"377.420765ms","start":"2026-05-06T05:51:11.384077Z","end":"2026-05-06T05:51:11.761497Z","steps":["trace[323778665] 'process raft request' (duration: 376.778792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.761699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.384057Z","time spent":"377.554138ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:11.761828Z","caller":"traceutil/trace.go:171","msg":"trace[380344001] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"335.437756ms","start":"2026-05-06T05:51:11.426362Z","end":"2026-05-06T05:51:11.761799Z","steps":["trace[380344001] 'process raft request' (duration: 335.080629ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:11.761939Z","caller":"traceutil/trace.go:171","msg":"trace[1168384736] linearizableReadLoop","detail":"{readStateIndex:25338; appliedIndex:25336; }","duration":"339.897301ms","start":"2026-05-06T05:51:11.42201Z","end":"2026-05-06T05:51:11.761908Z","steps":["trace[1168384736] 'read index received' (duration: 106.154824ms)","trace[1168384736] 'applied index is now lower than readState.Index' (duration: 233.740897ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:11.761979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.42632Z","time spent":"335.583109ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:11.762162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.240185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T05:51:11.762163Z","caller":"traceutil/trace.go:171","msg":"trace[1147420668] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"333.026405ms","start":"2026-05-06T05:51:11.429117Z","end":"2026-05-06T05:51:11.762143Z","steps":["trace[1147420668] 'process raft request' (duration: 332.597876ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:11.7622Z","caller":"traceutil/trace.go:171","msg":"trace[624324284] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00; range_end:; response_count:1; response_revision:24545; }","duration":"373.322027ms","start":"2026-05-06T05:51:11.388866Z","end":"2026-05-06T05:51:11.762188Z","steps":["trace[624324284] 'agreement among raft nodes before linearized reading' (duration: 373.161043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.762231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.388842Z","time spent":"373.381998ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3666,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00\" "} {"level":"warn","ts":"2026-05-06T05:51:11.762249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.429092Z","time spent":"333.107266ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:11.762302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"897.807113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:11.762352Z","caller":"traceutil/trace.go:171","msg":"trace[430011980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24545; }","duration":"897.897984ms","start":"2026-05-06T05:51:10.864439Z","end":"2026-05-06T05:51:11.762337Z","steps":["trace[430011980] 'agreement among raft nodes before linearized reading' (duration: 897.827262ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.762346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.998756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-05-06T05:51:11.762385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.864418Z","time spent":"897.960736ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:51:11.762404Z","caller":"traceutil/trace.go:171","msg":"trace[1317420684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24545; }","duration":"469.114629ms","start":"2026-05-06T05:51:11.293273Z","end":"2026-05-06T05:51:11.762387Z","steps":["trace[1317420684] 'agreement among raft nodes before linearized reading' (duration: 468.929675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.762373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.853761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-06T05:51:11.762453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.422449ms","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-05-06T05:51:11.762474Z","caller":"traceutil/trace.go:171","msg":"trace[588236239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24545; }","duration":"348.016044ms","start":"2026-05-06T05:51:11.414445Z","end":"2026-05-06T05:51:11.762461Z","steps":["trace[588236239] 'agreement among raft nodes before linearized reading' (duration: 347.78354ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:11.762491Z","caller":"traceutil/trace.go:171","msg":"trace[1548849398] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24545; }","duration":"506.495ms","start":"2026-05-06T05:51:11.255985Z","end":"2026-05-06T05:51:11.76248Z","steps":["trace[1548849398] 'agreement among raft nodes before linearized reading' (duration: 506.351697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.7625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.414421Z","time spent":"348.073586ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-06T05:51:11.762518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.588381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:51:11.762528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.255966Z","time spent":"506.554172ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T05:51:11.762452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.293245Z","time spent":"469.194401ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T05:51:11.762552Z","caller":"traceutil/trace.go:171","msg":"trace[1403569849] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:24545; }","duration":"977.655683ms","start":"2026-05-06T05:51:10.784886Z","end":"2026-05-06T05:51:11.762542Z","steps":["trace[1403569849] 'agreement among raft nodes before linearized reading' (duration: 977.592992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:11.762589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:10.784867Z","time spent":"977.715165ms","remote":"127.0.0.1:52656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":6,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:51:12.071099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.107022ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:12.071465Z","caller":"traceutil/trace.go:171","msg":"trace[2009240978] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"300.082288ms","start":"2026-05-06T05:51:11.771363Z","end":"2026-05-06T05:51:12.071446Z","steps":["trace[2009240978] 'process raft request' (duration: 300.004947ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:12.071504Z","caller":"traceutil/trace.go:171","msg":"trace[1307656186] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"303.47233ms","start":"2026-05-06T05:51:11.76801Z","end":"2026-05-06T05:51:12.071483Z","steps":["trace[1307656186] 'process raft request' (duration: 162.828697ms)","trace[1307656186] 'compare' (duration: 139.864427ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:12.071546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.771355Z","time spent":"300.15739ms","remote":"127.0.0.1:52260","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-06T05:51:12.071549Z","caller":"traceutil/trace.go:171","msg":"trace[122912941] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"301.136391ms","start":"2026-05-06T05:51:11.770374Z","end":"2026-05-06T05:51:12.07151Z","steps":["trace[122912941] 'process raft request' (duration: 300.950307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:12.071619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.767999Z","time spent":"303.556213ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:12.071513Z","caller":"traceutil/trace.go:171","msg":"trace[751947762] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"301.427896ms","start":"2026-05-06T05:51:11.770065Z","end":"2026-05-06T05:51:12.071493Z","steps":["trace[751947762] 'process raft request' (duration: 301.168631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:12.071741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.770282Z","time spent":"301.359857ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:12.071842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:11.76968Z","time spent":"302.05073ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:12.645884Z","caller":"traceutil/trace.go:171","msg":"trace[1989830915] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"157.613047ms","start":"2026-05-06T05:51:12.488225Z","end":"2026-05-06T05:51:12.645838Z","steps":["trace[1989830915] 'process raft request' (duration: 157.420803ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:12.679961Z","caller":"traceutil/trace.go:171","msg":"trace[137040665] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"102.447685ms","start":"2026-05-06T05:51:12.577488Z","end":"2026-05-06T05:51:12.679936Z","steps":["trace[137040665] 'process raft request' (duration: 102.293582ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:12.823312Z","caller":"traceutil/trace.go:171","msg":"trace[316297330] linearizableReadLoop","detail":"{readStateIndex:25348; appliedIndex:25347; }","duration":"132.354885ms","start":"2026-05-06T05:51:12.690925Z","end":"2026-05-06T05:51:12.82328Z","steps":["trace[316297330] 'read index received' (duration: 130.442643ms)","trace[316297330] 'applied index is now lower than readState.Index' (duration: 1.910462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:12.823943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.989788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T05:51:12.824175Z","caller":"traceutil/trace.go:171","msg":"trace[456066291] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:24552; }","duration":"133.272934ms","start":"2026-05-06T05:51:12.69089Z","end":"2026-05-06T05:51:12.824163Z","steps":["trace[456066291] 'agreement among raft nodes before linearized reading' (duration: 132.534469ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.409503Z","caller":"traceutil/trace.go:171","msg":"trace[1941666932] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"241.82276ms","start":"2026-05-06T05:51:13.167655Z","end":"2026-05-06T05:51:13.409477Z","steps":["trace[1941666932] 'process raft request' (duration: 241.657306ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.514775Z","caller":"traceutil/trace.go:171","msg":"trace[1078293441] transaction","detail":"{read_only:false; response_revision:24555; number_of_response:1; }","duration":"346.961163ms","start":"2026-05-06T05:51:13.16777Z","end":"2026-05-06T05:51:13.514731Z","steps":["trace[1078293441] 'process raft request' (duration: 293.653091ms)","trace[1078293441] 'compare' (duration: 52.999116ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:13.514935Z","caller":"traceutil/trace.go:171","msg":"trace[964089361] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"346.417461ms","start":"2026-05-06T05:51:13.168474Z","end":"2026-05-06T05:51:13.514891Z","steps":["trace[964089361] 'process raft request' (duration: 346.284879ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.5151Z","caller":"traceutil/trace.go:171","msg":"trace[1569465418] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"346.122005ms","start":"2026-05-06T05:51:13.168943Z","end":"2026-05-06T05:51:13.515065Z","steps":["trace[1569465418] 'process raft request' (duration: 345.88457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:13.515248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:13.168925Z","time spent":"346.247637ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:13.514957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:13.167706Z","time spent":"347.156527ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:13.515083Z","caller":"traceutil/trace.go:171","msg":"trace[1530769813] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25349; }","duration":"258.001007ms","start":"2026-05-06T05:51:13.257069Z","end":"2026-05-06T05:51:13.51507Z","steps":["trace[1530769813] 'read index received' (duration: 152.224201ms)","trace[1530769813] 'applied index is now lower than readState.Index' (duration: 105.776166ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:13.515421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.276533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:13.515592Z","caller":"traceutil/trace.go:171","msg":"trace[1628107197] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:24558; }","duration":"258.493777ms","start":"2026-05-06T05:51:13.257039Z","end":"2026-05-06T05:51:13.515532Z","steps":["trace[1628107197] 'agreement among raft nodes before linearized reading' (duration: 258.072398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:13.515484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:13.168449Z","time spent":"346.710817ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:13.687734Z","caller":"traceutil/trace.go:171","msg":"trace[2056174508] transaction","detail":"{read_only:false; response_revision:24559; number_of_response:1; }","duration":"165.610838ms","start":"2026-05-06T05:51:13.5221Z","end":"2026-05-06T05:51:13.687711Z","steps":["trace[2056174508] 'process raft request' (duration: 165.490465ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.70918Z","caller":"traceutil/trace.go:171","msg":"trace[99272994] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"129.058095ms","start":"2026-05-06T05:51:13.580092Z","end":"2026-05-06T05:51:13.70915Z","steps":["trace[99272994] 'process raft request' (duration: 128.816399ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.874177Z","caller":"traceutil/trace.go:171","msg":"trace[789786690] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"105.052031ms","start":"2026-05-06T05:51:13.769095Z","end":"2026-05-06T05:51:13.874147Z","steps":["trace[789786690] 'process raft request' (duration: 103.249652ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.874472Z","caller":"traceutil/trace.go:171","msg":"trace[846547372] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"102.912325ms","start":"2026-05-06T05:51:13.77154Z","end":"2026-05-06T05:51:13.874452Z","steps":["trace[846547372] 'process raft request' (duration: 102.537087ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:13.874976Z","caller":"traceutil/trace.go:171","msg":"trace[572404749] transaction","detail":"{read_only:false; response_revision:24563; number_of_response:1; }","duration":"103.264463ms","start":"2026-05-06T05:51:13.771671Z","end":"2026-05-06T05:51:13.874936Z","steps":["trace[572404749] 'process raft request' (duration: 103.061299ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:14.101727Z","caller":"traceutil/trace.go:171","msg":"trace[391647036] transaction","detail":"{read_only:false; response_revision:24564; number_of_response:1; }","duration":"220.290138ms","start":"2026-05-06T05:51:13.881397Z","end":"2026-05-06T05:51:14.101687Z","steps":["trace[391647036] 'process raft request' (duration: 183.97004ms)","trace[391647036] 'compare' (duration: 36.064573ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:14.116776Z","caller":"traceutil/trace.go:171","msg":"trace[1159907410] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"211.717485ms","start":"2026-05-06T05:51:13.905026Z","end":"2026-05-06T05:51:14.116743Z","steps":["trace[1159907410] 'process raft request' (duration: 211.51326ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:14.116762Z","caller":"traceutil/trace.go:171","msg":"trace[33252805] linearizableReadLoop","detail":"{readStateIndex:25361; appliedIndex:25359; }","duration":"196.125522ms","start":"2026-05-06T05:51:13.920588Z","end":"2026-05-06T05:51:14.116713Z","steps":["trace[33252805] 'read index received' (duration: 144.801942ms)","trace[33252805] 'applied index is now lower than readState.Index' (duration: 51.3209ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:14.117066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.452428ms","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:6"} {"level":"info","ts":"2026-05-06T05:51:14.117137Z","caller":"traceutil/trace.go:171","msg":"trace[213329874] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:24565; }","duration":"196.589181ms","start":"2026-05-06T05:51:13.92053Z","end":"2026-05-06T05:51:14.11712Z","steps":["trace[213329874] 'agreement among raft nodes before linearized reading' (duration: 196.410847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:14.11732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.804595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-05-06T05:51:14.117455Z","caller":"traceutil/trace.go:171","msg":"trace[377985776] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3e325735-a714-4d74-9d80-75edc336bf00; range_end:; response_count:1; response_revision:24565; }","duration":"161.095841ms","start":"2026-05-06T05:51:13.956341Z","end":"2026-05-06T05:51:14.117437Z","steps":["trace[377985776] 'agreement among raft nodes before linearized reading' (duration: 160.717473ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:16.380137Z","caller":"traceutil/trace.go:171","msg":"trace[1799528066] linearizableReadLoop","detail":"{readStateIndex:25382; appliedIndex:25381; }","duration":"159.077017ms","start":"2026-05-06T05:51:16.221042Z","end":"2026-05-06T05:51:16.380119Z","steps":["trace[1799528066] 'read index received' (duration: 158.955564ms)","trace[1799528066] 'applied index is now lower than readState.Index' (duration: 120.773µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:16.380321Z","caller":"traceutil/trace.go:171","msg":"trace[1036066882] transaction","detail":"{read_only:false; response_revision:24586; number_of_response:1; }","duration":"169.970942ms","start":"2026-05-06T05:51:16.210343Z","end":"2026-05-06T05:51:16.380314Z","steps":["trace[1036066882] 'process raft request' (duration: 169.673264ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:16.380525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.465787ms","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-05-06T05:51:16.38055Z","caller":"traceutil/trace.go:171","msg":"trace[536865601] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24586; }","duration":"159.526747ms","start":"2026-05-06T05:51:16.221018Z","end":"2026-05-06T05:51:16.380545Z","steps":["trace[536865601] 'agreement among raft nodes before linearized reading' (duration: 159.413235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:16.380665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.500947ms","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-05-06T05:51:16.380683Z","caller":"traceutil/trace.go:171","msg":"trace[525494480] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24586; }","duration":"159.534057ms","start":"2026-05-06T05:51:16.221145Z","end":"2026-05-06T05:51:16.380679Z","steps":["trace[525494480] 'agreement among raft nodes before linearized reading' (duration: 159.490586ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:16.501251Z","caller":"traceutil/trace.go:171","msg":"trace[708408773] transaction","detail":"{read_only:false; response_revision:24587; number_of_response:1; }","duration":"116.896634ms","start":"2026-05-06T05:51:16.38432Z","end":"2026-05-06T05:51:16.501217Z","steps":["trace[708408773] 'process raft request' (duration: 98.134202ms)","trace[708408773] 'compare' (duration: 18.418345ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:16.501396Z","caller":"traceutil/trace.go:171","msg":"trace[1233708490] transaction","detail":"{read_only:false; response_revision:24588; number_of_response:1; }","duration":"116.636889ms","start":"2026-05-06T05:51:16.384716Z","end":"2026-05-06T05:51:16.501353Z","steps":["trace[1233708490] 'process raft request' (duration: 116.332743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:19.744146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.050941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:19.744228Z","caller":"traceutil/trace.go:171","msg":"trace[1110153845] transaction","detail":"{read_only:false; response_revision:24629; number_of_response:1; }","duration":"209.605799ms","start":"2026-05-06T05:51:19.534608Z","end":"2026-05-06T05:51:19.744214Z","steps":["trace[1110153845] 'process raft request' (duration: 96.421055ms)","trace[1110153845] 'compare' (duration: 112.964199ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:19.751451Z","caller":"traceutil/trace.go:171","msg":"trace[995314756] transaction","detail":"{read_only:false; response_revision:24631; number_of_response:1; }","duration":"214.71875ms","start":"2026-05-06T05:51:19.536711Z","end":"2026-05-06T05:51:19.751429Z","steps":["trace[995314756] 'process raft request' (duration: 214.623568ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:19.751472Z","caller":"traceutil/trace.go:171","msg":"trace[1952436890] transaction","detail":"{read_only:false; response_revision:24630; number_of_response:1; }","duration":"215.332893ms","start":"2026-05-06T05:51:19.536097Z","end":"2026-05-06T05:51:19.75143Z","steps":["trace[1952436890] 'process raft request' (duration: 215.083248ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:19.751573Z","caller":"traceutil/trace.go:171","msg":"trace[791595799] linearizableReadLoop","detail":"{readStateIndex:25429; appliedIndex:25425; }","duration":"146.620971ms","start":"2026-05-06T05:51:19.604941Z","end":"2026-05-06T05:51:19.751562Z","steps":["trace[791595799] 'read index received' (duration: 26.16174ms)","trace[791595799] 'applied index is now lower than readState.Index' (duration: 120.457571ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:19.751611Z","caller":"traceutil/trace.go:171","msg":"trace[444248821] transaction","detail":"{read_only:false; response_revision:24632; number_of_response:1; }","duration":"212.083163ms","start":"2026-05-06T05:51:19.539504Z","end":"2026-05-06T05:51:19.751587Z","steps":["trace[444248821] 'process raft request' (duration: 211.884029ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:19.751676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.724422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:19.751704Z","caller":"traceutil/trace.go:171","msg":"trace[1454837426] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:24632; }","duration":"146.832884ms","start":"2026-05-06T05:51:19.604861Z","end":"2026-05-06T05:51:19.751694Z","steps":["trace[1454837426] 'agreement among raft nodes before linearized reading' (duration: 146.741393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:40.284121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.618578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0\" ","response":"range_response_count:1 size:1864"} {"level":"info","ts":"2026-05-06T05:51:40.284219Z","caller":"traceutil/trace.go:171","msg":"trace[1235177975] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-octavia-server-0; range_end:; response_count:1; response_revision:24803; }","duration":"118.784802ms","start":"2026-05-06T05:51:40.165414Z","end":"2026-05-06T05:51:40.284199Z","steps":["trace[1235177975] 'range keys from in-memory index tree' (duration: 118.422794ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:46.060139Z","caller":"traceutil/trace.go:171","msg":"trace[1830197834] linearizableReadLoop","detail":"{readStateIndex:25643; appliedIndex:25642; }","duration":"222.253975ms","start":"2026-05-06T05:51:45.837867Z","end":"2026-05-06T05:51:46.060121Z","steps":["trace[1830197834] 'read index received' (duration: 222.141583ms)","trace[1830197834] 'applied index is now lower than readState.Index' (duration: 111.432µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:46.060208Z","caller":"traceutil/trace.go:171","msg":"trace[1546095672] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"276.093417ms","start":"2026-05-06T05:51:45.784077Z","end":"2026-05-06T05:51:46.06017Z","steps":["trace[1546095672] 'process raft request' (duration: 275.904543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.060322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.433339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:46.060362Z","caller":"traceutil/trace.go:171","msg":"trace[47076156] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24840; }","duration":"222.526621ms","start":"2026-05-06T05:51:45.837822Z","end":"2026-05-06T05:51:46.060349Z","steps":["trace[47076156] 'agreement among raft nodes before linearized reading' (duration: 222.402399ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:46.228119Z","caller":"traceutil/trace.go:171","msg":"trace[647154247] transaction","detail":"{read_only:false; response_revision:24842; number_of_response:1; }","duration":"342.819504ms","start":"2026-05-06T05:51:45.885278Z","end":"2026-05-06T05:51:46.228097Z","steps":["trace[647154247] 'process raft request' (duration: 342.737702ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:46.228184Z","caller":"traceutil/trace.go:171","msg":"trace[744544008] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"364.884537ms","start":"2026-05-06T05:51:45.863271Z","end":"2026-05-06T05:51:46.228155Z","steps":["trace[744544008] 'process raft request' (duration: 348.978338ms)","trace[744544008] 'compare' (duration: 15.644664ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:46.228254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:45.885259Z","time spent":"342.925107ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:46.228328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:45.863251Z","time spent":"364.993339ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:46.228426Z","caller":"traceutil/trace.go:171","msg":"trace[47488190] linearizableReadLoop","detail":"{readStateIndex:25649; appliedIndex:25643; }","duration":"168.203529ms","start":"2026-05-06T05:51:46.060203Z","end":"2026-05-06T05:51:46.228407Z","steps":["trace[47488190] 'read index received' (duration: 152.077614ms)","trace[47488190] 'applied index is now lower than readState.Index' (duration: 16.125345ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:46.228423Z","caller":"traceutil/trace.go:171","msg":"trace[2040345542] transaction","detail":"{read_only:false; response_revision:24843; number_of_response:1; }","duration":"325.072065ms","start":"2026-05-06T05:51:45.903301Z","end":"2026-05-06T05:51:46.228373Z","steps":["trace[2040345542] 'process raft request' (duration: 324.759048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.228601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.27231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T05:51:46.228624Z","caller":"traceutil/trace.go:171","msg":"trace[1375644916] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:24846; }","duration":"197.334672ms","start":"2026-05-06T05:51:46.031283Z","end":"2026-05-06T05:51:46.228618Z","steps":["trace[1375644916] 'agreement among raft nodes before linearized reading' (duration: 197.189049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.228628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:45.903278Z","time spent":"325.229419ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:46.228753Z","caller":"traceutil/trace.go:171","msg":"trace[1247044846] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"324.626276ms","start":"2026-05-06T05:51:45.904121Z","end":"2026-05-06T05:51:46.228747Z","steps":["trace[1247044846] 'process raft request' (duration: 324.163885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.228805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:45.9041Z","time spent":"324.670097ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:46.228851Z","caller":"traceutil/trace.go:171","msg":"trace[1730029270] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"324.108295ms","start":"2026-05-06T05:51:45.904738Z","end":"2026-05-06T05:51:46.228847Z","steps":["trace[1730029270] 'process raft request' (duration: 323.619814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.228878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:45.904723Z","time spent":"324.141085ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:46.228918Z","caller":"traceutil/trace.go:171","msg":"trace[346238807] transaction","detail":"{read_only:false; response_revision:24846; number_of_response:1; }","duration":"200.778326ms","start":"2026-05-06T05:51:46.028136Z","end":"2026-05-06T05:51:46.228914Z","steps":["trace[346238807] 'process raft request' (duration: 200.247154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.229001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.453266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-06T05:51:46.229018Z","caller":"traceutil/trace.go:171","msg":"trace[1866097682] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24846; }","duration":"169.514757ms","start":"2026-05-06T05:51:46.059498Z","end":"2026-05-06T05:51:46.229013Z","steps":["trace[1866097682] 'agreement among raft nodes before linearized reading' (duration: 169.452766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.229086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.462887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-06T05:51:46.229101Z","caller":"traceutil/trace.go:171","msg":"trace[1731601960] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24846; }","duration":"170.499528ms","start":"2026-05-06T05:51:46.058598Z","end":"2026-05-06T05:51:46.229098Z","steps":["trace[1731601960] 'agreement among raft nodes before linearized reading' (duration: 170.459497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.229145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.858208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:51:46.229159Z","caller":"traceutil/trace.go:171","msg":"trace[927944247] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24846; }","duration":"186.891348ms","start":"2026-05-06T05:51:46.042264Z","end":"2026-05-06T05:51:46.229155Z","steps":["trace[927944247] 'agreement among raft nodes before linearized reading' (duration: 186.866428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.544193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.629716ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:46.544392Z","caller":"traceutil/trace.go:171","msg":"trace[561459224] linearizableReadLoop","detail":"{readStateIndex:25651; appliedIndex:25649; }","duration":"236.507621ms","start":"2026-05-06T05:51:46.307865Z","end":"2026-05-06T05:51:46.544373Z","steps":["trace[561459224] 'read index received' (duration: 111.768882ms)","trace[561459224] 'applied index is now lower than readState.Index' (duration: 124.737629ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:46.544393Z","caller":"traceutil/trace.go:171","msg":"trace[233350143] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"310.231489ms","start":"2026-05-06T05:51:46.23414Z","end":"2026-05-06T05:51:46.544371Z","steps":["trace[233350143] 'process raft request' (duration: 185.347697ms)","trace[233350143] 'compare' (duration: 124.466543ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:46.544462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:46.234119Z","time spent":"310.31284ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:46.544648Z","caller":"traceutil/trace.go:171","msg":"trace[1040256342] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"310.34441ms","start":"2026-05-06T05:51:46.234295Z","end":"2026-05-06T05:51:46.544639Z","steps":["trace[1040256342] 'process raft request' (duration: 310.003883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:46.544753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:46.234272Z","time spent":"310.457073ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:46.544808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.96217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:46.544832Z","caller":"traceutil/trace.go:171","msg":"trace[509570620] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:24848; }","duration":"237.029081ms","start":"2026-05-06T05:51:46.307795Z","end":"2026-05-06T05:51:46.544824Z","steps":["trace[509570620] 'agreement among raft nodes before linearized reading' (duration: 236.99273ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:46.699606Z","caller":"traceutil/trace.go:171","msg":"trace[1021665687] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"150.660904ms","start":"2026-05-06T05:51:46.548919Z","end":"2026-05-06T05:51:46.69958Z","steps":["trace[1021665687] 'process raft request' (duration: 148.270373ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:46.900095Z","caller":"traceutil/trace.go:171","msg":"trace[1659167535] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"195.612876ms","start":"2026-05-06T05:51:46.704441Z","end":"2026-05-06T05:51:46.900054Z","steps":["trace[1659167535] 'process raft request' (duration: 114.125993ms)","trace[1659167535] 'compare' (duration: 81.129496ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:47.187223Z","caller":"traceutil/trace.go:171","msg":"trace[137268002] transaction","detail":"{read_only:false; response_revision:24851; number_of_response:1; }","duration":"481.911192ms","start":"2026-05-06T05:51:46.705297Z","end":"2026-05-06T05:51:47.187208Z","steps":["trace[137268002] 'process raft request' (duration: 469.841993ms)","trace[137268002] 'compare' (duration: 11.64394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:47.187349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:46.705277Z","time spent":"482.005613ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:47.187485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.373598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:47.187564Z","caller":"traceutil/trace.go:171","msg":"trace[1161411516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24851; }","duration":"322.499291ms","start":"2026-05-06T05:51:46.865047Z","end":"2026-05-06T05:51:47.187546Z","steps":["trace[1161411516] 'agreement among raft nodes before linearized reading' (duration: 322.362248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:47.187607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:46.865026Z","time spent":"322.573142ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:51:47.187796Z","caller":"traceutil/trace.go:171","msg":"trace[466653743] linearizableReadLoop","detail":"{readStateIndex:25654; appliedIndex:25653; }","duration":"322.066852ms","start":"2026-05-06T05:51:46.865091Z","end":"2026-05-06T05:51:47.187158Z","steps":["trace[466653743] 'read index received' (duration: 309.909921ms)","trace[466653743] 'applied index is now lower than readState.Index' (duration: 12.155461ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:47.187913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.464379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:47.187945Z","caller":"traceutil/trace.go:171","msg":"trace[924196509] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:24851; }","duration":"322.529921ms","start":"2026-05-06T05:51:46.865404Z","end":"2026-05-06T05:51:47.187934Z","steps":["trace[924196509] 'agreement among raft nodes before linearized reading' (duration: 322.46795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:47.187971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:46.865388Z","time spent":"322.572912ms","remote":"127.0.0.1:36248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:51:47.188127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.159811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T05:51:47.188218Z","caller":"traceutil/trace.go:171","msg":"trace[890174658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24851; }","duration":"299.276623ms","start":"2026-05-06T05:51:46.888917Z","end":"2026-05-06T05:51:47.188194Z","steps":["trace[890174658] 'agreement among raft nodes before linearized reading' (duration: 298.977417ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:47.53349Z","caller":"traceutil/trace.go:171","msg":"trace[1686642202] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"147.771181ms","start":"2026-05-06T05:51:47.385682Z","end":"2026-05-06T05:51:47.533453Z","steps":["trace[1686642202] 'process raft request' (duration: 103.609596ms)","trace[1686642202] 'compare' (duration: 43.959121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:47.84172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.806537ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:47.84189Z","caller":"traceutil/trace.go:171","msg":"trace[1818722118] transaction","detail":"{read_only:false; response_revision:24854; number_of_response:1; }","duration":"242.038218ms","start":"2026-05-06T05:51:47.599831Z","end":"2026-05-06T05:51:47.841869Z","steps":["trace[1818722118] 'process raft request' (duration: 47.975957ms)","trace[1818722118] 'compare' (duration: 193.451178ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:47.847769Z","caller":"traceutil/trace.go:171","msg":"trace[2018663512] transaction","detail":"{read_only:false; response_revision:24855; number_of_response:1; }","duration":"109.950162ms","start":"2026-05-06T05:51:47.737759Z","end":"2026-05-06T05:51:47.847709Z","steps":["trace[2018663512] 'process raft request' (duration: 109.779148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.293169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.780611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268865"} {"level":"info","ts":"2026-05-06T05:51:48.293256Z","caller":"traceutil/trace.go:171","msg":"trace[898465124] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:24855; }","duration":"347.910214ms","start":"2026-05-06T05:51:47.945328Z","end":"2026-05-06T05:51:48.293238Z","steps":["trace[898465124] 'range keys from in-memory index tree' (duration: 346.601796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.293301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:47.945309Z","time spent":"347.978025ms","remote":"127.0.0.1:52608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":31,"response size":268887,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"info","ts":"2026-05-06T05:51:48.509999Z","caller":"traceutil/trace.go:171","msg":"trace[1196464018] transaction","detail":"{read_only:false; response_revision:24856; number_of_response:1; }","duration":"273.953661ms","start":"2026-05-06T05:51:48.235997Z","end":"2026-05-06T05:51:48.50995Z","steps":["trace[1196464018] 'process raft request' (duration: 273.789668ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.752651Z","caller":"traceutil/trace.go:171","msg":"trace[949885153] linearizableReadLoop","detail":"{readStateIndex:25664; appliedIndex:25660; }","duration":"516.031442ms","start":"2026-05-06T05:51:48.2366Z","end":"2026-05-06T05:51:48.752631Z","steps":["trace[949885153] 'read index received' (duration: 273.661356ms)","trace[949885153] 'applied index is now lower than readState.Index' (duration: 242.368896ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:48.752774Z","caller":"traceutil/trace.go:171","msg":"trace[893535184] transaction","detail":"{read_only:false; response_revision:24859; number_of_response:1; }","duration":"516.572053ms","start":"2026-05-06T05:51:48.236192Z","end":"2026-05-06T05:51:48.752764Z","steps":["trace[893535184] 'process raft request' (duration: 516.329147ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.752772Z","caller":"traceutil/trace.go:171","msg":"trace[1019372064] transaction","detail":"{read_only:false; response_revision:24858; number_of_response:1; }","duration":"516.590024ms","start":"2026-05-06T05:51:48.23615Z","end":"2026-05-06T05:51:48.75274Z","steps":["trace[1019372064] 'process raft request' (duration: 516.329567ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.752832Z","caller":"traceutil/trace.go:171","msg":"trace[2068850770] transaction","detail":"{read_only:false; response_revision:24861; number_of_response:1; }","duration":"515.099631ms","start":"2026-05-06T05:51:48.237698Z","end":"2026-05-06T05:51:48.752798Z","steps":["trace[2068850770] 'process raft request' (duration: 514.890506ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.752915Z","caller":"traceutil/trace.go:171","msg":"trace[822371565] transaction","detail":"{read_only:false; response_revision:24860; number_of_response:1; }","duration":"516.581463ms","start":"2026-05-06T05:51:48.236321Z","end":"2026-05-06T05:51:48.752902Z","steps":["trace[822371565] 'process raft request' (duration: 516.236755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.752929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.236141Z","time spent":"516.705586ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:48.752952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.237689Z","time spent":"515.200574ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:48.752973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.338349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:48.753042Z","caller":"traceutil/trace.go:171","msg":"trace[1671323854] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24861; }","duration":"516.452991ms","start":"2026-05-06T05:51:48.236572Z","end":"2026-05-06T05:51:48.753025Z","steps":["trace[1671323854] 'agreement among raft nodes before linearized reading' (duration: 516.303918ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.75298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.236313Z","time spent":"516.633545ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:48.753077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.236563Z","time spent":"516.506192ms","remote":"127.0.0.1:38396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-05-06T05:51:48.75309Z","caller":"traceutil/trace.go:171","msg":"trace[707624407] transaction","detail":"{read_only:false; response_revision:24857; number_of_response:1; }","duration":"516.994263ms","start":"2026-05-06T05:51:48.23607Z","end":"2026-05-06T05:51:48.753065Z","steps":["trace[707624407] 'process raft request' (duration: 516.236946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.753143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.236047Z","time spent":"517.067234ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:48.753249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:48.236186Z","time spent":"516.616064ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:48.761922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.163991ms","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-05-06T05:51:48.761955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.670941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-05-06T05:51:48.76198Z","caller":"traceutil/trace.go:171","msg":"trace[1787526914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24862; }","duration":"213.249873ms","start":"2026-05-06T05:51:48.548712Z","end":"2026-05-06T05:51:48.761962Z","steps":["trace[1787526914] 'agreement among raft nodes before linearized reading' (duration: 213.09796ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.762Z","caller":"traceutil/trace.go:171","msg":"trace[1345684126] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24862; }","duration":"213.748353ms","start":"2026-05-06T05:51:48.548238Z","end":"2026-05-06T05:51:48.761987Z","steps":["trace[1345684126] 'agreement among raft nodes before linearized reading' (duration: 213.629171ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.997389Z","caller":"traceutil/trace.go:171","msg":"trace[5202388] linearizableReadLoop","detail":"{readStateIndex:25669; appliedIndex:25666; }","duration":"229.060922ms","start":"2026-05-06T05:51:48.768311Z","end":"2026-05-06T05:51:48.997372Z","steps":["trace[5202388] 'read index received' (duration: 142.52591ms)","trace[5202388] 'applied index is now lower than readState.Index' (duration: 86.534352ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:48.997656Z","caller":"traceutil/trace.go:171","msg":"trace[539833407] transaction","detail":"{read_only:false; response_revision:24863; number_of_response:1; }","duration":"232.012024ms","start":"2026-05-06T05:51:48.765633Z","end":"2026-05-06T05:51:48.997645Z","steps":["trace[539833407] 'process raft request' (duration: 145.188977ms)","trace[539833407] 'compare' (duration: 86.362768ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:48.997789Z","caller":"traceutil/trace.go:171","msg":"trace[313528110] transaction","detail":"{read_only:false; response_revision:24864; number_of_response:1; }","duration":"230.985751ms","start":"2026-05-06T05:51:48.766798Z","end":"2026-05-06T05:51:48.997784Z","steps":["trace[313528110] 'process raft request' (duration: 230.522312ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:48.998116Z","caller":"traceutil/trace.go:171","msg":"trace[451633209] transaction","detail":"{read_only:false; response_revision:24865; number_of_response:1; }","duration":"230.793217ms","start":"2026-05-06T05:51:48.767317Z","end":"2026-05-06T05:51:48.99811Z","steps":["trace[451633209] 'process raft request' (duration: 230.032391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.998412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.085403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:48.998434Z","caller":"traceutil/trace.go:171","msg":"trace[376247535] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24865; }","duration":"230.135564ms","start":"2026-05-06T05:51:48.768293Z","end":"2026-05-06T05:51:48.998428Z","steps":["trace[376247535] 'agreement among raft nodes before linearized reading' (duration: 230.085483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:48.99852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.656004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:48.998534Z","caller":"traceutil/trace.go:171","msg":"trace[295208628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24865; }","duration":"136.697725ms","start":"2026-05-06T05:51:48.861833Z","end":"2026-05-06T05:51:48.998531Z","steps":["trace[295208628] 'agreement among raft nodes before linearized reading' (duration: 136.674554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.734567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.563205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:51:49.734629Z","caller":"traceutil/trace.go:171","msg":"trace[1423826831] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:24866; }","duration":"568.823741ms","start":"2026-05-06T05:51:49.165792Z","end":"2026-05-06T05:51:49.734616Z","steps":["trace[1423826831] 'count revisions from in-memory index tree' (duration: 568.481983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.734655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.16577Z","time spent":"568.877742ms","remote":"127.0.0.1:52508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:51:49.734683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.036464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-05-06T05:51:49.734714Z","caller":"traceutil/trace.go:171","msg":"trace[1876698640] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:24866; }","duration":"626.076656ms","start":"2026-05-06T05:51:49.108629Z","end":"2026-05-06T05:51:49.734706Z","steps":["trace[1876698640] 'range keys from in-memory index tree' (duration: 625.934373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.734736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.108624Z","time spent":"626.106176ms","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1888,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" "} {"level":"warn","ts":"2026-05-06T05:51:49.734942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.414358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-05-06T05:51:49.734973Z","caller":"traceutil/trace.go:171","msg":"trace[1656156396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24866; }","duration":"543.485999ms","start":"2026-05-06T05:51:49.191479Z","end":"2026-05-06T05:51:49.734965Z","steps":["trace[1656156396] 'range keys from in-memory index tree' (duration: 543.279384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.734993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.191459Z","time spent":"543.52908ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:51:49.735463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.55365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-05-06T05:51:49.735492Z","caller":"traceutil/trace.go:171","msg":"trace[1048740444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24866; }","duration":"507.60393ms","start":"2026-05-06T05:51:49.22788Z","end":"2026-05-06T05:51:49.735484Z","steps":["trace[1048740444] 'range keys from in-memory index tree' (duration: 507.472868ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.735512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.227865Z","time spent":"507.641792ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:51:49.734567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"626.211139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:49.735753Z","caller":"traceutil/trace.go:171","msg":"trace[771513079] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:24866; }","duration":"627.382964ms","start":"2026-05-06T05:51:49.108325Z","end":"2026-05-06T05:51:49.735708Z","steps":["trace[771513079] 'range keys from in-memory index tree' (duration: 626.156978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:49.735822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.108119Z","time spent":"627.67866ms","remote":"127.0.0.1:52262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-05-06T05:51:50.091698Z","caller":"traceutil/trace.go:171","msg":"trace[909671779] linearizableReadLoop","detail":"{readStateIndex:25671; appliedIndex:25670; }","duration":"351.241105ms","start":"2026-05-06T05:51:49.740433Z","end":"2026-05-06T05:51:50.091675Z","steps":["trace[909671779] 'read index received' (duration: 351.124183ms)","trace[909671779] 'applied index is now lower than readState.Index' (duration: 116.042µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:50.09177Z","caller":"traceutil/trace.go:171","msg":"trace[1618251466] transaction","detail":"{read_only:false; response_revision:24867; number_of_response:1; }","duration":"351.336387ms","start":"2026-05-06T05:51:49.740384Z","end":"2026-05-06T05:51:50.09172Z","steps":["trace[1618251466] 'process raft request' (duration: 351.133703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.091919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.474041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-9d32be29-d325-41ee-b0d4-4b1aeee048b7\" ","response":"range_response_count:1 size:1323"} {"level":"warn","ts":"2026-05-06T05:51:50.09193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.740369Z","time spent":"351.43408ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:50.09196Z","caller":"traceutil/trace.go:171","msg":"trace[186443215] range","detail":"{range_begin:/registry/persistentvolumes/pvc-9d32be29-d325-41ee-b0d4-4b1aeee048b7; range_end:; response_count:1; response_revision:24867; }","duration":"351.538912ms","start":"2026-05-06T05:51:49.740409Z","end":"2026-05-06T05:51:50.091948Z","steps":["trace[186443215] 'agreement among raft nodes before linearized reading' (duration: 351.401569ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.09199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.740363Z","time spent":"351.619274ms","remote":"127.0.0.1:52308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-9d32be29-d325-41ee-b0d4-4b1aeee048b7\" "} {"level":"warn","ts":"2026-05-06T05:51:50.543193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.988025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:50.543401Z","caller":"traceutil/trace.go:171","msg":"trace[1566772496] linearizableReadLoop","detail":"{readStateIndex:25673; appliedIndex:25671; }","duration":"451.603942ms","start":"2026-05-06T05:51:50.091783Z","end":"2026-05-06T05:51:50.543387Z","steps":["trace[1566772496] 'read index received' (duration: 82.267999ms)","trace[1566772496] 'applied index is now lower than readState.Index' (duration: 369.335083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:50.543622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.118971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:50.54366Z","caller":"traceutil/trace.go:171","msg":"trace[886980786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24869; }","duration":"679.181553ms","start":"2026-05-06T05:51:49.864469Z","end":"2026-05-06T05:51:50.54365Z","steps":["trace[886980786] 'agreement among raft nodes before linearized reading' (duration: 679.124701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.543694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.86446Z","time spent":"679.227403ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:51:50.543684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.943009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-06T05:51:50.543755Z","caller":"traceutil/trace.go:171","msg":"trace[1319870545] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24869; }","duration":"566.050362ms","start":"2026-05-06T05:51:49.977691Z","end":"2026-05-06T05:51:50.543741Z","steps":["trace[1319870545] 'agreement among raft nodes before linearized reading' (duration: 565.874418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.543792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.97768Z","time spent":"566.103612ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-06T05:51:50.543821Z","caller":"traceutil/trace.go:171","msg":"trace[364133170] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"803.38111ms","start":"2026-05-06T05:51:49.740406Z","end":"2026-05-06T05:51:50.543787Z","steps":["trace[364133170] 'process raft request' (duration: 433.71974ms)","trace[364133170] 'compare' (duration: 368.817162ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:50.543868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.44341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:685"} {"level":"info","ts":"2026-05-06T05:51:50.543907Z","caller":"traceutil/trace.go:171","msg":"trace[1338654461] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:24869; }","duration":"802.496121ms","start":"2026-05-06T05:51:49.741401Z","end":"2026-05-06T05:51:50.543897Z","steps":["trace[1338654461] 'agreement among raft nodes before linearized reading' (duration: 802.388899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.543934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.741394Z","time spent":"802.532892ms","remote":"127.0.0.1:52322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":707,"request content":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"warn","ts":"2026-05-06T05:51:50.543945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.740389Z","time spent":"803.487892ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:50.543957Z","caller":"traceutil/trace.go:171","msg":"trace[445644986] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"697.137916ms","start":"2026-05-06T05:51:49.84681Z","end":"2026-05-06T05:51:50.543947Z","steps":["trace[445644986] 'process raft request' (duration: 696.469142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:50.544025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:49.846789Z","time spent":"697.192888ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:51.201913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.011766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:51.201993Z","caller":"traceutil/trace.go:171","msg":"trace[9530367] linearizableReadLoop","detail":"{readStateIndex:25674; appliedIndex:25673; }","duration":"658.550861ms","start":"2026-05-06T05:51:50.543428Z","end":"2026-05-06T05:51:51.201979Z","steps":["trace[9530367] 'read index received' (duration: 375.469444ms)","trace[9530367] 'applied index is now lower than readState.Index' (duration: 283.080357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:51.202201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105957994s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-05-06T05:51:51.202221Z","caller":"traceutil/trace.go:171","msg":"trace[1021394763] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:24870; }","duration":"1.106010566s","start":"2026-05-06T05:51:50.096202Z","end":"2026-05-06T05:51:51.202212Z","steps":["trace[1021394763] 'agreement among raft nodes before linearized reading' (duration: 1.105921004s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.202194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.268269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:51:51.202243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.096178Z","time spent":"1.106059776s","remote":"127.0.0.1:52314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1872,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"info","ts":"2026-05-06T05:51:51.20224Z","caller":"traceutil/trace.go:171","msg":"trace[1806875543] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"686.696793ms","start":"2026-05-06T05:51:50.515517Z","end":"2026-05-06T05:51:51.202214Z","steps":["trace[1806875543] 'process raft request' (duration: 403.306279ms)","trace[1806875543] 'compare' (duration: 282.579847ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:51.202262Z","caller":"traceutil/trace.go:171","msg":"trace[793164168] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:24870; }","duration":"718.387521ms","start":"2026-05-06T05:51:50.483859Z","end":"2026-05-06T05:51:51.202246Z","steps":["trace[793164168] 'agreement among raft nodes before linearized reading' (duration: 718.214168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.202318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.483836Z","time spent":"718.463264ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":22,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:51:51.202335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.515493Z","time spent":"686.796385ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:51.282989Z","caller":"traceutil/trace.go:171","msg":"trace[1619262785] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"524.38985ms","start":"2026-05-06T05:51:50.758575Z","end":"2026-05-06T05:51:51.282965Z","steps":["trace[1619262785] 'process raft request' (duration: 524.312098ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283024Z","caller":"traceutil/trace.go:171","msg":"trace[355502172] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"524.464111ms","start":"2026-05-06T05:51:50.758529Z","end":"2026-05-06T05:51:51.282993Z","steps":["trace[355502172] 'process raft request' (duration: 524.215896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.28311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.758551Z","time spent":"524.499642ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:51.283176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.758505Z","time spent":"524.585785ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:51.283328Z","caller":"traceutil/trace.go:171","msg":"trace[1805068497] transaction","detail":"{read_only:false; response_revision:24873; number_of_response:1; }","duration":"524.716767ms","start":"2026-05-06T05:51:50.758575Z","end":"2026-05-06T05:51:51.283292Z","steps":["trace[1805068497] 'process raft request' (duration: 524.355179ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283381Z","caller":"traceutil/trace.go:171","msg":"trace[104803941] transaction","detail":"{read_only:false; response_revision:24874; number_of_response:1; }","duration":"524.748267ms","start":"2026-05-06T05:51:50.758619Z","end":"2026-05-06T05:51:51.283368Z","steps":["trace[104803941] 'process raft request' (duration: 524.612044ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283428Z","caller":"traceutil/trace.go:171","msg":"trace[1486433747] transaction","detail":"{read_only:false; response_revision:24875; number_of_response:1; }","duration":"522.528481ms","start":"2026-05-06T05:51:50.760883Z","end":"2026-05-06T05:51:51.283411Z","steps":["trace[1486433747] 'process raft request' (duration: 522.442989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.283449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.7586Z","time spent":"524.813419ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:51.283486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.758552Z","time spent":"524.86082ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:51.28352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.599513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-06T05:51:51.283538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.760869Z","time spent":"522.601412ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:51.283524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.826639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:51.283554Z","caller":"traceutil/trace.go:171","msg":"trace[435414366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24875; }","duration":"279.658894ms","start":"2026-05-06T05:51:51.003886Z","end":"2026-05-06T05:51:51.283545Z","steps":["trace[435414366] 'agreement among raft nodes before linearized reading' (duration: 279.538961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.283555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.893889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-05-06T05:51:51.283536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.369185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-06T05:51:51.283583Z","caller":"traceutil/trace.go:171","msg":"trace[1158649764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24875; }","duration":"738.9208ms","start":"2026-05-06T05:51:50.544649Z","end":"2026-05-06T05:51:51.28357Z","steps":["trace[1158649764] 'agreement among raft nodes before linearized reading' (duration: 738.805218ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283595Z","caller":"traceutil/trace.go:171","msg":"trace[317494000] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24875; }","duration":"279.973151ms","start":"2026-05-06T05:51:51.003607Z","end":"2026-05-06T05:51:51.283581Z","steps":["trace[317494000] 'agreement among raft nodes before linearized reading' (duration: 279.845558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.28359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.655645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-05-06T05:51:51.283606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.960997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:685"} {"level":"warn","ts":"2026-05-06T05:51:51.283612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.544635Z","time spent":"738.971302ms","remote":"127.0.0.1:52204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:51:51.283626Z","caller":"traceutil/trace.go:171","msg":"trace[1369641125] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24875; }","duration":"280.733316ms","start":"2026-05-06T05:51:51.002884Z","end":"2026-05-06T05:51:51.283617Z","steps":["trace[1369641125] 'agreement among raft nodes before linearized reading' (duration: 280.591703ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283614Z","caller":"traceutil/trace.go:171","msg":"trace[1766495486] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24875; }","duration":"735.454507ms","start":"2026-05-06T05:51:50.548143Z","end":"2026-05-06T05:51:51.283597Z","steps":["trace[1766495486] 'agreement among raft nodes before linearized reading' (duration: 735.268683ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:51.283641Z","caller":"traceutil/trace.go:171","msg":"trace[778291795] range","detail":"{range_begin:/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:24875; }","duration":"736.014429ms","start":"2026-05-06T05:51:50.547612Z","end":"2026-05-06T05:51:51.283627Z","steps":["trace[778291795] 'agreement among raft nodes before linearized reading' (duration: 735.916297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.283657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.548112Z","time spent":"735.536448ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-06T05:51:51.283673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:50.5476Z","time spent":"736.065719ms","remote":"127.0.0.1:52322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":707,"request content":"key:\"/registry/services/endpoints/kube-system/kube-prometheus-stack-kubelet\" "} {"level":"warn","ts":"2026-05-06T05:51:51.283675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.115482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T05:51:51.283695Z","caller":"traceutil/trace.go:171","msg":"trace[1209678878] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24875; }","duration":"186.183863ms","start":"2026-05-06T05:51:51.097505Z","end":"2026-05-06T05:51:51.283689Z","steps":["trace[1209678878] 'agreement among raft nodes before linearized reading' (duration: 186.114601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:51.739197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.852212ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:51.739323Z","caller":"traceutil/trace.go:171","msg":"trace[342616270] transaction","detail":"{read_only:false; response_revision:24876; number_of_response:1; }","duration":"452.303779ms","start":"2026-05-06T05:51:51.286992Z","end":"2026-05-06T05:51:51.739296Z","steps":["trace[342616270] 'process raft request' (duration: 184.278693ms)","trace[342616270] 'compare' (duration: 267.535805ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:51.739398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.28696Z","time spent":"452.40541ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":488,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:51:52.221784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.526583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:51:52.221982Z","caller":"traceutil/trace.go:171","msg":"trace[2044085455] linearizableReadLoop","detail":"{readStateIndex:25683; appliedIndex:25679; }","duration":"928.376544ms","start":"2026-05-06T05:51:51.29359Z","end":"2026-05-06T05:51:52.221967Z","steps":["trace[2044085455] 'read index received' (duration: 177.690822ms)","trace[2044085455] 'applied index is now lower than readState.Index' (duration: 750.684422ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:52.222056Z","caller":"traceutil/trace.go:171","msg":"trace[688968358] transaction","detail":"{read_only:false; response_revision:24877; number_of_response:1; }","duration":"934.416294ms","start":"2026-05-06T05:51:51.287628Z","end":"2026-05-06T05:51:52.222044Z","steps":["trace[688968358] 'process raft request' (duration: 631.509492ms)","trace[688968358] 'compare' (duration: 302.431312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:52.222115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.287612Z","time spent":"934.472945ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:52.222289Z","caller":"traceutil/trace.go:171","msg":"trace[708963041] transaction","detail":"{read_only:false; response_revision:24878; number_of_response:1; }","duration":"934.108886ms","start":"2026-05-06T05:51:51.288148Z","end":"2026-05-06T05:51:52.222257Z","steps":["trace[708963041] 'process raft request' (duration: 933.723778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.222333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.730201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:52.222436Z","caller":"traceutil/trace.go:171","msg":"trace[967298065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24879; }","duration":"928.863756ms","start":"2026-05-06T05:51:51.293558Z","end":"2026-05-06T05:51:52.222421Z","steps":["trace[967298065] 'agreement among raft nodes before linearized reading' (duration: 928.703952ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.222445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.288139Z","time spent":"934.217629ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:52.222407Z","caller":"traceutil/trace.go:171","msg":"trace[1079019532] transaction","detail":"{read_only:false; response_revision:24879; number_of_response:1; }","duration":"933.888142ms","start":"2026-05-06T05:51:51.288458Z","end":"2026-05-06T05:51:52.222346Z","steps":["trace[1079019532] 'process raft request' (duration: 933.471733ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.222574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.293541Z","time spent":"928.923856ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:51:52.223039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.288446Z","time spent":"934.076156ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:52.286739Z","caller":"traceutil/trace.go:171","msg":"trace[2093537876] transaction","detail":"{read_only:false; response_revision:24880; number_of_response:1; }","duration":"203.685748ms","start":"2026-05-06T05:51:52.083029Z","end":"2026-05-06T05:51:52.286715Z","steps":["trace[2093537876] 'process raft request' (duration: 203.493784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.286799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.969612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"warn","ts":"2026-05-06T05:51:52.286819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.629698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:51:52.286754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.219662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:52.286858Z","caller":"traceutil/trace.go:171","msg":"trace[260542966] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24880; }","duration":"934.69068ms","start":"2026-05-06T05:51:51.352155Z","end":"2026-05-06T05:51:52.286846Z","steps":["trace[260542966] 'agreement among raft nodes before linearized reading' (duration: 934.637919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.28688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.743221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-05-06T05:51:52.286896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.35214Z","time spent":"934.748631ms","remote":"127.0.0.1:52608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-05-06T05:51:52.28684Z","caller":"traceutil/trace.go:171","msg":"trace[981596388] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24880; }","duration":"639.041094ms","start":"2026-05-06T05:51:51.64779Z","end":"2026-05-06T05:51:52.286831Z","steps":["trace[981596388] 'agreement among raft nodes before linearized reading' (duration: 638.88484ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:52.286938Z","caller":"traceutil/trace.go:171","msg":"trace[469828538] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24880; }","duration":"190.844904ms","start":"2026-05-06T05:51:52.09608Z","end":"2026-05-06T05:51:52.286925Z","steps":["trace[469828538] 'agreement among raft nodes before linearized reading' (duration: 190.65343ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:52.286921Z","caller":"traceutil/trace.go:171","msg":"trace[2077779326] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:24880; }","duration":"678.410446ms","start":"2026-05-06T05:51:51.608469Z","end":"2026-05-06T05:51:52.28688Z","steps":["trace[2077779326] 'agreement among raft nodes before linearized reading' (duration: 678.211962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.286963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.647774Z","time spent":"639.182226ms","remote":"127.0.0.1:52276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":379,"request content":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" "} {"level":"warn","ts":"2026-05-06T05:51:52.287047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:51.608441Z","time spent":"678.554358ms","remote":"127.0.0.1:52286","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:51:52.671011Z","caller":"traceutil/trace.go:171","msg":"trace[1410226621] transaction","detail":"{read_only:false; response_revision:24881; number_of_response:1; }","duration":"378.669522ms","start":"2026-05-06T05:51:52.292309Z","end":"2026-05-06T05:51:52.670979Z","steps":["trace[1410226621] 'process raft request' (duration: 296.483384ms)","trace[1410226621] 'compare' (duration: 82.040535ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:52.671145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:52.292297Z","time spent":"378.779924ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:52.671016Z","caller":"traceutil/trace.go:171","msg":"trace[939214747] linearizableReadLoop","detail":"{readStateIndex:25685; appliedIndex:25684; }","duration":"377.982987ms","start":"2026-05-06T05:51:52.293011Z","end":"2026-05-06T05:51:52.670994Z","steps":["trace[939214747] 'read index received' (duration: 295.772609ms)","trace[939214747] 'applied index is now lower than readState.Index' (duration: 82.209488ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:52.671371Z","caller":"traceutil/trace.go:171","msg":"trace[456750350] transaction","detail":"{read_only:false; response_revision:24882; number_of_response:1; }","duration":"123.283918ms","start":"2026-05-06T05:51:52.548073Z","end":"2026-05-06T05:51:52.671357Z","steps":["trace[456750350] 'process raft request' (duration: 122.879619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.671362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.28941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:51:52.671421Z","caller":"traceutil/trace.go:171","msg":"trace[521077561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24882; }","duration":"376.371043ms","start":"2026-05-06T05:51:52.295033Z","end":"2026-05-06T05:51:52.671404Z","steps":["trace[521077561] 'agreement among raft nodes before linearized reading' (duration: 376.24532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.671455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:52.295025Z","time spent":"376.423194ms","remote":"127.0.0.1:52202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:51:52.671492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.522774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-05-06T05:51:52.671504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.429824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.249\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-05-06T05:51:52.671532Z","caller":"traceutil/trace.go:171","msg":"trace[2147130274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24882; }","duration":"124.603675ms","start":"2026-05-06T05:51:52.546918Z","end":"2026-05-06T05:51:52.671521Z","steps":["trace[2147130274] 'agreement among raft nodes before linearized reading' (duration: 124.461502ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:52.67155Z","caller":"traceutil/trace.go:171","msg":"trace[656716095] range","detail":"{range_begin:/registry/masterleases/199.19.213.249; range_end:; response_count:1; response_revision:24882; }","duration":"119.512447ms","start":"2026-05-06T05:51:52.552026Z","end":"2026-05-06T05:51:52.671539Z","steps":["trace[656716095] 'agreement among raft nodes before linearized reading' (duration: 119.328563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.671506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.487328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-05-06T05:51:52.671609Z","caller":"traceutil/trace.go:171","msg":"trace[576554089] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24882; }","duration":"378.601771ms","start":"2026-05-06T05:51:52.292991Z","end":"2026-05-06T05:51:52.671593Z","steps":["trace[576554089] 'agreement among raft nodes before linearized reading' (duration: 378.06978ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:52.671658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:52.292985Z","time spent":"378.658592ms","remote":"127.0.0.1:52344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-06T05:51:52.953437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.949574ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-06T05:51:52.953535Z","caller":"traceutil/trace.go:171","msg":"trace[2114093647] linearizableReadLoop","detail":"{readStateIndex:25687; appliedIndex:25686; }","duration":"280.867799ms","start":"2026-05-06T05:51:52.672652Z","end":"2026-05-06T05:51:52.95352Z","steps":["trace[2114093647] 'read index received' (duration: 147.915445ms)","trace[2114093647] 'applied index is now lower than readState.Index' (duration: 132.951044ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:51:52.953666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.006032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-06T05:51:52.953698Z","caller":"traceutil/trace.go:171","msg":"trace[19938199] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24882; }","duration":"281.068904ms","start":"2026-05-06T05:51:52.672621Z","end":"2026-05-06T05:51:52.95369Z","steps":["trace[19938199] 'agreement among raft nodes before linearized reading' (duration: 280.940921ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.011921Z","caller":"traceutil/trace.go:171","msg":"trace[1527023668] transaction","detail":"{read_only:false; response_revision:24883; number_of_response:1; }","duration":"337.44891ms","start":"2026-05-06T05:51:52.674444Z","end":"2026-05-06T05:51:53.011893Z","steps":["trace[1527023668] 'process raft request' (duration: 337.298007ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:53.012148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:51:52.674424Z","time spent":"337.652115ms","remote":"127.0.0.1:52418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:51:53.044422Z","caller":"traceutil/trace.go:171","msg":"trace[1904005420] transaction","detail":"{read_only:false; response_revision:24884; number_of_response:1; }","duration":"191.460576ms","start":"2026-05-06T05:51:52.852935Z","end":"2026-05-06T05:51:53.044396Z","steps":["trace[1904005420] 'process raft request' (duration: 191.244542ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.439136Z","caller":"traceutil/trace.go:171","msg":"trace[471371341] transaction","detail":"{read_only:false; response_revision:24887; number_of_response:1; }","duration":"150.978501ms","start":"2026-05-06T05:51:53.288122Z","end":"2026-05-06T05:51:53.4391Z","steps":["trace[471371341] 'process raft request' (duration: 75.881085ms)","trace[471371341] 'compare' (duration: 74.882201ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:53.50802Z","caller":"traceutil/trace.go:171","msg":"trace[1091444661] transaction","detail":"{read_only:false; response_revision:24888; number_of_response:1; }","duration":"219.640429ms","start":"2026-05-06T05:51:53.288358Z","end":"2026-05-06T05:51:53.507998Z","steps":["trace[1091444661] 'process raft request' (duration: 219.481466ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.508347Z","caller":"traceutil/trace.go:171","msg":"trace[239312521] transaction","detail":"{read_only:false; response_revision:24889; number_of_response:1; }","duration":"218.815531ms","start":"2026-05-06T05:51:53.289508Z","end":"2026-05-06T05:51:53.508323Z","steps":["trace[239312521] 'process raft request' (duration: 218.454274ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.508523Z","caller":"traceutil/trace.go:171","msg":"trace[75493896] transaction","detail":"{read_only:false; response_revision:24891; number_of_response:1; }","duration":"218.483665ms","start":"2026-05-06T05:51:53.29001Z","end":"2026-05-06T05:51:53.508494Z","steps":["trace[75493896] 'process raft request' (duration: 218.302781ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.508454Z","caller":"traceutil/trace.go:171","msg":"trace[1621771418] transaction","detail":"{read_only:false; response_revision:24890; number_of_response:1; }","duration":"219.039587ms","start":"2026-05-06T05:51:53.289392Z","end":"2026-05-06T05:51:53.508432Z","steps":["trace[1621771418] 'process raft request' (duration: 218.798892ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:53.852697Z","caller":"traceutil/trace.go:171","msg":"trace[1012024590] transaction","detail":"{read_only:false; response_revision:24892; number_of_response:1; }","duration":"107.486599ms","start":"2026-05-06T05:51:53.745185Z","end":"2026-05-06T05:51:53.852672Z","steps":["trace[1012024590] 'process raft request' (duration: 107.324546ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:54.377513Z","caller":"traceutil/trace.go:171","msg":"trace[1932125750] transaction","detail":"{read_only:false; response_revision:24894; number_of_response:1; }","duration":"147.369733ms","start":"2026-05-06T05:51:54.2301Z","end":"2026-05-06T05:51:54.377469Z","steps":["trace[1932125750] 'process raft request' (duration: 147.213729ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:54.377634Z","caller":"traceutil/trace.go:171","msg":"trace[31165850] transaction","detail":"{read_only:false; response_revision:24895; number_of_response:1; }","duration":"147.408244ms","start":"2026-05-06T05:51:54.230206Z","end":"2026-05-06T05:51:54.377615Z","steps":["trace[31165850] 'process raft request' (duration: 147.23187ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:55.591925Z","caller":"traceutil/trace.go:171","msg":"trace[1768151330] linearizableReadLoop","detail":"{readStateIndex:25705; appliedIndex:25704; }","duration":"134.822715ms","start":"2026-05-06T05:51:55.457084Z","end":"2026-05-06T05:51:55.591907Z","steps":["trace[1768151330] 'read index received' (duration: 134.733683ms)","trace[1768151330] 'applied index is now lower than readState.Index' (duration: 88.012µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:55.592011Z","caller":"traceutil/trace.go:171","msg":"trace[1852719114] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"147.090108ms","start":"2026-05-06T05:51:55.444896Z","end":"2026-05-06T05:51:55.591986Z","steps":["trace[1852719114] 'process raft request' (duration: 146.896324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:51:55.592978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.891607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T05:51:55.593005Z","caller":"traceutil/trace.go:171","msg":"trace[1669928913] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:24900; }","duration":"135.947478ms","start":"2026-05-06T05:51:55.45705Z","end":"2026-05-06T05:51:55.592997Z","steps":["trace[1669928913] 'agreement among raft nodes before linearized reading' (duration: 134.922816ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:55.675574Z","caller":"traceutil/trace.go:171","msg":"trace[23772312] transaction","detail":"{read_only:false; response_revision:24901; number_of_response:1; }","duration":"162.6481ms","start":"2026-05-06T05:51:55.512901Z","end":"2026-05-06T05:51:55.675549Z","steps":["trace[23772312] 'process raft request' (duration: 151.100923ms)","trace[23772312] 'compare' (duration: 11.388633ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:51:55.675825Z","caller":"traceutil/trace.go:171","msg":"trace[1066599751] transaction","detail":"{read_only:false; response_revision:24902; number_of_response:1; }","duration":"162.64645ms","start":"2026-05-06T05:51:55.51316Z","end":"2026-05-06T05:51:55.675807Z","steps":["trace[1066599751] 'process raft request' (duration: 162.345564ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:55.675979Z","caller":"traceutil/trace.go:171","msg":"trace[1902259649] transaction","detail":"{read_only:false; response_revision:24903; number_of_response:1; }","duration":"162.316183ms","start":"2026-05-06T05:51:55.513644Z","end":"2026-05-06T05:51:55.67596Z","steps":["trace[1902259649] 'process raft request' (duration: 162.006747ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:51:55.676121Z","caller":"traceutil/trace.go:171","msg":"trace[676218294] transaction","detail":"{read_only:false; response_revision:24904; number_of_response:1; }","duration":"161.365703ms","start":"2026-05-06T05:51:55.514733Z","end":"2026-05-06T05:51:55.676099Z","steps":["trace[676218294] 'process raft request' (duration: 161.184019ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:52:18.285069Z","caller":"traceutil/trace.go:171","msg":"trace[1063600681] transaction","detail":"{read_only:false; response_revision:25079; number_of_response:1; }","duration":"224.598676ms","start":"2026-05-06T05:52:18.060452Z","end":"2026-05-06T05:52:18.28505Z","steps":["trace[1063600681] 'process raft request' (duration: 157.555672ms)","trace[1063600681] 'compare' (duration: 66.41928ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:52:18.28499Z","caller":"traceutil/trace.go:171","msg":"trace[1249979381] transaction","detail":"{read_only:false; response_revision:25080; number_of_response:1; }","duration":"201.113943ms","start":"2026-05-06T05:52:18.083847Z","end":"2026-05-06T05:52:18.284961Z","steps":["trace[1249979381] 'process raft request' (duration: 201.013581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:52:18.532646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.106802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:52:18.532737Z","caller":"traceutil/trace.go:171","msg":"trace[1904042507] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:25080; }","duration":"100.224464ms","start":"2026-05-06T05:52:18.43249Z","end":"2026-05-06T05:52:18.532714Z","steps":["trace[1904042507] 'count revisions from in-memory index tree' (duration: 100.03944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:52:18.532998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.668345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/manila-ssh-keys\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:52:18.533047Z","caller":"traceutil/trace.go:171","msg":"trace[2133284594] range","detail":"{range_begin:/registry/secrets/openstack/manila-ssh-keys; range_end:; response_count:0; response_revision:25080; }","duration":"136.740976ms","start":"2026-05-06T05:52:18.396289Z","end":"2026-05-06T05:52:18.53303Z","steps":["trace[2133284594] 'range keys from in-memory index tree' (duration: 136.600763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:52:18.533193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.358338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-06T05:52:18.533257Z","caller":"traceutil/trace.go:171","msg":"trace[221688453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25080; }","duration":"136.47482ms","start":"2026-05-06T05:52:18.396766Z","end":"2026-05-06T05:52:18.533241Z","steps":["trace[221688453] 'range keys from in-memory index tree' (duration: 136.287855ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:52:18.680257Z","caller":"traceutil/trace.go:171","msg":"trace[586646374] transaction","detail":"{read_only:false; response_revision:25082; number_of_response:1; }","duration":"142.824936ms","start":"2026-05-06T05:52:18.537407Z","end":"2026-05-06T05:52:18.680232Z","steps":["trace[586646374] 'process raft request' (duration: 142.304974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:52:18.680438Z","caller":"traceutil/trace.go:171","msg":"trace[1253408376] transaction","detail":"{read_only:false; response_revision:25083; number_of_response:1; }","duration":"137.078353ms","start":"2026-05-06T05:52:18.543328Z","end":"2026-05-06T05:52:18.680406Z","steps":["trace[1253408376] 'process raft request' (duration: 136.856048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:52:18.680463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.480296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:52:18.680531Z","caller":"traceutil/trace.go:171","msg":"trace[978429856] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:25083; }","duration":"140.574418ms","start":"2026-05-06T05:52:18.53993Z","end":"2026-05-06T05:52:18.680505Z","steps":["trace[978429856] 'agreement among raft nodes before linearized reading' (duration: 140.468856ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:52:19.041969Z","caller":"traceutil/trace.go:171","msg":"trace[1071120678] transaction","detail":"{read_only:false; response_revision:25086; number_of_response:1; }","duration":"109.876241ms","start":"2026-05-06T05:52:18.932066Z","end":"2026-05-06T05:52:19.041942Z","steps":["trace[1071120678] 'process raft request' (duration: 77.914897ms)","trace[1071120678] 'compare' (duration: 31.645117ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:53:52.1017Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000068f7.wal"} {"level":"info","ts":"2026-05-06T05:54:29.345336Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23487} {"level":"info","ts":"2026-05-06T05:54:29.482353Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23487,"took":"130.354149ms","hash":606059889,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":37228544,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-06T05:54:29.48242Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":606059889,"revision":23487,"compact-revision":20371} {"level":"info","ts":"2026-05-06T05:59:29.353114Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26399} {"level":"info","ts":"2026-05-06T05:59:29.492577Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26399,"took":"134.849415ms","hash":1093751668,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":32411648,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T05:59:29.492645Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1093751668,"revision":26399,"compact-revision":23487} {"level":"info","ts":"2026-05-06T05:59:53.489298Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:59:53.496054Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-06T05:59:53.496251Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-06T06:04:29.359916Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28907} {"level":"info","ts":"2026-05-06T06:04:29.490244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28907,"took":"126.211757ms","hash":1955743917,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":30334976,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T06:04:29.49029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1955743917,"revision":28907,"compact-revision":26399} {"level":"info","ts":"2026-05-06T06:09:29.366708Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31191} {"level":"info","ts":"2026-05-06T06:09:29.503099Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31191,"took":"132.770952ms","hash":3548199104,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":31297536,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T06:09:29.503154Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3548199104,"revision":31191,"compact-revision":28907} {"level":"info","ts":"2026-05-06T06:14:29.37504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33553} {"level":"info","ts":"2026-05-06T06:14:29.527398Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33553,"took":"145.284007ms","hash":4085880779,"current-db-size-bytes":58523648,"current-db-size":"58 MB","current-db-size-in-use-bytes":32927744,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-06T06:14:29.527478Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4085880779,"revision":33553,"compact-revision":31191} {"level":"info","ts":"2026-05-06T06:19:29.381149Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35783} {"level":"info","ts":"2026-05-06T06:19:29.531626Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35783,"took":"146.094795ms","hash":601018181,"current-db-size-bytes":59412480,"current-db-size":"59 MB","current-db-size-in-use-bytes":31019008,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T06:19:29.531694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":601018181,"revision":35783,"compact-revision":33553} {"level":"info","ts":"2026-05-06T06:21:05.659639Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5835264b86215850","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T06:21:05.662987Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-05-06T06:21:05.663084Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}